{"level":"warn","ts":"2026-02-01T18:54:00.391611Z","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-01T18:54:00.391815Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78: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.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-01T18:54:00.391936Z","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-01T18:54:00.392001Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-02-01T18:54:00.392105Z","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-01T18:54:00.392743Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-02-01T18:54:00.392989Z","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.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-01T18:54:00.39811Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.855526ms"} {"level":"info","ts":"2026-02-01T18:54:00.407974Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-02-01T18:54:00.408121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:54:00.408201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-02-01T18:54:00.408453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:54:00.408533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-02-01T18:54:00.408857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-02-01T18:54:00.424869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:54:00.44532Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:54:00.44721Z","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-01T18:54:00.449454Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:54:00.452737Z","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-01T18:54:00.453064Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:54:00.453132Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:54:00.453316Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:54:00.453478Z","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-01T18:54:00.453561Z","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-01T18:54:00.453654Z","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-01T18:54:00.454382Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-02-01T18:54:00.455043Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-02-01T18:54:00.455023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-02-01T18:54:00.455237Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-02-01T18:54:01.31259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:54:01.312631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:54:01.31265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-02-01T18:54:01.312671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:54:01.312676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-02-01T18:54:01.312683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-02-01T18:54:01.31269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-02-01T18:54:01.314222Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:54:01.31759Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:54:01.321187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:54:01.322271Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:54:01.322645Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:54:01.322697Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:54:01.322715Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:54:01.323995Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-02-01T18:54:01.329055Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:54:01.329213Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:54:01.331194Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:54:10.83524Z","caller":"traceutil/trace.go:171","msg":"trace[997588653] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"290.378581ms","start":"2026-02-01T18:54:10.544844Z","end":"2026-02-01T18:54:10.835223Z","steps":["trace[997588653] 'read index received' (duration: 290.256348ms)","trace[997588653] 'applied index is now lower than readState.Index' (duration: 121.603µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:10.835454Z","caller":"traceutil/trace.go:171","msg":"trace[1698669372] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"332.349867ms","start":"2026-02-01T18:54:10.503094Z","end":"2026-02-01T18:54:10.835444Z","steps":["trace[1698669372] 'process raft request' (duration: 332.050579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:10.835788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:10.50308Z","time spent":"332.396887ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:54:10.835957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.107479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-01T18:54:10.836009Z","caller":"traceutil/trace.go:171","msg":"trace[1481342330] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:301; }","duration":"291.17267ms","start":"2026-02-01T18:54:10.544813Z","end":"2026-02-01T18:54:10.835985Z","steps":["trace[1481342330] 'agreement among raft nodes before linearized reading' (duration: 291.089068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:10.836155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.162162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-01T18:54:10.836177Z","caller":"traceutil/trace.go:171","msg":"trace[214377451] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:301; }","duration":"255.207992ms","start":"2026-02-01T18:54:10.580964Z","end":"2026-02-01T18:54:10.836172Z","steps":["trace[214377451] 'agreement among raft nodes before linearized reading' (duration: 255.165201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:10.836467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.074149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-01T18:54:10.836503Z","caller":"traceutil/trace.go:171","msg":"trace[477552682] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:301; }","duration":"141.134451ms","start":"2026-02-01T18:54:10.69536Z","end":"2026-02-01T18:54:10.836495Z","steps":["trace[477552682] 'agreement among raft nodes before linearized reading' (duration: 141.07828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:10.836596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.534759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-01T18:54:10.836619Z","caller":"traceutil/trace.go:171","msg":"trace[210909072] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:301; }","duration":"190.59623ms","start":"2026-02-01T18:54:10.646016Z","end":"2026-02-01T18:54:10.836612Z","steps":["trace[210909072] 'agreement among raft nodes before linearized reading' (duration: 190.563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:10.836696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.776388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-01T18:54:10.836715Z","caller":"traceutil/trace.go:171","msg":"trace[438871997] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:301; }","duration":"240.82067ms","start":"2026-02-01T18:54:10.59589Z","end":"2026-02-01T18:54:10.83671Z","steps":["trace[438871997] 'agreement among raft nodes before linearized reading' (duration: 240.790989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:17.416961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637463622,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:54:17.918113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637463622,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:54:18.24886Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.85404195s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:54:18.249135Z","caller":"traceutil/trace.go:171","msg":"trace[135926255] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"1.332242502s","start":"2026-02-01T18:54:16.91686Z","end":"2026-02-01T18:54:18.249103Z","steps":["trace[135926255] 'read index received' (duration: 1.332100598s)","trace[135926255] 'applied index is now lower than readState.Index' (duration: 141.074µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:18.249335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332472138s","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-01T18:54:18.249395Z","caller":"traceutil/trace.go:171","msg":"trace[1380683496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"1.332621442s","start":"2026-02-01T18:54:16.91676Z","end":"2026-02-01T18:54:18.249381Z","steps":["trace[1380683496] 'agreement among raft nodes before linearized reading' (duration: 1.332491459s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.24943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:16.916745Z","time spent":"1.332677773s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:54:18.249516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214898857s","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-01T18:54:18.249554Z","caller":"traceutil/trace.go:171","msg":"trace[1891095514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"1.214978919s","start":"2026-02-01T18:54:17.034565Z","end":"2026-02-01T18:54:18.249544Z","steps":["trace[1891095514] 'agreement among raft nodes before linearized reading' (duration: 1.214911287s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.249582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:17.03455Z","time spent":"1.21502538s","remote":"127.0.0.1:44420","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-01T18:54:18.249604Z","caller":"traceutil/trace.go:171","msg":"trace[610678194] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"1.85485667s","start":"2026-02-01T18:54:16.394737Z","end":"2026-02-01T18:54:18.249593Z","steps":["trace[610678194] 'process raft request' (duration: 1.854252825s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.249782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.960074ms","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-01T18:54:18.249813Z","caller":"traceutil/trace.go:171","msg":"trace[1349898947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:372; }","duration":"386.024506ms","start":"2026-02-01T18:54:17.863779Z","end":"2026-02-01T18:54:18.249803Z","steps":["trace[1349898947] 'agreement among raft nodes before linearized reading' (duration: 385.975005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.249821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:16.394723Z","time spent":"1.854903802s","remote":"127.0.0.1:44328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5073,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:18.24984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:17.863761Z","time spent":"386.073187ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:54:18.251632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208299886s","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-01T18:54:18.251675Z","caller":"traceutil/trace.go:171","msg":"trace[469485467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:372; }","duration":"1.208360118s","start":"2026-02-01T18:54:17.043302Z","end":"2026-02-01T18:54:18.251662Z","steps":["trace[469485467] 'agreement among raft nodes before linearized reading' (duration: 1.206743056s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.251705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:17.043295Z","time spent":"1.208403829s","remote":"127.0.0.1:44420","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-01T18:54:18.251972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.694935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-01T18:54:18.252012Z","caller":"traceutil/trace.go:171","msg":"trace[1554047614] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:372; }","duration":"572.749227ms","start":"2026-02-01T18:54:17.679252Z","end":"2026-02-01T18:54:18.252001Z","steps":["trace[1554047614] 'agreement among raft nodes before linearized reading' (duration: 570.742275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.252041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:17.679215Z","time spent":"572.819819ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4057,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-01T18:54:18.655133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.400881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:18.655266Z","caller":"traceutil/trace.go:171","msg":"trace[46204844] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"396.546428ms","start":"2026-02-01T18:54:18.258671Z","end":"2026-02-01T18:54:18.655217Z","steps":["trace[46204844] 'process raft request' (duration: 279.844649ms)","trace[46204844] 'compare' (duration: 116.280218ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:18.655349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:18.258648Z","time spent":"396.662501ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:18.661785Z","caller":"traceutil/trace.go:171","msg":"trace[216509194] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"386.731763ms","start":"2026-02-01T18:54:18.275039Z","end":"2026-02-01T18:54:18.661771Z","steps":["trace[216509194] 'process raft request' (duration: 386.690912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.661924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:18.275034Z","time spent":"386.788785ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:18.661774Z","caller":"traceutil/trace.go:171","msg":"trace[1745752676] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"389.087554ms","start":"2026-02-01T18:54:18.272657Z","end":"2026-02-01T18:54:18.661744Z","steps":["trace[1745752676] 'process raft request' (duration: 388.89538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.663657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:18.272643Z","time spent":"390.971494ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:18.662155Z","caller":"traceutil/trace.go:171","msg":"trace[413311251] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"388.89558ms","start":"2026-02-01T18:54:18.27325Z","end":"2026-02-01T18:54:18.662145Z","steps":["trace[413311251] 'process raft request' (duration: 388.383837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.663814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:18.273243Z","time spent":"390.551383ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:18.662188Z","caller":"traceutil/trace.go:171","msg":"trace[1615403356] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"387.429622ms","start":"2026-02-01T18:54:18.274753Z","end":"2026-02-01T18:54:18.662182Z","steps":["trace[1615403356] 'process raft request' (duration: 386.928189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:18.663928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:18.274746Z","time spent":"389.164597ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:34.133877Z","caller":"traceutil/trace.go:171","msg":"trace[1403655153] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"317.184375ms","start":"2026-02-01T18:54:33.816679Z","end":"2026-02-01T18:54:34.133863Z","steps":["trace[1403655153] 'process raft request' (duration: 317.095212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:34.133976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:33.816664Z","time spent":"317.260527ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:38.927038Z","caller":"traceutil/trace.go:171","msg":"trace[2017647670] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"132.341283ms","start":"2026-02-01T18:54:38.794673Z","end":"2026-02-01T18:54:38.927014Z","steps":["trace[2017647670] 'read index received' (duration: 132.088096ms)","trace[2017647670] 'applied index is now lower than readState.Index' (duration: 252.307µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:38.927147Z","caller":"traceutil/trace.go:171","msg":"trace[794226793] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"160.960273ms","start":"2026-02-01T18:54:38.766178Z","end":"2026-02-01T18:54:38.927138Z","steps":["trace[794226793] 'process raft request' (duration: 160.666306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:38.92733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.016792ms","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-01T18:54:38.927384Z","caller":"traceutil/trace.go:171","msg":"trace[36219535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"118.107445ms","start":"2026-02-01T18:54:38.809262Z","end":"2026-02-01T18:54:38.92737Z","steps":["trace[36219535] 'agreement among raft nodes before linearized reading' (duration: 117.961111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:38.927489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.817185ms","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-01T18:54:38.927504Z","caller":"traceutil/trace.go:171","msg":"trace[472803441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:487; }","duration":"132.870846ms","start":"2026-02-01T18:54:38.794629Z","end":"2026-02-01T18:54:38.9275Z","steps":["trace[472803441] 'agreement among raft nodes before linearized reading' (duration: 132.823575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:38.927606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.056199ms","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-01T18:54:38.927622Z","caller":"traceutil/trace.go:171","msg":"trace[1001763783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"112.093919ms","start":"2026-02-01T18:54:38.815523Z","end":"2026-02-01T18:54:38.927617Z","steps":["trace[1001763783] 'agreement among raft nodes before linearized reading' (duration: 112.041458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.502703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637463858,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:54:41.708441Z","caller":"traceutil/trace.go:171","msg":"trace[1035796804] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"706.736741ms","start":"2026-02-01T18:54:41.001683Z","end":"2026-02-01T18:54:41.70842Z","steps":["trace[1035796804] 'read index received' (duration: 706.548947ms)","trace[1035796804] 'applied index is now lower than readState.Index' (duration: 180.804µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:41.708567Z","caller":"traceutil/trace.go:171","msg":"trace[378595174] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"773.188601ms","start":"2026-02-01T18:54:40.935372Z","end":"2026-02-01T18:54:41.708561Z","steps":["trace[378595174] 'process raft request' (duration: 772.890083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.708695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:40.935363Z","time spent":"773.226632ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:41.708814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.102195ms","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-01T18:54:41.708876Z","caller":"traceutil/trace.go:171","msg":"trace[421793330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"706.204428ms","start":"2026-02-01T18:54:41.002655Z","end":"2026-02-01T18:54:41.708859Z","steps":["trace[421793330] 'agreement among raft nodes before linearized reading' (duration: 706.017323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.708913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.002651Z","time spent":"706.253879ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:41.709102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.122176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:41.709129Z","caller":"traceutil/trace.go:171","msg":"trace[1822715727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"498.185907ms","start":"2026-02-01T18:54:41.210936Z","end":"2026-02-01T18:54:41.709122Z","steps":["trace[1822715727] 'agreement among raft nodes before linearized reading' (duration: 498.075515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.709137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.443574ms","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-01T18:54:41.709149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.210919Z","time spent":"498.226498ms","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:54:41.709168Z","caller":"traceutil/trace.go:171","msg":"trace[1023096389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:491; }","duration":"706.477835ms","start":"2026-02-01T18:54:41.00268Z","end":"2026-02-01T18:54:41.709158Z","steps":["trace[1023096389] 'agreement among raft nodes before linearized reading' (duration: 706.396343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.709199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.002676Z","time spent":"706.516426ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:54:41.709299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.614384ms","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-01T18:54:41.709319Z","caller":"traceutil/trace.go:171","msg":"trace[1038076359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"707.631514ms","start":"2026-02-01T18:54:41.00168Z","end":"2026-02-01T18:54:41.709312Z","steps":["trace[1038076359] 'agreement among raft nodes before linearized reading' (duration: 707.557432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.709335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.001644Z","time spent":"707.687556ms","remote":"127.0.0.1:44420","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-01T18:54:42.074904Z","caller":"traceutil/trace.go:171","msg":"trace[1619327446] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"360.822374ms","start":"2026-02-01T18:54:41.714055Z","end":"2026-02-01T18:54:42.074878Z","steps":["trace[1619327446] 'process raft request' (duration: 360.598038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.075074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.714043Z","time spent":"360.927866ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:42.075685Z","caller":"traceutil/trace.go:171","msg":"trace[792607351] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"358.733829ms","start":"2026-02-01T18:54:41.716933Z","end":"2026-02-01T18:54:42.075667Z","steps":["trace[792607351] 'process raft request' (duration: 358.705878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.075784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.716926Z","time spent":"358.804112ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:42.075963Z","caller":"traceutil/trace.go:171","msg":"trace[483091055] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"360.69674ms","start":"2026-02-01T18:54:41.715258Z","end":"2026-02-01T18:54:42.075955Z","steps":["trace[483091055] 'process raft request' (duration: 360.316341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.075996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.715249Z","time spent":"360.730061ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:44.860989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.270938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:44.861151Z","caller":"traceutil/trace.go:171","msg":"trace[2132367017] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"497.611842ms","start":"2026-02-01T18:54:44.363523Z","end":"2026-02-01T18:54:44.861135Z","steps":["trace[2132367017] 'process raft request' (duration: 497.55221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:44.861214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:44.363508Z","time spent":"497.675953ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:44.86125Z","caller":"traceutil/trace.go:171","msg":"trace[1038994075] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"598.157063ms","start":"2026-02-01T18:54:44.263056Z","end":"2026-02-01T18:54:44.861213Z","steps":["trace[1038994075] 'process raft request' (duration: 244.590557ms)","trace[1038994075] 'compare' (duration: 353.113104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:44.861401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:44.26304Z","time spent":"598.286245ms","remote":"127.0.0.1:44190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:45.221955Z","caller":"traceutil/trace.go:171","msg":"trace[986344657] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"112.954542ms","start":"2026-02-01T18:54:45.108981Z","end":"2026-02-01T18:54:45.221935Z","steps":["trace[986344657] 'process raft request' (duration: 112.779397ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:45.235344Z","caller":"traceutil/trace.go:171","msg":"trace[995517075] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"123.987747ms","start":"2026-02-01T18:54:45.111335Z","end":"2026-02-01T18:54:45.235323Z","steps":["trace[995517075] 'process raft request' (duration: 123.861934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:45.904036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.721305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:45.904458Z","caller":"traceutil/trace.go:171","msg":"trace[1503345778] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"133.537085ms","start":"2026-02-01T18:54:45.770905Z","end":"2026-02-01T18:54:45.904442Z","steps":["trace[1503345778] 'process raft request' (duration: 27.353998ms)","trace[1503345778] 'compare' (duration: 105.598922ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:47.490124Z","caller":"traceutil/trace.go:171","msg":"trace[339678558] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"107.85257ms","start":"2026-02-01T18:54:47.382254Z","end":"2026-02-01T18:54:47.490106Z","steps":["trace[339678558] 'process raft request' (duration: 107.756297ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:49.730173Z","caller":"traceutil/trace.go:171","msg":"trace[84977226] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"121.029591ms","start":"2026-02-01T18:54:49.609081Z","end":"2026-02-01T18:54:49.73011Z","steps":["trace[84977226] 'process raft request' (duration: 119.990054ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:50.227678Z","caller":"traceutil/trace.go:171","msg":"trace[1667782499] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"160.459891ms","start":"2026-02-01T18:54:50.066008Z","end":"2026-02-01T18:54:50.226468Z","steps":["trace[1667782499] 'process raft request' (duration: 158.025988ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:53.563582Z","caller":"traceutil/trace.go:171","msg":"trace[519258522] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"115.664221ms","start":"2026-02-01T18:54:53.447902Z","end":"2026-02-01T18:54:53.563566Z","steps":["trace[519258522] 'process raft request' (duration: 115.575339ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:55.375102Z","caller":"traceutil/trace.go:171","msg":"trace[2091804047] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"161.118487ms","start":"2026-02-01T18:54:55.213969Z","end":"2026-02-01T18:54:55.375087Z","steps":["trace[2091804047] 'read index received' (duration: 160.976354ms)","trace[2091804047] 'applied index is now lower than readState.Index' (duration: 141.643µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:55.375203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.217941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:55.375224Z","caller":"traceutil/trace.go:171","msg":"trace[1933911869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"161.276683ms","start":"2026-02-01T18:54:55.213942Z","end":"2026-02-01T18:54:55.375219Z","steps":["trace[1933911869] 'agreement among raft nodes before linearized reading' (duration: 161.20937ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:55.375442Z","caller":"traceutil/trace.go:171","msg":"trace[107361498] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"230.084653ms","start":"2026-02-01T18:54:55.145352Z","end":"2026-02-01T18:54:55.375437Z","steps":["trace[107361498] 'process raft request' (duration: 229.641681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.946152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.732655ms","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-01T18:55:02.946203Z","caller":"traceutil/trace.go:171","msg":"trace[1884824275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"580.812308ms","start":"2026-02-01T18:55:02.365379Z","end":"2026-02-01T18:55:02.946191Z","steps":["trace[1884824275] 'range keys from in-memory index tree' (duration: 580.622562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.946226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.365371Z","time spent":"580.850868ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:02.946301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.142096ms","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-01T18:55:02.946341Z","caller":"traceutil/trace.go:171","msg":"trace[715380706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"579.194057ms","start":"2026-02-01T18:55:02.367135Z","end":"2026-02-01T18:55:02.946329Z","steps":["trace[715380706] 'range keys from in-memory index tree' (duration: 579.055534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.946362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.582653ms","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-01T18:55:02.946373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.367129Z","time spent":"579.235138ms","remote":"127.0.0.1:44420","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-01T18:55:02.946377Z","caller":"traceutil/trace.go:171","msg":"trace[1647514933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"783.623854ms","start":"2026-02-01T18:55:02.162748Z","end":"2026-02-01T18:55:02.946372Z","steps":["trace[1647514933] 'range keys from in-memory index tree' (duration: 783.521821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.946403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.162731Z","time spent":"783.667955ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:02.946605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.640174ms","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-01T18:55:02.946619Z","caller":"traceutil/trace.go:171","msg":"trace[326969183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"605.681035ms","start":"2026-02-01T18:55:02.340933Z","end":"2026-02-01T18:55:02.946614Z","steps":["trace[326969183] 'range keys from in-memory index tree' (duration: 605.580122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.946631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.340915Z","time spent":"605.714247ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T18:55:03.406526Z","caller":"traceutil/trace.go:171","msg":"trace[1605531443] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"454.021928ms","start":"2026-02-01T18:55:02.952487Z","end":"2026-02-01T18:55:03.406509Z","steps":["trace[1605531443] 'process raft request' (duration: 453.881304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:03.406635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.95247Z","time spent":"454.10608ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:04.663917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.920295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:04.664063Z","caller":"traceutil/trace.go:171","msg":"trace[2016918057] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:708; }","duration":"1.454763572s","start":"2026-02-01T18:55:03.209288Z","end":"2026-02-01T18:55:04.664052Z","steps":["trace[2016918057] 'read index received' (duration: 197.169986ms)","trace[2016918057] 'applied index is now lower than readState.Index' (duration: 1.257592946s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:04.664215Z","caller":"traceutil/trace.go:171","msg":"trace[27684875] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.71144379s","start":"2026-02-01T18:55:02.952764Z","end":"2026-02-01T18:55:04.664207Z","steps":["trace[27684875] 'process raft request' (duration: 982.164316ms)","trace[27684875] 'compare' (duration: 728.658478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:04.664262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.952758Z","time spent":"1.711483571s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:04.664372Z","caller":"traceutil/trace.go:171","msg":"trace[1664185494] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.711494921s","start":"2026-02-01T18:55:02.952872Z","end":"2026-02-01T18:55:04.664367Z","steps":["trace[1664185494] 'process raft request' (duration: 1.711110901s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:04.664431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.95286Z","time spent":"1.711532442s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:04.664516Z","caller":"traceutil/trace.go:171","msg":"trace[1660372194] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.703777796s","start":"2026-02-01T18:55:02.960733Z","end":"2026-02-01T18:55:04.66451Z","steps":["trace[1660372194] 'process raft request' (duration: 1.703281894s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:04.664554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.96072Z","time spent":"1.703815447s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:04.664644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455354988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:04.664666Z","caller":"traceutil/trace.go:171","msg":"trace[1417183362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"1.455407518s","start":"2026-02-01T18:55:03.209252Z","end":"2026-02-01T18:55:04.66466Z","steps":["trace[1417183362] 'agreement among raft nodes before linearized reading' (duration: 1.455372957s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:04.664684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:03.209237Z","time spent":"1.45544346s","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:04.665053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.774364ms","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-01T18:55:04.665118Z","caller":"traceutil/trace.go:171","msg":"trace[761517916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"633.884487ms","start":"2026-02-01T18:55:04.031217Z","end":"2026-02-01T18:55:04.665102Z","steps":["trace[761517916] 'agreement among raft nodes before linearized reading' (duration: 633.686872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:04.665153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:04.031203Z","time spent":"633.941568ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:04.665238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.266734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:55:04.665261Z","caller":"traceutil/trace.go:171","msg":"trace[174925457] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:691; }","duration":"414.321896ms","start":"2026-02-01T18:55:04.250933Z","end":"2026-02-01T18:55:04.665255Z","steps":["trace[174925457] 'agreement among raft nodes before linearized reading' (duration: 414.235344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:04.665278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:04.250918Z","time spent":"414.356176ms","remote":"127.0.0.1:44190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-02-01T18:55:05.175973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637464231,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:05.426434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.709353ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-01T18:55:05.426484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:04.668104Z","time spent":"758.377398ms","remote":"127.0.0.1:44190","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-01T18:55:05.520376Z","caller":"traceutil/trace.go:171","msg":"trace[1584475469] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:712; }","duration":"844.616616ms","start":"2026-02-01T18:55:04.67572Z","end":"2026-02-01T18:55:05.520336Z","steps":["trace[1584475469] 'read index received' (duration: 551.961541ms)","trace[1584475469] 'applied index is now lower than readState.Index' (duration: 292.654295ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:05.520621Z","caller":"traceutil/trace.go:171","msg":"trace[1529969544] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"850.03102ms","start":"2026-02-01T18:55:04.670579Z","end":"2026-02-01T18:55:05.52061Z","steps":["trace[1529969544] 'process raft request' (duration: 849.645641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.520729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:04.670559Z","time spent":"850.097153ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:05.520925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.19933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:05.520963Z","caller":"traceutil/trace.go:171","msg":"trace[1476233560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"845.250361ms","start":"2026-02-01T18:55:04.675703Z","end":"2026-02-01T18:55:05.520953Z","steps":["trace[1476233560] 'agreement among raft nodes before linearized reading' (duration: 845.19712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.520994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:04.675698Z","time spent":"845.290282ms","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:55:05.982614Z","caller":"traceutil/trace.go:171","msg":"trace[1336960175] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:714; }","duration":"462.168193ms","start":"2026-02-01T18:55:05.52043Z","end":"2026-02-01T18:55:05.982599Z","steps":["trace[1336960175] 'read index received' (duration: 461.970748ms)","trace[1336960175] 'applied index is now lower than readState.Index' (duration: 194.795µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:05.982782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.931717ms","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-01T18:55:05.982805Z","caller":"traceutil/trace.go:171","msg":"trace[1554492566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"572.992799ms","start":"2026-02-01T18:55:05.409804Z","end":"2026-02-01T18:55:05.982797Z","steps":["trace[1554492566] 'agreement among raft nodes before linearized reading' (duration: 572.856916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.982827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:05.409791Z","time spent":"573.03103ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T18:55:05.983023Z","caller":"traceutil/trace.go:171","msg":"trace[69177308] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"567.968574ms","start":"2026-02-01T18:55:05.415047Z","end":"2026-02-01T18:55:05.983015Z","steps":["trace[69177308] 'process raft request' (duration: 567.397229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.983078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:05.415038Z","time spent":"568.002294ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:05.983159Z","caller":"traceutil/trace.go:171","msg":"trace[89481006] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"555.861317ms","start":"2026-02-01T18:55:05.427291Z","end":"2026-02-01T18:55:05.983153Z","steps":["trace[89481006] 'process raft request' (duration: 555.276062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.98319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:05.427284Z","time spent":"555.888988ms","remote":"127.0.0.1:44190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:05.98328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.940308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:05.983299Z","caller":"traceutil/trace.go:171","msg":"trace[1670358823] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:694; }","duration":"528.973729ms","start":"2026-02-01T18:55:05.454319Z","end":"2026-02-01T18:55:05.983292Z","steps":["trace[1670358823] 'agreement among raft nodes before linearized reading' (duration: 528.944979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.983331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:05.454281Z","time spent":"529.045501ms","remote":"127.0.0.1:44380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:55:06.835587Z","caller":"traceutil/trace.go:171","msg":"trace[1934784157] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"160.563131ms","start":"2026-02-01T18:55:06.675005Z","end":"2026-02-01T18:55:06.835568Z","steps":["trace[1934784157] 'process raft request' (duration: 160.456059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:07.737937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.737814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:07.738071Z","caller":"traceutil/trace.go:171","msg":"trace[1502331108] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"1.060623827s","start":"2026-02-01T18:55:06.677435Z","end":"2026-02-01T18:55:07.738059Z","steps":["trace[1502331108] 'process raft request' (duration: 1.060574795s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:07.738113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:06.677428Z","time spent":"1.060664657s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:07.73833Z","caller":"traceutil/trace.go:171","msg":"trace[793355159] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"1.062984006s","start":"2026-02-01T18:55:06.675336Z","end":"2026-02-01T18:55:07.73832Z","steps":["trace[793355159] 'process raft request' (duration: 636.792981ms)","trace[793355159] 'compare' (duration: 425.415735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:07.738366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:06.675323Z","time spent":"1.063024078s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:07.82347Z","caller":"traceutil/trace.go:171","msg":"trace[691729432] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:718; }","duration":"613.987734ms","start":"2026-02-01T18:55:07.209458Z","end":"2026-02-01T18:55:07.823446Z","steps":["trace[691729432] 'read index received' (duration: 102.680321ms)","trace[691729432] 'applied index is now lower than readState.Index' (duration: 511.306093ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:07.823589Z","caller":"traceutil/trace.go:171","msg":"trace[211060864] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"884.427889ms","start":"2026-02-01T18:55:06.939151Z","end":"2026-02-01T18:55:07.823579Z","steps":["trace[211060864] 'process raft request' (duration: 884.086611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:07.823692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:06.939133Z","time spent":"884.482441ms","remote":"127.0.0.1:44322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:07.823839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.337713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:07.823905Z","caller":"traceutil/trace.go:171","msg":"trace[1336189068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"614.470476ms","start":"2026-02-01T18:55:07.209417Z","end":"2026-02-01T18:55:07.823888Z","steps":["trace[1336189068] 'agreement among raft nodes before linearized reading' (duration: 614.334563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:07.823942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:07.209402Z","time spent":"614.531998ms","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:55:07.828975Z","caller":"traceutil/trace.go:171","msg":"trace[900461059] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"126.904222ms","start":"2026-02-01T18:55:07.702054Z","end":"2026-02-01T18:55:07.828958Z","steps":["trace[900461059] 'process raft request' (duration: 126.599974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:07.829256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.123099ms","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-01T18:55:07.829303Z","caller":"traceutil/trace.go:171","msg":"trace[744517461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:700; }","duration":"303.206761ms","start":"2026-02-01T18:55:07.526081Z","end":"2026-02-01T18:55:07.829288Z","steps":["trace[744517461] 'agreement among raft nodes before linearized reading' (duration: 303.080288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:07.829334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:07.526063Z","time spent":"303.263233ms","remote":"127.0.0.1:44420","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-01T18:55:22.295756Z","caller":"traceutil/trace.go:171","msg":"trace[928061690] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"208.985913ms","start":"2026-02-01T18:55:22.086756Z","end":"2026-02-01T18:55:22.295742Z","steps":["trace[928061690] 'process raft request' (duration: 208.85679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:24.397249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637464391,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:55:24.506064Z","caller":"traceutil/trace.go:171","msg":"trace[613695416] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"609.716126ms","start":"2026-02-01T18:55:23.896334Z","end":"2026-02-01T18:55:24.50605Z","steps":["trace[613695416] 'read index received' (duration: 609.590073ms)","trace[613695416] 'applied index is now lower than readState.Index' (duration: 125.593µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:24.506186Z","caller":"traceutil/trace.go:171","msg":"trace[195267114] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"703.620036ms","start":"2026-02-01T18:55:23.802561Z","end":"2026-02-01T18:55:24.506181Z","steps":["trace[195267114] 'process raft request' (duration: 703.396721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:24.506244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:23.802551Z","time spent":"703.648997ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:24.506371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.036504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T18:55:24.506385Z","caller":"traceutil/trace.go:171","msg":"trace[25809200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"610.076175ms","start":"2026-02-01T18:55:23.896305Z","end":"2026-02-01T18:55:24.506381Z","steps":["trace[25809200] 'agreement among raft nodes before linearized reading' (duration: 610.016694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:24.506398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:23.896292Z","time spent":"610.103636ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:24.506483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.624725ms","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-01T18:55:24.506495Z","caller":"traceutil/trace.go:171","msg":"trace[8866489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:747; }","duration":"508.667356ms","start":"2026-02-01T18:55:23.997824Z","end":"2026-02-01T18:55:24.506491Z","steps":["trace[8866489] 'agreement among raft nodes before linearized reading' (duration: 508.634035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:24.506506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:23.997807Z","time spent":"508.695986ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:24.506602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.907647ms","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-01T18:55:24.50662Z","caller":"traceutil/trace.go:171","msg":"trace[1354402496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"207.934417ms","start":"2026-02-01T18:55:24.298682Z","end":"2026-02-01T18:55:24.506616Z","steps":["trace[1354402496] 'agreement among raft nodes before linearized reading' (duration: 207.895116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:24.506688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.563013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:55:24.506699Z","caller":"traceutil/trace.go:171","msg":"trace[427062034] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:747; }","duration":"254.590624ms","start":"2026-02-01T18:55:24.252105Z","end":"2026-02-01T18:55:24.506696Z","steps":["trace[427062034] 'agreement among raft nodes before linearized reading' (duration: 254.559363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:24.506764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.725364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:24.506776Z","caller":"traceutil/trace.go:171","msg":"trace[1450858261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"295.774644ms","start":"2026-02-01T18:55:24.210997Z","end":"2026-02-01T18:55:24.506772Z","steps":["trace[1450858261] 'agreement among raft nodes before linearized reading' (duration: 295.756274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:25.943201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.212343ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-01T18:55:25.943288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:24.510732Z","time spent":"1.432545751s","remote":"127.0.0.1:44190","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-01T18:55:25.968641Z","caller":"traceutil/trace.go:171","msg":"trace[1113515171] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:773; }","duration":"953.78349ms","start":"2026-02-01T18:55:25.014836Z","end":"2026-02-01T18:55:25.96862Z","steps":["trace[1113515171] 'read index received' (duration: 220.201907ms)","trace[1113515171] 'applied index is now lower than readState.Index' (duration: 733.580553ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:25.968809Z","caller":"traceutil/trace.go:171","msg":"trace[1960299732] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.457736517s","start":"2026-02-01T18:55:24.511Z","end":"2026-02-01T18:55:25.968736Z","steps":["trace[1960299732] 'process raft request' (duration: 1.457382399s)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:25.96891Z","caller":"traceutil/trace.go:171","msg":"trace[1144577698] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.455064069s","start":"2026-02-01T18:55:24.513826Z","end":"2026-02-01T18:55:25.96889Z","steps":["trace[1144577698] 'process raft request' (duration: 1.454755752s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:25.968919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:24.510991Z","time spent":"1.45784749s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:25.969007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:24.513818Z","time spent":"1.455129222s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:25.969094Z","caller":"traceutil/trace.go:171","msg":"trace[508718199] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.458015555s","start":"2026-02-01T18:55:24.51107Z","end":"2026-02-01T18:55:25.969085Z","steps":["trace[508718199] 'process raft request' (duration: 1.457467051s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:25.969141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:24.511065Z","time spent":"1.458048326s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:25.969279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.453357ms","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-01T18:55:25.969305Z","caller":"traceutil/trace.go:171","msg":"trace[308728687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"954.504628ms","start":"2026-02-01T18:55:25.014791Z","end":"2026-02-01T18:55:25.969296Z","steps":["trace[308728687] 'agreement among raft nodes before linearized reading' (duration: 954.412406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:25.969337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:25.014753Z","time spent":"954.57672ms","remote":"127.0.0.1:44420","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-01T18:55:26.110233Z","caller":"traceutil/trace.go:171","msg":"trace[703317745] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"141.510732ms","start":"2026-02-01T18:55:25.968703Z","end":"2026-02-01T18:55:26.110213Z","steps":["trace[703317745] 'read index received' (duration: 77.052605ms)","trace[703317745] 'applied index is now lower than readState.Index' (duration: 64.457157ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:26.110261Z","caller":"traceutil/trace.go:171","msg":"trace[1588104897] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"165.345023ms","start":"2026-02-01T18:55:25.944885Z","end":"2026-02-01T18:55:26.11023Z","steps":["trace[1588104897] 'process raft request' (duration: 100.862576ms)","trace[1588104897] 'compare' (duration: 64.383885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:26.110358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.190513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:26.110382Z","caller":"traceutil/trace.go:171","msg":"trace[171271479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"899.265245ms","start":"2026-02-01T18:55:25.21111Z","end":"2026-02-01T18:55:26.110375Z","steps":["trace[171271479] 'agreement among raft nodes before linearized reading' (duration: 899.173192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:26.110404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:25.211091Z","time spent":"899.309125ms","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:26.110676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.995566ms","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-01T18:55:26.110703Z","caller":"traceutil/trace.go:171","msg":"trace[562504941] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:751; }","duration":"343.072848ms","start":"2026-02-01T18:55:25.767623Z","end":"2026-02-01T18:55:26.110695Z","steps":["trace[562504941] 'agreement among raft nodes before linearized reading' (duration: 342.970385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:26.110726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:25.767605Z","time spent":"343.113398ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":83,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:55:26.112492Z","caller":"traceutil/trace.go:171","msg":"trace[338486517] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"136.69582ms","start":"2026-02-01T18:55:25.975789Z","end":"2026-02-01T18:55:26.112485Z","steps":["trace[338486517] 'process raft request' (duration: 136.588238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:30.05801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.061538ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-01T18:55:30.058125Z","caller":"traceutil/trace.go:171","msg":"trace[415588161] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"547.926477ms","start":"2026-02-01T18:55:29.510181Z","end":"2026-02-01T18:55:30.058108Z","steps":["trace[415588161] 'read index received' (duration: 114.698625ms)","trace[415588161] 'applied index is now lower than readState.Index' (duration: 433.226492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:30.058493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.296096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:30.058531Z","caller":"traceutil/trace.go:171","msg":"trace[1940826037] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:760; }","duration":"548.380998ms","start":"2026-02-01T18:55:29.510138Z","end":"2026-02-01T18:55:30.058519Z","steps":["trace[1940826037] 'agreement among raft nodes before linearized reading' (duration: 548.308106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:30.058566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:29.510114Z","time spent":"548.441019ms","remote":"127.0.0.1:44286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:55:30.238882Z","caller":"traceutil/trace.go:171","msg":"trace[1328984364] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"175.446198ms","start":"2026-02-01T18:55:30.063418Z","end":"2026-02-01T18:55:30.238865Z","steps":["trace[1328984364] 'process raft request' (duration: 175.345216ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:30.465414Z","caller":"traceutil/trace.go:171","msg":"trace[948484768] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:789; }","duration":"344.019802ms","start":"2026-02-01T18:55:30.121362Z","end":"2026-02-01T18:55:30.465382Z","steps":["trace[948484768] 'read index received' (duration: 117.878615ms)","trace[948484768] 'applied index is now lower than readState.Index' (duration: 226.139927ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:30.465463Z","caller":"traceutil/trace.go:171","msg":"trace[44128212] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"399.753519ms","start":"2026-02-01T18:55:30.065677Z","end":"2026-02-01T18:55:30.465431Z","steps":["trace[44128212] 'process raft request' (duration: 381.090788ms)","trace[44128212] 'compare' (duration: 18.457015ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:30.465642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.263037ms","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-01T18:55:30.465679Z","caller":"traceutil/trace.go:171","msg":"trace[1799525048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"344.319229ms","start":"2026-02-01T18:55:30.121349Z","end":"2026-02-01T18:55:30.465668Z","steps":["trace[1799525048] 'agreement among raft nodes before linearized reading' (duration: 344.131014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:30.465716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:30.121301Z","time spent":"344.405122ms","remote":"127.0.0.1:44420","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-01T18:55:30.465818Z","caller":"traceutil/trace.go:171","msg":"trace[564831701] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"399.105692ms","start":"2026-02-01T18:55:30.066703Z","end":"2026-02-01T18:55:30.465809Z","steps":["trace[564831701] 'process raft request' (duration: 398.63436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:30.465893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:30.066686Z","time spent":"399.151534ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:30.465637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:30.06567Z","time spent":"399.870611ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:42.702194Z","caller":"traceutil/trace.go:171","msg":"trace[173559033] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"132.848993ms","start":"2026-02-01T18:55:42.56933Z","end":"2026-02-01T18:55:42.702179Z","steps":["trace[173559033] 'process raft request' (duration: 132.72537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:43.861691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126103288s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:43.861872Z","caller":"traceutil/trace.go:171","msg":"trace[582745274] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:878; }","duration":"654.396235ms","start":"2026-02-01T18:55:43.207461Z","end":"2026-02-01T18:55:43.861858Z","steps":["trace[582745274] 'read index received' (duration: 400.109048ms)","trace[582745274] 'applied index is now lower than readState.Index' (duration: 254.286347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:43.861949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.484137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:43.861973Z","caller":"traceutil/trace.go:171","msg":"trace[1488577462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"654.530298ms","start":"2026-02-01T18:55:43.207434Z","end":"2026-02-01T18:55:43.861965Z","steps":["trace[1488577462] 'agreement among raft nodes before linearized reading' (duration: 654.466546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:43.862003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:43.20742Z","time spent":"654.57501ms","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:55:43.86225Z","caller":"traceutil/trace.go:171","msg":"trace[910628226] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"1.155352118s","start":"2026-02-01T18:55:42.706886Z","end":"2026-02-01T18:55:43.862238Z","steps":["trace[910628226] 'process raft request' (duration: 28.632944ms)","trace[910628226] 'compare' (duration: 1.125571184s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:43.862304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:42.706871Z","time spent":"1.155404149s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:43.862416Z","caller":"traceutil/trace.go:171","msg":"trace[2092156342] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"1.15510545s","start":"2026-02-01T18:55:42.707302Z","end":"2026-02-01T18:55:43.862408Z","steps":["trace[2092156342] 'process raft request' (duration: 1.154479664s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:43.862461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:42.707296Z","time spent":"1.155140271s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:43.862659Z","caller":"traceutil/trace.go:171","msg":"trace[1355949331] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"1.152443893s","start":"2026-02-01T18:55:42.710205Z","end":"2026-02-01T18:55:43.862649Z","steps":["trace[1355949331] 'process raft request' (duration: 1.151622092s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:43.862716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:42.710178Z","time spent":"1.152507475s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:45.507447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200028903s","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-01T18:55:45.507499Z","caller":"traceutil/trace.go:171","msg":"trace[173129411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:849; }","duration":"1.200119196s","start":"2026-02-01T18:55:44.307367Z","end":"2026-02-01T18:55:45.507486Z","steps":["trace[173129411] 'range keys from in-memory index tree' (duration: 1.19990873s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.507524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:44.307341Z","time spent":"1.200177877s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:55:45.507613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028359632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:45.50768Z","caller":"traceutil/trace.go:171","msg":"trace[153575637] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:849; }","duration":"1.028456314s","start":"2026-02-01T18:55:44.479205Z","end":"2026-02-01T18:55:45.507661Z","steps":["trace[153575637] 'count revisions from in-memory index tree' (duration: 1.02830593s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.507716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:44.47919Z","time spent":"1.028517295s","remote":"127.0.0.1:44406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:45.507847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254905268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:55:45.507864Z","caller":"traceutil/trace.go:171","msg":"trace[121004831] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:849; }","duration":"1.2549454s","start":"2026-02-01T18:55:44.252914Z","end":"2026-02-01T18:55:45.507859Z","steps":["trace[121004831] 'range keys from in-memory index tree' (duration: 1.254780336s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.507877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:44.252899Z","time spent":"1.25497475s","remote":"127.0.0.1:44190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-02-01T18:55:45.508038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.416584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-01T18:55:45.508059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.697441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:45.508062Z","caller":"traceutil/trace.go:171","msg":"trace[1293796977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:849; }","duration":"801.480406ms","start":"2026-02-01T18:55:44.706575Z","end":"2026-02-01T18:55:45.508055Z","steps":["trace[1293796977] 'range keys from in-memory index tree' (duration: 801.280391ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:45.508078Z","caller":"traceutil/trace.go:171","msg":"trace[499977353] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:849; }","duration":"163.717451ms","start":"2026-02-01T18:55:45.344355Z","end":"2026-02-01T18:55:45.508072Z","steps":["trace[499977353] 'count revisions from in-memory index tree' (duration: 163.66292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.508082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:44.706558Z","time spent":"801.519777ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:45.508153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.005514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:45.508165Z","caller":"traceutil/trace.go:171","msg":"trace[542854259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"759.045915ms","start":"2026-02-01T18:55:44.749116Z","end":"2026-02-01T18:55:45.508162Z","steps":["trace[542854259] 'range keys from in-memory index tree' (duration: 758.957203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.508177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:44.7491Z","time spent":"759.074636ms","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:45.508266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.813906ms","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-01T18:55:45.50828Z","caller":"traceutil/trace.go:171","msg":"trace[151091673] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:849; }","duration":"662.852877ms","start":"2026-02-01T18:55:44.845421Z","end":"2026-02-01T18:55:45.508274Z","steps":["trace[151091673] 'count revisions from in-memory index tree' (duration: 662.755905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.508303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:44.845404Z","time spent":"662.894038ms","remote":"127.0.0.1:44488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:45.50849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.79682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:45.508516Z","caller":"traceutil/trace.go:171","msg":"trace[1871339281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"300.837242ms","start":"2026-02-01T18:55:45.20767Z","end":"2026-02-01T18:55:45.508508Z","steps":["trace[1871339281] 'range keys from in-memory index tree' (duration: 300.753449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.508537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:45.207656Z","time spent":"300.872612ms","remote":"127.0.0.1:44154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:45.85593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.581054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:45.856127Z","caller":"traceutil/trace.go:171","msg":"trace[748531260] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"297.329094ms","start":"2026-02-01T18:55:45.558787Z","end":"2026-02-01T18:55:45.856116Z","steps":["trace[748531260] 'process raft request' (duration: 124.514113ms)","trace[748531260] 'compare' (duration: 172.478672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:46.150181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.530976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:46.150273Z","caller":"traceutil/trace.go:171","msg":"trace[435811600] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"272.423726ms","start":"2026-02-01T18:55:45.877834Z","end":"2026-02-01T18:55:46.150258Z","steps":["trace[435811600] 'read index received' (duration: 108.719384ms)","trace[435811600] 'applied index is now lower than readState.Index' (duration: 163.703272ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:46.150373Z","caller":"traceutil/trace.go:171","msg":"trace[1901336595] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"272.654822ms","start":"2026-02-01T18:55:45.877706Z","end":"2026-02-01T18:55:46.15036Z","steps":["trace[1901336595] 'process raft request' (duration: 108.866278ms)","trace[1901336595] 'compare' (duration: 163.426804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:46.150547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.662882ms","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-01T18:55:46.150595Z","caller":"traceutil/trace.go:171","msg":"trace[2070953014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:853; }","duration":"272.726213ms","start":"2026-02-01T18:55:45.877857Z","end":"2026-02-01T18:55:46.150583Z","steps":["trace[2070953014] 'agreement among raft nodes before linearized reading' (duration: 272.59216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:46.150677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.837956ms","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-01T18:55:46.150702Z","caller":"traceutil/trace.go:171","msg":"trace[1153595737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:853; }","duration":"272.874687ms","start":"2026-02-01T18:55:45.87782Z","end":"2026-02-01T18:55:46.150694Z","steps":["trace[1153595737] 'agreement among raft nodes before linearized reading' (duration: 272.780525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:46.150745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.031407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:46.150765Z","caller":"traceutil/trace.go:171","msg":"trace[727924316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"194.076619ms","start":"2026-02-01T18:55:45.956682Z","end":"2026-02-01T18:55:46.150759Z","steps":["trace[727924316] 'agreement among raft nodes before linearized reading' (duration: 194.045338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:46.709772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637464645,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:55:46.849818Z","caller":"traceutil/trace.go:171","msg":"trace[2056419328] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"693.862209ms","start":"2026-02-01T18:55:46.155931Z","end":"2026-02-01T18:55:46.849793Z","steps":["trace[2056419328] 'process raft request' (duration: 648.467254ms)","trace[2056419328] 'compare' (duration: 45.053657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:46.849936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:46.15591Z","time spent":"693.965632ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:47.784702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.481563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:47.784833Z","caller":"traceutil/trace.go:171","msg":"trace[276366480] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:886; }","duration":"1.57511556s","start":"2026-02-01T18:55:46.209704Z","end":"2026-02-01T18:55:47.78482Z","steps":["trace[276366480] 'read index received' (duration: 594.702248ms)","trace[276366480] 'applied index is now lower than readState.Index' (duration: 980.412622ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:47.785008Z","caller":"traceutil/trace.go:171","msg":"trace[1557507046] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"1.62905065s","start":"2026-02-01T18:55:46.15595Z","end":"2026-02-01T18:55:47.785Z","steps":["trace[1557507046] 'process raft request' (duration: 1.246188688s)","trace[1557507046] 'compare' (duration: 382.213616ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:47.785054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:46.155931Z","time spent":"1.629102061s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:47.785173Z","caller":"traceutil/trace.go:171","msg":"trace[1077519497] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"1.150117294s","start":"2026-02-01T18:55:46.635049Z","end":"2026-02-01T18:55:47.785166Z","steps":["trace[1077519497] 'process raft request' (duration: 1.149719324s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.78521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:46.635032Z","time spent":"1.150160175s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:47.785345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.575657673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:47.785373Z","caller":"traceutil/trace.go:171","msg":"trace[898213455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"1.575716144s","start":"2026-02-01T18:55:46.20965Z","end":"2026-02-01T18:55:47.785366Z","steps":["trace[898213455] 'agreement among raft nodes before linearized reading' (duration: 1.575670743s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.785392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:46.209635Z","time spent":"1.575753016s","remote":"127.0.0.1:44154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:47.78553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149340705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:47.785558Z","caller":"traceutil/trace.go:171","msg":"trace[1871213536] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:856; }","duration":"1.149390816s","start":"2026-02-01T18:55:46.636161Z","end":"2026-02-01T18:55:47.785552Z","steps":["trace[1871213536] 'agreement among raft nodes before linearized reading' (duration: 1.149338064s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.785577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:46.636153Z","time spent":"1.149418636s","remote":"127.0.0.1:44436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:47.785854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.176487ms","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-01T18:55:47.785881Z","caller":"traceutil/trace.go:171","msg":"trace[279433639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:856; }","duration":"224.211548ms","start":"2026-02-01T18:55:47.561663Z","end":"2026-02-01T18:55:47.785875Z","steps":["trace[279433639] 'agreement among raft nodes before linearized reading' (duration: 224.098855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.785994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.300536ms","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-01T18:55:47.786016Z","caller":"traceutil/trace.go:171","msg":"trace[1225108816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"225.333356ms","start":"2026-02-01T18:55:47.560677Z","end":"2026-02-01T18:55:47.786011Z","steps":["trace[1225108816] 'agreement among raft nodes before linearized reading' (duration: 225.280315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.786105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.679811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:47.786128Z","caller":"traceutil/trace.go:171","msg":"trace[183594432] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:856; }","duration":"234.727763ms","start":"2026-02-01T18:55:47.551395Z","end":"2026-02-01T18:55:47.786123Z","steps":["trace[183594432] 'agreement among raft nodes before linearized reading' (duration: 234.687532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:48.26896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.738448ms","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-01T18:55:48.269111Z","caller":"traceutil/trace.go:171","msg":"trace[497511600] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:856; }","duration":"254.915592ms","start":"2026-02-01T18:55:48.014171Z","end":"2026-02-01T18:55:48.269086Z","steps":["trace[497511600] 'range keys from in-memory index tree' (duration: 254.719418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:48.269392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.494807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:48.269541Z","caller":"traceutil/trace.go:171","msg":"trace[482780574] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"467.533459ms","start":"2026-02-01T18:55:47.80199Z","end":"2026-02-01T18:55:48.269523Z","steps":["trace[482780574] 'process raft request' (duration: 467.473938ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:48.269566Z","caller":"traceutil/trace.go:171","msg":"trace[493370481] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:889; }","duration":"468.307238ms","start":"2026-02-01T18:55:47.801243Z","end":"2026-02-01T18:55:48.26955Z","steps":["trace[493370481] 'read index received' (duration: 132.575875ms)","trace[493370481] 'applied index is now lower than readState.Index' (duration: 335.729983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:48.269609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.801982Z","time spent":"467.591141ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:48.269654Z","caller":"traceutil/trace.go:171","msg":"trace[1515509899] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"468.823061ms","start":"2026-02-01T18:55:47.800819Z","end":"2026-02-01T18:55:48.269642Z","steps":["trace[1515509899] 'process raft request' (duration: 132.990106ms)","trace[1515509899] 'compare' (duration: 335.326563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:48.269719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.800771Z","time spent":"468.906223ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:48.269845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.491276ms","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-01T18:55:48.269916Z","caller":"traceutil/trace.go:171","msg":"trace[996084332] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:858; }","duration":"272.530107ms","start":"2026-02-01T18:55:47.997342Z","end":"2026-02-01T18:55:48.269872Z","steps":["trace[996084332] 'agreement among raft nodes before linearized reading' (duration: 272.476486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:48.27012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.800047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T18:55:48.270155Z","caller":"traceutil/trace.go:171","msg":"trace[240107069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:858; }","duration":"114.834888ms","start":"2026-02-01T18:55:48.155309Z","end":"2026-02-01T18:55:48.270144Z","steps":["trace[240107069] 'agreement among raft nodes before linearized reading' (duration: 114.719755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:48.270188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.941004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:48.27022Z","caller":"traceutil/trace.go:171","msg":"trace[1915572248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:858; }","duration":"468.989695ms","start":"2026-02-01T18:55:47.801219Z","end":"2026-02-01T18:55:48.270209Z","steps":["trace[1915572248] 'agreement among raft nodes before linearized reading' (duration: 468.943824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:48.270247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.801212Z","time spent":"469.028936ms","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:49.147298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.625134ms","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-01T18:55:49.147372Z","caller":"traceutil/trace.go:171","msg":"trace[1088952053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:859; }","duration":"269.741327ms","start":"2026-02-01T18:55:48.877614Z","end":"2026-02-01T18:55:49.147355Z","steps":["trace[1088952053] 'range keys from in-memory index tree' (duration: 269.45969ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:49.534099Z","caller":"traceutil/trace.go:171","msg":"trace[1925152810] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"567.360178ms","start":"2026-02-01T18:55:48.966699Z","end":"2026-02-01T18:55:49.534059Z","steps":["trace[1925152810] 'process raft request' (duration: 567.149083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:49.53424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:48.966668Z","time spent":"567.497201ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:50.549704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.478199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:50.549884Z","caller":"traceutil/trace.go:171","msg":"trace[1951840111] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"1.399688713s","start":"2026-02-01T18:55:49.150181Z","end":"2026-02-01T18:55:50.54987Z","steps":["trace[1951840111] 'process raft request' (duration: 965.991478ms)","trace[1951840111] 'compare' (duration: 433.248254ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:50.549926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.150166Z","time spent":"1.399740534s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:50.549995Z","caller":"traceutil/trace.go:171","msg":"trace[1282460005] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:893; }","duration":"1.339899123s","start":"2026-02-01T18:55:49.21009Z","end":"2026-02-01T18:55:50.549989Z","steps":["trace[1282460005] 'read index received' (duration: 324.562401ms)","trace[1282460005] 'applied index is now lower than readState.Index' (duration: 1.015336112s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:50.625007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.256713ms","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-01T18:55:50.625057Z","caller":"traceutil/trace.go:171","msg":"trace[433982570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:861; }","duration":"830.348495ms","start":"2026-02-01T18:55:49.794695Z","end":"2026-02-01T18:55:50.625044Z","steps":["trace[433982570] 'agreement among raft nodes before linearized reading' (duration: 830.205192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.625084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.794677Z","time spent":"830.402646ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:50.625266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415175563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:50.625284Z","caller":"traceutil/trace.go:171","msg":"trace[64082090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"1.415191353s","start":"2026-02-01T18:55:49.210086Z","end":"2026-02-01T18:55:50.625277Z","steps":["trace[64082090] 'agreement among raft nodes before linearized reading' (duration: 1.339940034s)","trace[64082090] 'range keys from in-memory index tree' (duration: 75.227668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:50.625299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.210053Z","time spent":"1.415242144s","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:50.625408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.986078ms","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-01T18:55:50.625425Z","caller":"traceutil/trace.go:171","msg":"trace[1164807785] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:861; }","duration":"185.033589ms","start":"2026-02-01T18:55:50.440386Z","end":"2026-02-01T18:55:50.62542Z","steps":["trace[1164807785] 'agreement among raft nodes before linearized reading' (duration: 185.002169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.625539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.423245ms","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-01T18:55:50.625574Z","caller":"traceutil/trace.go:171","msg":"trace[1937951486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:861; }","duration":"335.469886ms","start":"2026-02-01T18:55:50.290097Z","end":"2026-02-01T18:55:50.625567Z","steps":["trace[1937951486] 'agreement among raft nodes before linearized reading' (duration: 335.404034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.625589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.29009Z","time spent":"335.494767ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:50.625716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.219793ms","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-01T18:55:50.625731Z","caller":"traceutil/trace.go:171","msg":"trace[2029199757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:861; }","duration":"349.254374ms","start":"2026-02-01T18:55:50.276472Z","end":"2026-02-01T18:55:50.625727Z","steps":["trace[2029199757] 'agreement among raft nodes before linearized reading' (duration: 349.207223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.625745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.276463Z","time spent":"349.278974ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:55:50.625838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.732721ms","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-01T18:55:50.625855Z","caller":"traceutil/trace.go:171","msg":"trace[1612890990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:861; }","duration":"350.782542ms","start":"2026-02-01T18:55:50.275067Z","end":"2026-02-01T18:55:50.625849Z","steps":["trace[1612890990] 'agreement among raft nodes before linearized reading' (duration: 350.736381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.625868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.275049Z","time spent":"350.815893ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T18:55:50.813223Z","caller":"traceutil/trace.go:171","msg":"trace[453227357] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"178.133696ms","start":"2026-02-01T18:55:50.635074Z","end":"2026-02-01T18:55:50.813208Z","steps":["trace[453227357] 'read index received' (duration: 41.033626ms)","trace[453227357] 'applied index is now lower than readState.Index' (duration: 137.09937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:50.813323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.234538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:50.813343Z","caller":"traceutil/trace.go:171","msg":"trace[2122788854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"178.279289ms","start":"2026-02-01T18:55:50.635057Z","end":"2026-02-01T18:55:50.813337Z","steps":["trace[2122788854] 'agreement among raft nodes before linearized reading' (duration: 178.215017ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:50.81353Z","caller":"traceutil/trace.go:171","msg":"trace[1502856137] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"182.872875ms","start":"2026-02-01T18:55:50.630649Z","end":"2026-02-01T18:55:50.813521Z","steps":["trace[1502856137] 'process raft request' (duration: 162.933051ms)","trace[1502856137] 'compare' (duration: 19.303448ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:50.81361Z","caller":"traceutil/trace.go:171","msg":"trace[1506753059] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"178.577677ms","start":"2026-02-01T18:55:50.635027Z","end":"2026-02-01T18:55:50.813605Z","steps":["trace[1506753059] 'process raft request' (duration: 178.131556ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:50.813678Z","caller":"traceutil/trace.go:171","msg":"trace[428046694] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"178.387551ms","start":"2026-02-01T18:55:50.635286Z","end":"2026-02-01T18:55:50.813673Z","steps":["trace[428046694] 'process raft request' (duration: 177.900659ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:53.136345Z","caller":"traceutil/trace.go:171","msg":"trace[1676598006] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"319.205525ms","start":"2026-02-01T18:55:52.817124Z","end":"2026-02-01T18:55:53.13633Z","steps":["trace[1676598006] 'read index received' (duration: 319.079252ms)","trace[1676598006] 'applied index is now lower than readState.Index' (duration: 125.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:53.136512Z","caller":"traceutil/trace.go:171","msg":"trace[1894209030] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"456.050868ms","start":"2026-02-01T18:55:52.680455Z","end":"2026-02-01T18:55:53.136506Z","steps":["trace[1894209030] 'process raft request' (duration: 455.795141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:53.136582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:52.680327Z","time spent":"456.198081ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:53.136728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.602625ms","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-01T18:55:53.136743Z","caller":"traceutil/trace.go:171","msg":"trace[822867749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:867; }","duration":"319.637246ms","start":"2026-02-01T18:55:52.817101Z","end":"2026-02-01T18:55:53.136739Z","steps":["trace[822867749] 'agreement among raft nodes before linearized reading' (duration: 319.571324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:53.136758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:52.817086Z","time spent":"319.668867ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:55:53.136846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.682288ms","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-01T18:55:53.136859Z","caller":"traceutil/trace.go:171","msg":"trace[1468712316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:867; }","duration":"319.703378ms","start":"2026-02-01T18:55:52.817152Z","end":"2026-02-01T18:55:53.136856Z","steps":["trace[1468712316] 'agreement among raft nodes before linearized reading' (duration: 319.666328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:53.13687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:52.81715Z","time spent":"319.717639ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:53.136965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.788541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T18:55:53.136979Z","caller":"traceutil/trace.go:171","msg":"trace[1740151293] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:867; }","duration":"319.810641ms","start":"2026-02-01T18:55:52.817165Z","end":"2026-02-01T18:55:53.136975Z","steps":["trace[1740151293] 'agreement among raft nodes before linearized reading' (duration: 319.773121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:53.13699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:52.817163Z","time spent":"319.824452ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:53.707474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637464678,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:55:53.755033Z","caller":"traceutil/trace.go:171","msg":"trace[1893571382] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:901; }","duration":"547.584259ms","start":"2026-02-01T18:55:53.207433Z","end":"2026-02-01T18:55:53.755017Z","steps":["trace[1893571382] 'read index received' (duration: 514.548615ms)","trace[1893571382] 'applied index is now lower than readState.Index' (duration: 33.035024ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:53.755165Z","caller":"traceutil/trace.go:171","msg":"trace[2067163222] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"614.637581ms","start":"2026-02-01T18:55:53.140521Z","end":"2026-02-01T18:55:53.755159Z","steps":["trace[2067163222] 'process raft request' (duration: 581.449153ms)","trace[2067163222] 'compare' (duration: 32.688855ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:53.755228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:53.14051Z","time spent":"614.670091ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:53.75532Z","caller":"traceutil/trace.go:171","msg":"trace[1061913895] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"614.692543ms","start":"2026-02-01T18:55:53.140623Z","end":"2026-02-01T18:55:53.755316Z","steps":["trace[1061913895] 'process raft request' (duration: 614.352574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:53.755374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:53.140618Z","time spent":"614.711433ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:53.755434Z","caller":"traceutil/trace.go:171","msg":"trace[403411818] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"613.553894ms","start":"2026-02-01T18:55:53.141876Z","end":"2026-02-01T18:55:53.75543Z","steps":["trace[403411818] 'process raft request' (duration: 613.122653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:53.755456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:53.14187Z","time spent":"613.573495ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:53.755518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.084952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:53.755534Z","caller":"traceutil/trace.go:171","msg":"trace[80095868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"548.121632ms","start":"2026-02-01T18:55:53.207408Z","end":"2026-02-01T18:55:53.755529Z","steps":["trace[80095868] 'agreement among raft nodes before linearized reading' (duration: 548.096001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:53.755548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:53.207395Z","time spent":"548.150642ms","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:53.755632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.287812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:53.755644Z","caller":"traceutil/trace.go:171","msg":"trace[1189594608] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:870; }","duration":"209.325302ms","start":"2026-02-01T18:55:53.546315Z","end":"2026-02-01T18:55:53.75564Z","steps":["trace[1189594608] 'agreement among raft nodes before linearized reading' (duration: 209.300871ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:00.191266Z","caller":"traceutil/trace.go:171","msg":"trace[2119150079] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"389.864978ms","start":"2026-02-01T18:55:59.801384Z","end":"2026-02-01T18:56:00.191249Z","steps":["trace[2119150079] 'process raft request' (duration: 389.784876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:00.191366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:59.801371Z","time spent":"389.94791ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:00.191447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.952044ms","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-01T18:56:00.19148Z","caller":"traceutil/trace.go:171","msg":"trace[461942675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:885; }","duration":"381.015085ms","start":"2026-02-01T18:55:59.810457Z","end":"2026-02-01T18:56:00.191472Z","steps":["trace[461942675] 'agreement among raft nodes before linearized reading' (duration: 380.858011ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:00.191276Z","caller":"traceutil/trace.go:171","msg":"trace[1978420611] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"380.7765ms","start":"2026-02-01T18:55:59.810479Z","end":"2026-02-01T18:56:00.191256Z","steps":["trace[1978420611] 'read index received' (duration: 380.756319ms)","trace[1978420611] 'applied index is now lower than readState.Index' (duration: 18.141µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:00.191499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:59.810416Z","time spent":"381.078707ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T18:56:00.238124Z","caller":"traceutil/trace.go:171","msg":"trace[194774299] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"388.743479ms","start":"2026-02-01T18:55:59.849368Z","end":"2026-02-01T18:56:00.238111Z","steps":["trace[194774299] 'process raft request' (duration: 388.484633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:00.238212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:59.849351Z","time spent":"388.816132ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:00.238308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.073227ms","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-01T18:56:00.238324Z","caller":"traceutil/trace.go:171","msg":"trace[1162228946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"427.096728ms","start":"2026-02-01T18:55:59.811222Z","end":"2026-02-01T18:56:00.238319Z","steps":["trace[1162228946] 'agreement among raft nodes before linearized reading' (duration: 427.032686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:00.238336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:59.811217Z","time spent":"427.116489ms","remote":"127.0.0.1:44420","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-01T18:56:01.507802Z","caller":"traceutil/trace.go:171","msg":"trace[2074469129] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"229.959603ms","start":"2026-02-01T18:56:01.277825Z","end":"2026-02-01T18:56:01.507784Z","steps":["trace[2074469129] 'process raft request' (duration: 229.85226ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:02.407587Z","caller":"traceutil/trace.go:171","msg":"trace[989941715] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"155.096297ms","start":"2026-02-01T18:56:02.252476Z","end":"2026-02-01T18:56:02.407572Z","steps":["trace[989941715] 'read index received' (duration: 154.954113ms)","trace[989941715] 'applied index is now lower than readState.Index' (duration: 141.554µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:02.407661Z","caller":"traceutil/trace.go:171","msg":"trace[1245462183] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"157.453555ms","start":"2026-02-01T18:56:02.250202Z","end":"2026-02-01T18:56:02.407656Z","steps":["trace[1245462183] 'process raft request' (duration: 157.26559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:02.407871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.81422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T18:56:02.40797Z","caller":"traceutil/trace.go:171","msg":"trace[1555101081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"139.961775ms","start":"2026-02-01T18:56:02.267993Z","end":"2026-02-01T18:56:02.407954Z","steps":["trace[1555101081] 'agreement among raft nodes before linearized reading' (duration: 139.76835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:02.408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.506477ms","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-01T18:56:02.408021Z","caller":"traceutil/trace.go:171","msg":"trace[934748306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"155.548898ms","start":"2026-02-01T18:56:02.252466Z","end":"2026-02-01T18:56:02.408015Z","steps":["trace[934748306] 'agreement among raft nodes before linearized reading' (duration: 155.417244ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:02.899446Z","caller":"traceutil/trace.go:171","msg":"trace[440266119] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"289.938741ms","start":"2026-02-01T18:56:02.60949Z","end":"2026-02-01T18:56:02.899429Z","steps":["trace[440266119] 'process raft request' (duration: 289.846279ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:03.74332Z","caller":"traceutil/trace.go:171","msg":"trace[1546220439] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"227.668544ms","start":"2026-02-01T18:56:03.515637Z","end":"2026-02-01T18:56:03.743306Z","steps":["trace[1546220439] 'process raft request' (duration: 227.334186ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:04.604996Z","caller":"traceutil/trace.go:171","msg":"trace[469989216] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"192.629772ms","start":"2026-02-01T18:56:04.412351Z","end":"2026-02-01T18:56:04.604981Z","steps":["trace[469989216] 'read index received' (duration: 192.528189ms)","trace[469989216] 'applied index is now lower than readState.Index' (duration: 100.863µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:04.605011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.255371Z","time spent":"349.634495ms","remote":"127.0.0.1:44190","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-01T18:56:04.605164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.786155ms","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-01T18:56:04.605183Z","caller":"traceutil/trace.go:171","msg":"trace[1696526580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"192.866667ms","start":"2026-02-01T18:56:04.412311Z","end":"2026-02-01T18:56:04.605178Z","steps":["trace[1696526580] 'agreement among raft nodes before linearized reading' (duration: 192.743734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:04.605418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.861785ms","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-01T18:56:04.605439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.440869ms","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-01T18:56:04.605442Z","caller":"traceutil/trace.go:171","msg":"trace[1708658939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"184.921487ms","start":"2026-02-01T18:56:04.420511Z","end":"2026-02-01T18:56:04.605432Z","steps":["trace[1708658939] 'agreement among raft nodes before linearized reading' (duration: 184.820664ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:04.605456Z","caller":"traceutil/trace.go:171","msg":"trace[1804174073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"152.463559ms","start":"2026-02-01T18:56:04.452988Z","end":"2026-02-01T18:56:04.605451Z","steps":["trace[1804174073] 'agreement among raft nodes before linearized reading' (duration: 152.414898ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:04.910802Z","caller":"traceutil/trace.go:171","msg":"trace[1189364592] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"302.58117ms","start":"2026-02-01T18:56:04.608206Z","end":"2026-02-01T18:56:04.910788Z","steps":["trace[1189364592] 'process raft request' (duration: 302.477347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:04.910884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.608193Z","time spent":"302.647672ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:05.341759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.606539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:05.341961Z","caller":"traceutil/trace.go:171","msg":"trace[1753983518] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:933; }","duration":"439.992381ms","start":"2026-02-01T18:56:04.901956Z","end":"2026-02-01T18:56:05.341948Z","steps":["trace[1753983518] 'read index received' (duration: 8.831412ms)","trace[1753983518] 'applied index is now lower than readState.Index' (duration: 431.160229ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:05.34204Z","caller":"traceutil/trace.go:171","msg":"trace[1989085612] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"733.733989ms","start":"2026-02-01T18:56:04.608299Z","end":"2026-02-01T18:56:05.342033Z","steps":["trace[1989085612] 'process raft request' (duration: 409.795081ms)","trace[1989085612] 'compare' (duration: 323.278991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:05.342094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.608294Z","time spent":"733.771509ms","remote":"127.0.0.1:44190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:05.34247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.488404ms","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-01T18:56:05.342518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.248296ms","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-01T18:56:05.342545Z","caller":"traceutil/trace.go:171","msg":"trace[111554716] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:899; }","duration":"108.312708ms","start":"2026-02-01T18:56:05.234224Z","end":"2026-02-01T18:56:05.342537Z","steps":["trace[111554716] 'agreement among raft nodes before linearized reading' (duration: 108.250397ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:05.342539Z","caller":"traceutil/trace.go:171","msg":"trace[1129478488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"440.608487ms","start":"2026-02-01T18:56:04.901913Z","end":"2026-02-01T18:56:05.342522Z","steps":["trace[1129478488] 'agreement among raft nodes before linearized reading' (duration: 440.313988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:05.342583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.901891Z","time spent":"440.683749ms","remote":"127.0.0.1:44420","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-01T18:56:05.342831Z","caller":"traceutil/trace.go:171","msg":"trace[1118275372] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"732.411346ms","start":"2026-02-01T18:56:04.610411Z","end":"2026-02-01T18:56:05.342822Z","steps":["trace[1118275372] 'process raft request' (duration: 731.497363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:05.342873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.610405Z","time spent":"732.445227ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:05.342936Z","caller":"traceutil/trace.go:171","msg":"trace[527249095] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"733.986975ms","start":"2026-02-01T18:56:04.608941Z","end":"2026-02-01T18:56:05.342928Z","steps":["trace[527249095] 'process raft request' (duration: 732.902437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:05.342978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.608935Z","time spent":"734.017536ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:05.77387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.216884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:05.77396Z","caller":"traceutil/trace.go:171","msg":"trace[403464757] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"254.781606ms","start":"2026-02-01T18:56:05.519166Z","end":"2026-02-01T18:56:05.773947Z","steps":["trace[403464757] 'read index received' (duration: 39.101µs)","trace[403464757] 'applied index is now lower than readState.Index' (duration: 254.741535ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:05.774131Z","caller":"traceutil/trace.go:171","msg":"trace[1772894746] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"424.096632ms","start":"2026-02-01T18:56:05.350025Z","end":"2026-02-01T18:56:05.774121Z","steps":["trace[1772894746] 'process raft request' (duration: 159.571199ms)","trace[1772894746] 'compare' (duration: 264.0724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:05.774182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:05.350001Z","time spent":"424.158413ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:05.774349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.183257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:05.774379Z","caller":"traceutil/trace.go:171","msg":"trace[1729549468] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:900; }","duration":"255.262929ms","start":"2026-02-01T18:56:05.519107Z","end":"2026-02-01T18:56:05.77437Z","steps":["trace[1729549468] 'agreement among raft nodes before linearized reading' (duration: 255.197467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:06.447181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.2494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:06.447238Z","caller":"traceutil/trace.go:171","msg":"trace[185088503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"236.350372ms","start":"2026-02-01T18:56:06.210874Z","end":"2026-02-01T18:56:06.447225Z","steps":["trace[185088503] 'range keys from in-memory index tree' (duration: 236.179157ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:14.035829Z","caller":"traceutil/trace.go:171","msg":"trace[1824228495] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"133.748639ms","start":"2026-02-01T18:56:13.90206Z","end":"2026-02-01T18:56:14.035809Z","steps":["trace[1824228495] 'process raft request' (duration: 70.506296ms)","trace[1824228495] 'compare' (duration: 63.152171ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:15.962017Z","caller":"traceutil/trace.go:171","msg":"trace[49295033] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"112.161545ms","start":"2026-02-01T18:56:15.849824Z","end":"2026-02-01T18:56:15.961986Z","steps":["trace[49295033] 'process raft request' (duration: 111.980711ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:18.370979Z","caller":"traceutil/trace.go:171","msg":"trace[1453633124] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"108.00985ms","start":"2026-02-01T18:56:18.262947Z","end":"2026-02-01T18:56:18.370957Z","steps":["trace[1453633124] 'process raft request' (duration: 107.891107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:21.319219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.612801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:21.319293Z","caller":"traceutil/trace.go:171","msg":"trace[587588466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"107.721743ms","start":"2026-02-01T18:56:21.211552Z","end":"2026-02-01T18:56:21.319274Z","steps":["trace[587588466] 'range keys from in-memory index tree' (duration: 107.56441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:22.486483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637464952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:56:22.920501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.793328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:22.920586Z","caller":"traceutil/trace.go:171","msg":"trace[909755539] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"934.644266ms","start":"2026-02-01T18:56:21.98593Z","end":"2026-02-01T18:56:22.920575Z","steps":["trace[909755539] 'read index received' (duration: 800.652582ms)","trace[909755539] 'applied index is now lower than readState.Index' (duration: 133.990834ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:22.920731Z","caller":"traceutil/trace.go:171","msg":"trace[1278681297] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.427200741s","start":"2026-02-01T18:56:21.493522Z","end":"2026-02-01T18:56:22.920723Z","steps":["trace[1278681297] 'process raft request' (duration: 1.293108535s)","trace[1278681297] 'compare' (duration: 133.409509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:22.920807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:21.493507Z","time spent":"1.427249053s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:22.920973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.055036ms","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-01T18:56:22.920992Z","caller":"traceutil/trace.go:171","msg":"trace[1283874860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"935.120928ms","start":"2026-02-01T18:56:21.985864Z","end":"2026-02-01T18:56:22.920985Z","steps":["trace[1283874860] 'agreement among raft nodes before linearized reading' (duration: 935.047127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:22.921016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:21.985845Z","time spent":"935.167199ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:56:22.921104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.136447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:22.921125Z","caller":"traceutil/trace.go:171","msg":"trace[1016259683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"709.193139ms","start":"2026-02-01T18:56:22.211927Z","end":"2026-02-01T18:56:22.92112Z","steps":["trace[1016259683] 'agreement among raft nodes before linearized reading' (duration: 709.163358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:22.921145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.211908Z","time spent":"709.23363ms","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:56:22.921354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.148707ms","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-01T18:56:22.921381Z","caller":"traceutil/trace.go:171","msg":"trace[761026039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"541.211679ms","start":"2026-02-01T18:56:22.380162Z","end":"2026-02-01T18:56:22.921374Z","steps":["trace[761026039] 'agreement among raft nodes before linearized reading' (duration: 541.132497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:22.921399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.38014Z","time spent":"541.254681ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:56:22.921502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.358434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-02-01T18:56:22.921526Z","caller":"traceutil/trace.go:171","msg":"trace[1217494616] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:986; }","duration":"632.412156ms","start":"2026-02-01T18:56:22.289107Z","end":"2026-02-01T18:56:22.921519Z","steps":["trace[1217494616] 'agreement among raft nodes before linearized reading' (duration: 632.358994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:22.921543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.289091Z","time spent":"632.447696ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-01T18:56:23.643349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637464956,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:56:24.144433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637464956,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:56:24.645099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602328637464956,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:56:24.588443Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.647984698s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-01T18:56:25.818734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121124144s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:25.818821Z","caller":"traceutil/trace.go:171","msg":"trace[955485529] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"2.878400495s","start":"2026-02-01T18:56:22.940404Z","end":"2026-02-01T18:56:25.818805Z","steps":["trace[955485529] 'process raft request' (duration: 1.757140878s)","trace[955485529] 'compare' (duration: 1.120648312s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:25.818881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.940395Z","time spent":"2.878454367s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:26.22745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.01785429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-01T18:56:26.242534Z","caller":"traceutil/trace.go:171","msg":"trace[2058397804] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.032986861s","start":"2026-02-01T18:56:24.209528Z","end":"2026-02-01T18:56:26.242514Z","steps":["trace[2058397804] 'agreement among raft nodes before linearized reading' (duration: 2.017888901s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.305764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:24.209512Z","time spent":"2.096219164s","remote":"127.0.0.1:44148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/01 18:56:26 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-01T18:56:26.383006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.679457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:26.383232Z","caller":"traceutil/trace.go:171","msg":"trace[723080072] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1026; }","duration":"3.240852184s","start":"2026-02-01T18:56:23.142363Z","end":"2026-02-01T18:56:26.383215Z","steps":["trace[723080072] 'read index received' (duration: 1.555187972s)","trace[723080072] 'applied index is now lower than readState.Index' (duration: 1.685663192s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:26.383591Z","caller":"traceutil/trace.go:171","msg":"trace[859315261] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"3.440740247s","start":"2026-02-01T18:56:22.942727Z","end":"2026-02-01T18:56:26.383468Z","steps":["trace[859315261] 'process raft request' (duration: 3.298509456s)","trace[859315261] 'compare' (duration: 141.550094ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:26.383665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.942719Z","time spent":"3.440905692s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:26.38388Z","caller":"traceutil/trace.go:171","msg":"trace[1856532727] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"3.441109987s","start":"2026-02-01T18:56:22.94276Z","end":"2026-02-01T18:56:26.38387Z","steps":["trace[1856532727] 'process raft request' (duration: 3.440332087s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.383973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.942757Z","time spent":"3.441150828s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:26.384122Z","caller":"traceutil/trace.go:171","msg":"trace[2101902433] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"2.645260272s","start":"2026-02-01T18:56:23.738852Z","end":"2026-02-01T18:56:26.384112Z","steps":["trace[2101902433] 'process raft request' (duration: 2.644301937s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.384167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:23.738836Z","time spent":"2.645307763s","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":865,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:56:26.384488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.242103036s","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-01T18:56:26.384557Z","caller":"traceutil/trace.go:171","msg":"trace[1125605558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"3.242214399s","start":"2026-02-01T18:56:23.142325Z","end":"2026-02-01T18:56:26.384539Z","steps":["trace[1125605558] 'agreement among raft nodes before linearized reading' (duration: 3.242006863s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.384598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:23.142307Z","time spent":"3.24228203s","remote":"127.0.0.1:44420","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-01T18:56:26.384913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.892041818s","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-01T18:56:26.384956Z","caller":"traceutil/trace.go:171","msg":"trace[2022298161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"2.89211231s","start":"2026-02-01T18:56:23.492834Z","end":"2026-02-01T18:56:26.384946Z","steps":["trace[2022298161] 'agreement among raft nodes before linearized reading' (duration: 2.892010467s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.384983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:23.492818Z","time spent":"2.892159071s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:56:26.385327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452242175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T18:56:26.385366Z","caller":"traceutil/trace.go:171","msg":"trace[1404239439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"1.452319566s","start":"2026-02-01T18:56:24.933037Z","end":"2026-02-01T18:56:26.385356Z","steps":["trace[1404239439] 'agreement among raft nodes before linearized reading' (duration: 1.452213163s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.385393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:24.933018Z","time spent":"1.452367278s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:56:26.385405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.342481424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-01T18:56:26.38545Z","caller":"traceutil/trace.go:171","msg":"trace[568953974] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:990; }","duration":"2.342555146s","start":"2026-02-01T18:56:24.04288Z","end":"2026-02-01T18:56:26.385435Z","steps":["trace[568953974] 'agreement among raft nodes before linearized reading' (duration: 2.342387682s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.385484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:24.042862Z","time spent":"2.342614668s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-01T18:56:26.385663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561047644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-01T18:56:26.385689Z","caller":"traceutil/trace.go:171","msg":"trace[88034986] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:990; }","duration":"1.561109066s","start":"2026-02-01T18:56:24.824572Z","end":"2026-02-01T18:56:26.385681Z","steps":["trace[88034986] 'agreement among raft nodes before linearized reading' (duration: 1.561033574s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.38583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:24.824535Z","time spent":"1.561180368s","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-01T18:56:26.385843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.130061165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:56:26.385879Z","caller":"traceutil/trace.go:171","msg":"trace[342952145] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:990; }","duration":"2.130097286s","start":"2026-02-01T18:56:24.255772Z","end":"2026-02-01T18:56:26.385869Z","steps":["trace[342952145] 'agreement among raft nodes before linearized reading' (duration: 2.130002803s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.385906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:24.255702Z","time spent":"2.130197238s","remote":"127.0.0.1:44190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"info","ts":"2026-02-01T18:56:26.528152Z","caller":"traceutil/trace.go:171","msg":"trace[1365948641] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1031; }","duration":"130.67446ms","start":"2026-02-01T18:56:26.397463Z","end":"2026-02-01T18:56:26.528138Z","steps":["trace[1365948641] 'read index received' (duration: 44.770457ms)","trace[1365948641] 'applied index is now lower than readState.Index' (duration: 85.903493ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:26.528204Z","caller":"traceutil/trace.go:171","msg":"trace[918888070] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"131.349968ms","start":"2026-02-01T18:56:26.396817Z","end":"2026-02-01T18:56:26.528167Z","steps":["trace[918888070] 'process raft request' (duration: 131.238295ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:26.528508Z","caller":"traceutil/trace.go:171","msg":"trace[556593064] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"126.70329ms","start":"2026-02-01T18:56:26.401796Z","end":"2026-02-01T18:56:26.528499Z","steps":["trace[556593064] 'process raft request' (duration: 126.28028ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:26.528484Z","caller":"traceutil/trace.go:171","msg":"trace[2110581096] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"134.320303ms","start":"2026-02-01T18:56:26.394153Z","end":"2026-02-01T18:56:26.528473Z","steps":["trace[2110581096] 'process raft request' (duration: 133.781759ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:26.528551Z","caller":"traceutil/trace.go:171","msg":"trace[1863432482] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"132.413185ms","start":"2026-02-01T18:56:26.39613Z","end":"2026-02-01T18:56:26.528543Z","steps":["trace[1863432482] 'process raft request' (duration: 131.896402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.528628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.146713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:26.530063Z","caller":"traceutil/trace.go:171","msg":"trace[1128251863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"132.61268ms","start":"2026-02-01T18:56:26.39744Z","end":"2026-02-01T18:56:26.530053Z","steps":["trace[1128251863] 'agreement among raft nodes before linearized reading' (duration: 131.141852ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:26.528671Z","caller":"traceutil/trace.go:171","msg":"trace[1574614619] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"124.351491ms","start":"2026-02-01T18:56:26.4043Z","end":"2026-02-01T18:56:26.528652Z","steps":["trace[1574614619] 'process raft request' (duration: 123.794917ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:26.528701Z","caller":"traceutil/trace.go:171","msg":"trace[563545800] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"124.353741ms","start":"2026-02-01T18:56:26.40434Z","end":"2026-02-01T18:56:26.528694Z","steps":["trace[563545800] 'process raft request' (duration: 123.776877ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:27.456424Z","caller":"traceutil/trace.go:171","msg":"trace[1615647955] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"146.443899ms","start":"2026-02-01T18:56:27.309956Z","end":"2026-02-01T18:56:27.4564Z","steps":["trace[1615647955] 'process raft request' (duration: 146.318765ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:27.499256Z","caller":"traceutil/trace.go:171","msg":"trace[92185690] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"187.665017ms","start":"2026-02-01T18:56:27.311567Z","end":"2026-02-01T18:56:27.499232Z","steps":["trace[92185690] 'process raft request' (duration: 187.525034ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:28.184212Z","caller":"traceutil/trace.go:171","msg":"trace[232696430] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"209.911248ms","start":"2026-02-01T18:56:27.97428Z","end":"2026-02-01T18:56:28.184191Z","steps":["trace[232696430] 'process raft request' (duration: 209.784574ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:28.688335Z","caller":"traceutil/trace.go:171","msg":"trace[1338375667] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"134.57826ms","start":"2026-02-01T18:56:28.55373Z","end":"2026-02-01T18:56:28.688309Z","steps":["trace[1338375667] 'process raft request' (duration: 74.248911ms)","trace[1338375667] 'compare' (duration: 60.202246ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:28.757996Z","caller":"traceutil/trace.go:171","msg":"trace[1778187550] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"189.719668ms","start":"2026-02-01T18:56:28.568241Z","end":"2026-02-01T18:56:28.757961Z","steps":["trace[1778187550] 'process raft request' (duration: 189.609055ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:28.758352Z","caller":"traceutil/trace.go:171","msg":"trace[317446270] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"200.9051ms","start":"2026-02-01T18:56:28.557429Z","end":"2026-02-01T18:56:28.758334Z","steps":["trace[317446270] 'process raft request' (duration: 200.349687ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:28.758724Z","caller":"traceutil/trace.go:171","msg":"trace[1827934293] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"203.69562ms","start":"2026-02-01T18:56:28.55499Z","end":"2026-02-01T18:56:28.758685Z","steps":["trace[1827934293] 'process raft request' (duration: 202.614893ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:28.758797Z","caller":"traceutil/trace.go:171","msg":"trace[866114461] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"199.629568ms","start":"2026-02-01T18:56:28.559118Z","end":"2026-02-01T18:56:28.758748Z","steps":["trace[866114461] 'process raft request' (duration: 198.699305ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:28.759112Z","caller":"traceutil/trace.go:171","msg":"trace[1982875117] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"202.573002ms","start":"2026-02-01T18:56:28.55653Z","end":"2026-02-01T18:56:28.759103Z","steps":["trace[1982875117] 'process raft request' (duration: 201.201248ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:28.798764Z","caller":"traceutil/trace.go:171","msg":"trace[1777508997] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"167.762835ms","start":"2026-02-01T18:56:28.630983Z","end":"2026-02-01T18:56:28.798745Z","steps":["trace[1777508997] 'process raft request' (duration: 164.79877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:44.932722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.22953ms","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-01T18:56:44.933372Z","caller":"traceutil/trace.go:171","msg":"trace[1919996752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1084; }","duration":"103.883336ms","start":"2026-02-01T18:56:44.829471Z","end":"2026-02-01T18:56:44.933354Z","steps":["trace[1919996752] 'range keys from in-memory index tree' (duration: 103.105917ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:56.778074Z","caller":"traceutil/trace.go:171","msg":"trace[1796309013] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"109.034726ms","start":"2026-02-01T18:56:56.669021Z","end":"2026-02-01T18:56:56.778055Z","steps":["trace[1796309013] 'process raft request' (duration: 53.507227ms)","trace[1796309013] 'compare' (duration: 55.309813ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:57:03.216756Z","caller":"traceutil/trace.go:171","msg":"trace[848420831] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"108.900213ms","start":"2026-02-01T18:57:03.107824Z","end":"2026-02-01T18:57:03.216724Z","steps":["trace[848420831] 'process raft request' (duration: 81.380239ms)","trace[848420831] 'compare' (duration: 27.38274ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:57:03.233983Z","caller":"traceutil/trace.go:171","msg":"trace[1982995977] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"124.757023ms","start":"2026-02-01T18:57:03.109213Z","end":"2026-02-01T18:57:03.23397Z","steps":["trace[1982995977] 'process raft request' (duration: 124.497296ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:57:31.863522Z","caller":"traceutil/trace.go:171","msg":"trace[331967358] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"136.548441ms","start":"2026-02-01T18:57:31.726956Z","end":"2026-02-01T18:57:31.863504Z","steps":["trace[331967358] 'process raft request' (duration: 136.433318ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:57:32.151487Z","caller":"traceutil/trace.go:171","msg":"trace[704975746] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"107.338854ms","start":"2026-02-01T18:57:32.044107Z","end":"2026-02-01T18:57:32.151446Z","steps":["trace[704975746] 'process raft request' (duration: 40.070219ms)","trace[704975746] 'compare' (duration: 67.07919ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:57:32.151772Z","caller":"traceutil/trace.go:171","msg":"trace[1842616289] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"107.351086ms","start":"2026-02-01T18:57:32.044407Z","end":"2026-02-01T18:57:32.151759Z","steps":["trace[1842616289] 'process raft request' (duration: 106.975145ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:57:33.602401Z","caller":"traceutil/trace.go:171","msg":"trace[731225449] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"148.674275ms","start":"2026-02-01T18:57:33.453702Z","end":"2026-02-01T18:57:33.602376Z","steps":["trace[731225449] 'process raft request' (duration: 74.643121ms)","trace[731225449] 'compare' (duration: 73.575463ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:57:36.272342Z","caller":"traceutil/trace.go:171","msg":"trace[728235310] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"100.387018ms","start":"2026-02-01T18:57:36.17193Z","end":"2026-02-01T18:57:36.272317Z","steps":["trace[728235310] 'process raft request' (duration: 100.05237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:57:52.060474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.705115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-01T18:57:52.060554Z","caller":"traceutil/trace.go:171","msg":"trace[1007223266] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1357; }","duration":"117.87998ms","start":"2026-02-01T18:57:51.942653Z","end":"2026-02-01T18:57:52.060533Z","steps":["trace[1007223266] 'range keys from in-memory index tree' (duration: 117.537931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:57:57.720543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.922489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T18:57:57.720601Z","caller":"traceutil/trace.go:171","msg":"trace[505477193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"135.028102ms","start":"2026-02-01T18:57:57.58556Z","end":"2026-02-01T18:57:57.720588Z","steps":["trace[505477193] 'range keys from in-memory index tree' (duration: 134.756295ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:55.699686Z","caller":"traceutil/trace.go:171","msg":"trace[655225006] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"430.987357ms","start":"2026-02-01T18:59:55.268676Z","end":"2026-02-01T18:59:55.699664Z","steps":["trace[655225006] 'process raft request' (duration: 430.856604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:59:55.717357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:59:55.268662Z","time spent":"448.638206ms","remote":"127.0.0.1:44420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}