{"level":"warn","ts":"2026-02-11T03:14:18.37245Z","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-11T03:14:18.372763Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-11T03:14:18.372854Z","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-11T03:14:18.372869Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-11T03:14:18.373024Z","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-11T03:14:18.373967Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-02-11T03:14:18.374642Z","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://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-11T03:14:18.422802Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"47.618588ms"} {"level":"info","ts":"2026-02-11T03:14:18.463977Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-02-11T03:14:18.46406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T03:14:18.46408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-02-11T03:14:18.464223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T03:14:18.464233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-02-11T03:14:18.464318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-02-11T03:14:18.495897Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T03:14:18.502849Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T03:14:18.509806Z","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-11T03:14:18.523144Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T03:14:18.523896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T03:14:18.524042Z","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-11T03:14:18.52409Z","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-11T03:14:18.524101Z","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-11T03:14:18.52443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-02-11T03:14:18.52461Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-11T03:14:18.533742Z","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-11T03:14:18.534495Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T03:14:18.534523Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T03:14:18.534848Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-11T03:14:18.53503Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-11T03:14:18.777307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T03:14:18.77738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T03:14:18.777401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-02-11T03:14:18.777417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-02-11T03:14:18.777422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-11T03:14:18.777436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-02-11T03:14:18.777448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-11T03:14:18.789244Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T03:14:18.789533Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T03:14:18.793045Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:14:18.793323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T03:14:18.793903Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T03:14:18.793974Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T03:14:18.798446Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T03:14:18.80664Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-02-11T03:14:18.80743Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:14:18.80784Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:14:18.808228Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-11T03:14:19.26012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:18.937121Z","time spent":"322.996458ms","remote":"127.0.0.1:51042","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T03:14:19.26028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.470735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.263432Z","caller":"traceutil/trace.go:171","msg":"trace[1857875216] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"104.645853ms","start":"2026-02-11T03:14:19.158773Z","end":"2026-02-11T03:14:19.263419Z","steps":["trace[1857875216] 'range keys from in-memory index tree' (duration: 101.354366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.26034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.512539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.263629Z","caller":"traceutil/trace.go:171","msg":"trace[397895464] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"315.809535ms","start":"2026-02-11T03:14:18.94781Z","end":"2026-02-11T03:14:19.26362Z","steps":["trace[397895464] 'count revisions from in-memory index tree' (duration: 312.492259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.263658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:18.947808Z","time spent":"315.842304ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-11T03:14:19.260356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.588618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.263794Z","caller":"traceutil/trace.go:171","msg":"trace[492500426] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"316.026463ms","start":"2026-02-11T03:14:18.947762Z","end":"2026-02-11T03:14:19.263789Z","steps":["trace[492500426] 'range keys from in-memory index tree' (duration: 312.485789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.263869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:18.947752Z","time spent":"316.059253ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":121,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T03:14:19.260383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.322989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.263972Z","caller":"traceutil/trace.go:171","msg":"trace[1237660628] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"247.926522ms","start":"2026-02-11T03:14:19.01604Z","end":"2026-02-11T03:14:19.263966Z","steps":["trace[1237660628] 'count revisions from in-memory index tree' (duration: 244.28184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.260397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.424681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.264125Z","caller":"traceutil/trace.go:171","msg":"trace[1566884457] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"230.138663ms","start":"2026-02-11T03:14:19.033962Z","end":"2026-02-11T03:14:19.264101Z","steps":["trace[1566884457] 'count revisions from in-memory index tree' (duration: 226.319842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.26041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.488841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.26429Z","caller":"traceutil/trace.go:171","msg":"trace[1312173210] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"230.472191ms","start":"2026-02-11T03:14:19.033813Z","end":"2026-02-11T03:14:19.264285Z","steps":["trace[1312173210] 'range keys from in-memory index tree' (duration: 226.318812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.260421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.458805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.26445Z","caller":"traceutil/trace.go:171","msg":"trace[1740713453] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"213.498813ms","start":"2026-02-11T03:14:19.050946Z","end":"2026-02-11T03:14:19.264445Z","steps":["trace[1740713453] 'count revisions from in-memory index tree' (duration: 209.436975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.260435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.605843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.265985Z","caller":"traceutil/trace.go:171","msg":"trace[1806034955] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"215.157517ms","start":"2026-02-11T03:14:19.050814Z","end":"2026-02-11T03:14:19.265971Z","steps":["trace[1806034955] 'range keys from in-memory index tree' (duration: 209.354066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.260446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.541396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.266292Z","caller":"traceutil/trace.go:171","msg":"trace[87791829] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"202.389776ms","start":"2026-02-11T03:14:19.063895Z","end":"2026-02-11T03:14:19.266284Z","steps":["trace[87791829] 'count revisions from in-memory index tree' (duration: 196.525836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.26046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.587546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.266591Z","caller":"traceutil/trace.go:171","msg":"trace[36729459] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"202.745532ms","start":"2026-02-11T03:14:19.063836Z","end":"2026-02-11T03:14:19.266582Z","steps":["trace[36729459] 'range keys from in-memory index tree' (duration: 196.531026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.260473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.174341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.267871Z","caller":"traceutil/trace.go:171","msg":"trace[1060781635] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"148.576646ms","start":"2026-02-11T03:14:19.119287Z","end":"2026-02-11T03:14:19.267864Z","steps":["trace[1060781635] 'count revisions from in-memory index tree' (duration: 141.155831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.260493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.326328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.268383Z","caller":"traceutil/trace.go:171","msg":"trace[292408516] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"149.222018ms","start":"2026-02-11T03:14:19.11915Z","end":"2026-02-11T03:14:19.268372Z","steps":["trace[292408516] 'range keys from in-memory index tree' (duration: 141.15886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:19.260612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.868381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:14:19.268669Z","caller":"traceutil/trace.go:171","msg":"trace[146440611] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"110.030928ms","start":"2026-02-11T03:14:19.15863Z","end":"2026-02-11T03:14:19.268661Z","steps":["trace[146440611] 'count revisions from in-memory index tree' (duration: 101.698053ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:21.958238Z","caller":"traceutil/trace.go:171","msg":"trace[1789348020] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"104.315425ms","start":"2026-02-11T03:14:21.853731Z","end":"2026-02-11T03:14:21.958046Z","steps":["trace[1789348020] 'process raft request' (duration: 89.85302ms)","trace[1789348020] 'compare' (duration: 14.321127ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:22.646279Z","caller":"traceutil/trace.go:171","msg":"trace[1093716101] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"132.729809ms","start":"2026-02-11T03:14:22.513521Z","end":"2026-02-11T03:14:22.646251Z","steps":["trace[1093716101] 'process raft request' (duration: 110.414371ms)","trace[1093716101] 'compare' (duration: 22.182229ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:22.864885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.265992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:14:22.865186Z","caller":"traceutil/trace.go:171","msg":"trace[19512025] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"124.891422ms","start":"2026-02-11T03:14:22.740283Z","end":"2026-02-11T03:14:22.865175Z","steps":["trace[19512025] 'process raft request' (duration: 17.046574ms)","trace[19512025] 'compare' (duration: 107.179813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:24.531699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.220615ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-02-11T03:14:24.557335Z","caller":"traceutil/trace.go:171","msg":"trace[596747344] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:220; }","duration":"202.578637ms","start":"2026-02-11T03:14:24.354731Z","end":"2026-02-11T03:14:24.55731Z","steps":["trace[596747344] 'read index received' (duration: 23.72537ms)","trace[596747344] 'applied index is now lower than readState.Index' (duration: 178.851977ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:24.557593Z","caller":"traceutil/trace.go:171","msg":"trace[1408925460] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"209.191637ms","start":"2026-02-11T03:14:24.348241Z","end":"2026-02-11T03:14:24.557432Z","steps":["trace[1408925460] 'process raft request' (duration: 208.935599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:24.557992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.240844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:24.558055Z","caller":"traceutil/trace.go:171","msg":"trace[2143964879] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:216; }","duration":"203.328783ms","start":"2026-02-11T03:14:24.354708Z","end":"2026-02-11T03:14:24.558036Z","steps":["trace[2143964879] 'agreement among raft nodes before linearized reading' (duration: 203.230574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:24.571614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.854182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3397"} {"level":"info","ts":"2026-02-11T03:14:24.571967Z","caller":"traceutil/trace.go:171","msg":"trace[1131483105] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:217; }","duration":"117.23348ms","start":"2026-02-11T03:14:24.454709Z","end":"2026-02-11T03:14:24.571942Z","steps":["trace[1131483105] 'agreement among raft nodes before linearized reading' (duration: 116.818243ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:24.773684Z","caller":"traceutil/trace.go:171","msg":"trace[2140060574] linearizableReadLoop","detail":"{readStateIndex:225; appliedIndex:224; }","duration":"143.28526ms","start":"2026-02-11T03:14:24.630376Z","end":"2026-02-11T03:14:24.773661Z","steps":["trace[2140060574] 'read index received' (duration: 135.094708ms)","trace[2140060574] 'applied index is now lower than readState.Index' (duration: 8.189642ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:24.77467Z","caller":"traceutil/trace.go:171","msg":"trace[68819885] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"193.847807ms","start":"2026-02-11T03:14:24.580798Z","end":"2026-02-11T03:14:24.774646Z","steps":["trace[68819885] 'process raft request' (duration: 184.69649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:24.778893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.494776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:24.778983Z","caller":"traceutil/trace.go:171","msg":"trace[39516635] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:219; }","duration":"148.623226ms","start":"2026-02-11T03:14:24.630338Z","end":"2026-02-11T03:14:24.778962Z","steps":["trace[39516635] 'agreement among raft nodes before linearized reading' (duration: 143.905067ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:24.972715Z","caller":"traceutil/trace.go:171","msg":"trace[583938013] linearizableReadLoop","detail":"{readStateIndex:228; appliedIndex:227; }","duration":"166.963231ms","start":"2026-02-11T03:14:24.805733Z","end":"2026-02-11T03:14:24.972696Z","steps":["trace[583938013] 'read index received' (duration: 94.599764ms)","trace[583938013] 'applied index is now lower than readState.Index' (duration: 72.362527ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:24.976382Z","caller":"traceutil/trace.go:171","msg":"trace[1359301321] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"174.452956ms","start":"2026-02-11T03:14:24.801899Z","end":"2026-02-11T03:14:24.976352Z","steps":["trace[1359301321] 'process raft request' (duration: 98.455237ms)","trace[1359301321] 'compare' (duration: 72.134828ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:24.976623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.637423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:24.976849Z","caller":"traceutil/trace.go:171","msg":"trace[267211143] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:222; }","duration":"171.085222ms","start":"2026-02-11T03:14:24.805705Z","end":"2026-02-11T03:14:24.97679Z","steps":["trace[267211143] 'agreement among raft nodes before linearized reading' (duration: 167.444578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:25.999512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.240327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:14:25.999781Z","caller":"traceutil/trace.go:171","msg":"trace[750177289] linearizableReadLoop","detail":"{readStateIndex:263; appliedIndex:262; }","duration":"369.166695ms","start":"2026-02-11T03:14:25.6306Z","end":"2026-02-11T03:14:25.999767Z","steps":["trace[750177289] 'read index received' (duration: 192.626749ms)","trace[750177289] 'applied index is now lower than readState.Index' (duration: 176.538836ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:26.000035Z","caller":"traceutil/trace.go:171","msg":"trace[1026510793] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"385.277368ms","start":"2026-02-11T03:14:25.614747Z","end":"2026-02-11T03:14:26.000024Z","steps":["trace[1026510793] 'process raft request' (duration: 208.470743ms)","trace[1026510793] 'compare' (duration: 176.134277ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:26.000112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.492343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:26.000234Z","caller":"traceutil/trace.go:171","msg":"trace[929136084] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:257; }","duration":"369.614073ms","start":"2026-02-11T03:14:25.630597Z","end":"2026-02-11T03:14:26.000211Z","steps":["trace[929136084] 'agreement among raft nodes before linearized reading' (duration: 369.438964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:26.000279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:25.630572Z","time spent":"369.696773ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" "} {"level":"warn","ts":"2026-02-11T03:14:26.000174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:25.614739Z","time spent":"385.387437ms","remote":"127.0.0.1:51362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:14:26.000988Z","caller":"traceutil/trace.go:171","msg":"trace[1862391324] transaction","detail":"{read_only:false; number_of_response:0; response_revision:258; }","duration":"254.279628ms","start":"2026-02-11T03:14:25.746693Z","end":"2026-02-11T03:14:26.000973Z","steps":["trace[1862391324] 'process raft request' (duration: 254.231258ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:26.001182Z","caller":"traceutil/trace.go:171","msg":"trace[1494600064] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"363.973032ms","start":"2026-02-11T03:14:25.637197Z","end":"2026-02-11T03:14:26.00117Z","steps":["trace[1494600064] 'process raft request' (duration: 363.592164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:26.001274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:25.637185Z","time spent":"364.036652ms","remote":"127.0.0.1:51074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:14:26.006529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.183777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-11T03:14:26.00684Z","caller":"traceutil/trace.go:171","msg":"trace[1788904480] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:262; }","duration":"369.518196ms","start":"2026-02-11T03:14:25.637306Z","end":"2026-02-11T03:14:26.006824Z","steps":["trace[1788904480] 'agreement among raft nodes before linearized reading' (duration: 369.157297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:26.006976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:25.637301Z","time spent":"369.642386ms","remote":"127.0.0.1:51412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-11T03:14:26.00766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.592119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:26.007797Z","caller":"traceutil/trace.go:171","msg":"trace[1585403600] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:262; }","duration":"360.742369ms","start":"2026-02-11T03:14:25.647046Z","end":"2026-02-11T03:14:26.007789Z","steps":["trace[1585403600] 'agreement among raft nodes before linearized reading' (duration: 360.592409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:26.007944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:25.647039Z","time spent":"360.896928ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-11T03:14:26.547986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.451908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-11T03:14:26.548075Z","caller":"traceutil/trace.go:171","msg":"trace[1473391796] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:274; }","duration":"217.581979ms","start":"2026-02-11T03:14:26.330473Z","end":"2026-02-11T03:14:26.548055Z","steps":["trace[1473391796] 'range keys from in-memory index tree' (duration: 217.381689ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:26.716112Z","caller":"traceutil/trace.go:171","msg":"trace[1078489468] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:283; }","duration":"119.656923ms","start":"2026-02-11T03:14:26.596436Z","end":"2026-02-11T03:14:26.716093Z","steps":["trace[1078489468] 'read index received' (duration: 77.946767ms)","trace[1078489468] 'applied index is now lower than readState.Index' (duration: 41.709496ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:26.716228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.777063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-11T03:14:26.716259Z","caller":"traceutil/trace.go:171","msg":"trace[1481745146] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:276; }","duration":"119.863513ms","start":"2026-02-11T03:14:26.596386Z","end":"2026-02-11T03:14:26.716249Z","steps":["trace[1481745146] 'agreement among raft nodes before linearized reading' (duration: 119.775243ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:26.716497Z","caller":"traceutil/trace.go:171","msg":"trace[241532291] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"150.477846ms","start":"2026-02-11T03:14:26.566009Z","end":"2026-02-11T03:14:26.716487Z","steps":["trace[241532291] 'process raft request' (duration: 108.379371ms)","trace[241532291] 'compare' (duration: 41.630455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:27.50613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.303605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:14:27.506594Z","caller":"traceutil/trace.go:171","msg":"trace[125753366] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:295; }","duration":"170.585452ms","start":"2026-02-11T03:14:27.335991Z","end":"2026-02-11T03:14:27.506576Z","steps":["trace[125753366] 'read index received' (duration: 36.528357ms)","trace[125753366] 'applied index is now lower than readState.Index' (duration: 134.055975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:27.506838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.839121ms","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-11T03:14:27.507006Z","caller":"traceutil/trace.go:171","msg":"trace[1772637595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:289; }","duration":"170.925171ms","start":"2026-02-11T03:14:27.335944Z","end":"2026-02-11T03:14:27.506869Z","steps":["trace[1772637595] 'agreement among raft nodes before linearized reading' (duration: 170.705552ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:27.507558Z","caller":"traceutil/trace.go:171","msg":"trace[336236417] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"457.691104ms","start":"2026-02-11T03:14:27.049852Z","end":"2026-02-11T03:14:27.507543Z","steps":["trace[336236417] 'process raft request' (duration: 322.697991ms)","trace[336236417] 'compare' (duration: 133.197505ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:27.508074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:27.049841Z","time spent":"457.754464ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:14:27.508274Z","caller":"traceutil/trace.go:171","msg":"trace[638839064] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"425.674981ms","start":"2026-02-11T03:14:27.082559Z","end":"2026-02-11T03:14:27.508234Z","steps":["trace[638839064] 'process raft request' (duration: 423.923324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:27.508663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:27.082531Z","time spent":"425.779681ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:14:27.509701Z","caller":"traceutil/trace.go:171","msg":"trace[975485921] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"458.116754ms","start":"2026-02-11T03:14:27.051571Z","end":"2026-02-11T03:14:27.509688Z","steps":["trace[975485921] 'process raft request' (duration: 454.65675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:27.515244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:27.051545Z","time spent":"458.213084ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:27.7354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.508761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:14:27.735556Z","caller":"traceutil/trace.go:171","msg":"trace[1620712671] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"141.542973ms","start":"2026-02-11T03:14:27.593974Z","end":"2026-02-11T03:14:27.735517Z","steps":["trace[1620712671] 'process raft request' (duration: 38.788223ms)","trace[1620712671] 'compare' (duration: 102.320331ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:27.96571Z","caller":"traceutil/trace.go:171","msg":"trace[1954393083] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"134.165504ms","start":"2026-02-11T03:14:27.831517Z","end":"2026-02-11T03:14:27.965682Z","steps":["trace[1954393083] 'process raft request' (duration: 90.541088ms)","trace[1954393083] 'compare' (duration: 43.505287ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:28.186411Z","caller":"traceutil/trace.go:171","msg":"trace[115049701] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"205.153747ms","start":"2026-02-11T03:14:27.981229Z","end":"2026-02-11T03:14:28.186383Z","steps":["trace[115049701] 'process raft request' (duration: 140.125985ms)","trace[115049701] 'compare' (duration: 64.920862ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:28.496068Z","caller":"traceutil/trace.go:171","msg":"trace[1333544968] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"165.219878ms","start":"2026-02-11T03:14:28.330823Z","end":"2026-02-11T03:14:28.496043Z","steps":["trace[1333544968] 'process raft request' (duration: 112.535081ms)","trace[1333544968] 'compare' (duration: 52.588147ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:29.388312Z","caller":"traceutil/trace.go:171","msg":"trace[2074364498] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:316; }","duration":"107.33193ms","start":"2026-02-11T03:14:29.280964Z","end":"2026-02-11T03:14:29.388296Z","steps":["trace[2074364498] 'read index received' (duration: 49.960858ms)","trace[2074364498] 'applied index is now lower than readState.Index' (duration: 57.370342ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:29.388749Z","caller":"traceutil/trace.go:171","msg":"trace[608019613] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"120.263068ms","start":"2026-02-11T03:14:29.268473Z","end":"2026-02-11T03:14:29.388736Z","steps":["trace[608019613] 'process raft request' (duration: 62.385765ms)","trace[608019613] 'compare' (duration: 57.274532ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:29.389124Z","caller":"traceutil/trace.go:171","msg":"trace[1645397553] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"116.925906ms","start":"2026-02-11T03:14:29.272189Z","end":"2026-02-11T03:14:29.389115Z","steps":["trace[1645397553] 'process raft request' (duration: 116.070795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:29.3894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.419241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-11T03:14:29.389434Z","caller":"traceutil/trace.go:171","msg":"trace[1236449459] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:309; }","duration":"108.492061ms","start":"2026-02-11T03:14:29.280934Z","end":"2026-02-11T03:14:29.389426Z","steps":["trace[1236449459] 'agreement among raft nodes before linearized reading' (duration: 108.418531ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:29.598701Z","caller":"traceutil/trace.go:171","msg":"trace[1849522784] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:321; }","duration":"118.463678ms","start":"2026-02-11T03:14:29.480199Z","end":"2026-02-11T03:14:29.598663Z","steps":["trace[1849522784] 'read index received' (duration: 46.008777ms)","trace[1849522784] 'applied index is now lower than readState.Index' (duration: 72.453881ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:29.598982Z","caller":"traceutil/trace.go:171","msg":"trace[58629934] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"156.572859ms","start":"2026-02-11T03:14:29.442384Z","end":"2026-02-11T03:14:29.598957Z","steps":["trace[58629934] 'process raft request' (duration: 83.870648ms)","trace[58629934] 'compare' (duration: 72.27915ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:29.59922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.997638ms","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-11T03:14:29.599266Z","caller":"traceutil/trace.go:171","msg":"trace[594216745] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:313; }","duration":"119.061628ms","start":"2026-02-11T03:14:29.480194Z","end":"2026-02-11T03:14:29.599255Z","steps":["trace[594216745] 'agreement among raft nodes before linearized reading' (duration: 118.941018ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:29.881997Z","caller":"traceutil/trace.go:171","msg":"trace[1878612313] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:334; }","duration":"100.230667ms","start":"2026-02-11T03:14:29.781749Z","end":"2026-02-11T03:14:29.881979Z","steps":["trace[1878612313] 'read index received' (duration: 44.200125ms)","trace[1878612313] 'applied index is now lower than readState.Index' (duration: 56.029682ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:29.88223Z","caller":"traceutil/trace.go:171","msg":"trace[1527258654] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"120.301538ms","start":"2026-02-11T03:14:29.761879Z","end":"2026-02-11T03:14:29.882181Z","steps":["trace[1527258654] 'process raft request' (duration: 118.380376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:29.882954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.136727ms","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-11T03:14:29.883032Z","caller":"traceutil/trace.go:171","msg":"trace[1143140392] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:329; }","duration":"101.306197ms","start":"2026-02-11T03:14:29.781707Z","end":"2026-02-11T03:14:29.883013Z","steps":["trace[1143140392] 'agreement among raft nodes before linearized reading' (duration: 100.931067ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:29.884053Z","caller":"traceutil/trace.go:171","msg":"trace[1984938391] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"120.466018ms","start":"2026-02-11T03:14:29.76357Z","end":"2026-02-11T03:14:29.884036Z","steps":["trace[1984938391] 'process raft request' (duration: 118.323518ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:29.885088Z","caller":"traceutil/trace.go:171","msg":"trace[647887544] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"119.275438ms","start":"2026-02-11T03:14:29.765798Z","end":"2026-02-11T03:14:29.885073Z","steps":["trace[647887544] 'process raft request' (duration: 116.137437ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:31.144007Z","caller":"traceutil/trace.go:171","msg":"trace[370436059] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"407.084697ms","start":"2026-02-11T03:14:30.736907Z","end":"2026-02-11T03:14:31.143991Z","steps":["trace[370436059] 'process raft request' (duration: 406.996736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:31.144128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:30.736889Z","time spent":"407.179527ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:32.381584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.742521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:14:32.381714Z","caller":"traceutil/trace.go:171","msg":"trace[355888522] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"752.215348ms","start":"2026-02-11T03:14:31.629482Z","end":"2026-02-11T03:14:32.381697Z","steps":["trace[355888522] 'read index received' (duration: 29.56µs)","trace[355888522] 'applied index is now lower than readState.Index' (duration: 752.184498ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:32.381803Z","caller":"traceutil/trace.go:171","msg":"trace[1155545192] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"1.32084496s","start":"2026-02-11T03:14:31.060947Z","end":"2026-02-11T03:14:32.381792Z","steps":["trace[1155545192] 'process raft request' (duration: 324.817708ms)","trace[1155545192] 'compare' (duration: 995.619051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:32.381863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:31.06092Z","time spent":"1.32091171s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:32.381934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.344342ms","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-11T03:14:32.382004Z","caller":"traceutil/trace.go:171","msg":"trace[747201701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:351; }","duration":"742.421683ms","start":"2026-02-11T03:14:31.639567Z","end":"2026-02-11T03:14:32.381988Z","steps":["trace[747201701] 'agreement among raft nodes before linearized reading' (duration: 742.310372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:32.382032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:31.639515Z","time spent":"742.511293ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T03:14:32.382125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.644439ms","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-11T03:14:32.382141Z","caller":"traceutil/trace.go:171","msg":"trace[117347168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:351; }","duration":"752.685269ms","start":"2026-02-11T03:14:31.629451Z","end":"2026-02-11T03:14:32.382136Z","steps":["trace[117347168] 'agreement among raft nodes before linearized reading' (duration: 752.656929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:32.38216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:31.629436Z","time spent":"752.720149ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T03:14:32.382214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.757844ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:32.382255Z","caller":"traceutil/trace.go:171","msg":"trace[2058688205] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:351; }","duration":"524.801954ms","start":"2026-02-11T03:14:31.857448Z","end":"2026-02-11T03:14:32.38225Z","steps":["trace[2058688205] 'agreement among raft nodes before linearized reading' (duration: 524.751554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:32.382511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.579324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:32.382562Z","caller":"traceutil/trace.go:171","msg":"trace[374024730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:351; }","duration":"695.655384ms","start":"2026-02-11T03:14:31.686894Z","end":"2026-02-11T03:14:32.382549Z","steps":["trace[374024730] 'agreement among raft nodes before linearized reading' (duration: 695.563814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:32.382593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:31.686877Z","time spent":"695.709226ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:14:32.38282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.805517ms","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-11T03:14:32.382849Z","caller":"traceutil/trace.go:171","msg":"trace[825198329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:351; }","duration":"739.843217ms","start":"2026-02-11T03:14:31.642997Z","end":"2026-02-11T03:14:32.38284Z","steps":["trace[825198329] 'agreement among raft nodes before linearized reading' (duration: 739.784067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:32.382875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:31.642991Z","time spent":"739.877807ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T03:14:33.016421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.270068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:14:33.016506Z","caller":"traceutil/trace.go:171","msg":"trace[1489703685] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"627.701693ms","start":"2026-02-11T03:14:32.38879Z","end":"2026-02-11T03:14:33.016492Z","steps":["trace[1489703685] 'process raft request' (duration: 424.300985ms)","trace[1489703685] 'compare' (duration: 202.961576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:33.016547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:32.388779Z","time spent":"627.750594ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:34.766427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001033516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T03:14:34.766595Z","caller":"traceutil/trace.go:171","msg":"trace[310505872] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001276048s","start":"2026-02-11T03:14:32.765287Z","end":"2026-02-11T03:14:34.766563Z","steps":["trace[310505872] 'agreement among raft nodes before linearized reading' (duration: 2.001051077s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:34.766707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:32.76527Z","time spent":"2.001421489s","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:14:36.474934Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.661782359s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T03:14:36.475446Z","caller":"traceutil/trace.go:171","msg":"trace[661277735] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:360; }","duration":"3.710106869s","start":"2026-02-11T03:14:32.765319Z","end":"2026-02-11T03:14:36.475426Z","steps":["trace[661277735] 'read index received' (duration: 47.807856ms)","trace[661277735] 'applied index is now lower than readState.Index' (duration: 3.662298123s)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:36.475669Z","caller":"traceutil/trace.go:171","msg":"trace[1141325231] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"4.083268697s","start":"2026-02-11T03:14:32.392389Z","end":"2026-02-11T03:14:36.475657Z","steps":["trace[1141325231] 'process raft request' (duration: 4.082777554s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:36.475759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:32.39237Z","time spent":"4.083327278s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:14:36.476048Z","caller":"traceutil/trace.go:171","msg":"trace[278271331] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"4.081228222s","start":"2026-02-11T03:14:32.394808Z","end":"2026-02-11T03:14:36.476036Z","steps":["trace[278271331] 'process raft request' (duration: 4.080543996s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:36.476109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:32.394802Z","time spent":"4.081275102s","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:14:36.476207Z","caller":"traceutil/trace.go:171","msg":"trace[549041733] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"4.08028944s","start":"2026-02-11T03:14:32.395903Z","end":"2026-02-11T03:14:36.476193Z","steps":["trace[549041733] 'process raft request' (duration: 4.079488564s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:36.476265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:32.395896Z","time spent":"4.08033558s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:14:36.731202Z","caller":"traceutil/trace.go:171","msg":"trace[229479999] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:364; }","duration":"255.695559ms","start":"2026-02-11T03:14:36.475483Z","end":"2026-02-11T03:14:36.731179Z","steps":["trace[229479999] 'read index received' (duration: 219.620011ms)","trace[229479999] 'applied index is now lower than readState.Index' (duration: 36.074518ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:36.731535Z","caller":"traceutil/trace.go:171","msg":"trace[1434734814] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"338.232708ms","start":"2026-02-11T03:14:36.393289Z","end":"2026-02-11T03:14:36.731522Z","steps":["trace[1434734814] 'process raft request' (duration: 301.779648ms)","trace[1434734814] 'compare' (duration: 35.999497ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:36.731835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:36.393264Z","time spent":"338.5041ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:36.732171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.421856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:36.732263Z","caller":"traceutil/trace.go:171","msg":"trace[597441060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:356; }","duration":"851.558937ms","start":"2026-02-11T03:14:35.880692Z","end":"2026-02-11T03:14:36.732251Z","steps":["trace[597441060] 'agreement among raft nodes before linearized reading' (duration: 851.439827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:36.732352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:35.880659Z","time spent":"851.682998ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:14:36.732592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.71236135s","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-11T03:14:36.732695Z","caller":"traceutil/trace.go:171","msg":"trace[1543676400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:356; }","duration":"1.71248965s","start":"2026-02-11T03:14:35.020183Z","end":"2026-02-11T03:14:36.732673Z","steps":["trace[1543676400] 'agreement among raft nodes before linearized reading' (duration: 1.71237346s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:36.732808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:35.020163Z","time spent":"1.712633102s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T03:14:36.733033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.965605868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:36.733132Z","caller":"traceutil/trace.go:171","msg":"trace[1670012735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:356; }","duration":"1.965774269s","start":"2026-02-11T03:14:34.767347Z","end":"2026-02-11T03:14:36.733121Z","steps":["trace[1670012735] 'agreement among raft nodes before linearized reading' (duration: 1.965658269s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:36.73322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:34.767313Z","time spent":"1.96589721s","remote":"127.0.0.1:51010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:14:36.733456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.510767499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T03:14:36.733531Z","caller":"traceutil/trace.go:171","msg":"trace[16767918] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:356; }","duration":"3.51087499s","start":"2026-02-11T03:14:33.222646Z","end":"2026-02-11T03:14:36.733521Z","steps":["trace[16767918] 'agreement among raft nodes before linearized reading' (duration: 3.510773709s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:36.73364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:33.222622Z","time spent":"3.511004792s","remote":"127.0.0.1:51034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.200\" "} {"level":"warn","ts":"2026-02-11T03:14:36.733885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.586109564s","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-11T03:14:36.733977Z","caller":"traceutil/trace.go:171","msg":"trace[915804016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:356; }","duration":"3.586348065s","start":"2026-02-11T03:14:33.147617Z","end":"2026-02-11T03:14:36.733965Z","steps":["trace[915804016] 'agreement among raft nodes before linearized reading' (duration: 3.586231374s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:36.734066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:33.147589Z","time spent":"3.586466516s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:14:36.734313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.974465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-11T03:14:36.734397Z","caller":"traceutil/trace.go:171","msg":"trace[1268685970] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:356; }","duration":"251.085876ms","start":"2026-02-11T03:14:36.483301Z","end":"2026-02-11T03:14:36.734387Z","steps":["trace[1268685970] 'agreement among raft nodes before linearized reading' (duration: 250.964815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:37.122868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.795029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:14:37.123245Z","caller":"traceutil/trace.go:171","msg":"trace[2021081261] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:365; }","duration":"368.712572ms","start":"2026-02-11T03:14:36.754517Z","end":"2026-02-11T03:14:37.123229Z","steps":["trace[2021081261] 'read index received' (duration: 110.422659ms)","trace[2021081261] 'applied index is now lower than readState.Index' (duration: 258.288903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:37.123336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.812033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:37.123367Z","caller":"traceutil/trace.go:171","msg":"trace[272292045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:360; }","duration":"368.861833ms","start":"2026-02-11T03:14:36.754497Z","end":"2026-02-11T03:14:37.123359Z","steps":["trace[272292045] 'agreement among raft nodes before linearized reading' (duration: 368.791243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:37.123402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:36.75449Z","time spent":"368.903374ms","remote":"127.0.0.1:51010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T03:14:37.124223Z","caller":"traceutil/trace.go:171","msg":"trace[1835355856] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"373.943111ms","start":"2026-02-11T03:14:36.750243Z","end":"2026-02-11T03:14:37.124186Z","steps":["trace[1835355856] 'process raft request' (duration: 372.858602ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:37.124528Z","caller":"traceutil/trace.go:171","msg":"trace[1169017408] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"372.291539ms","start":"2026-02-11T03:14:36.752221Z","end":"2026-02-11T03:14:37.124512Z","steps":["trace[1169017408] 'process raft request' (duration: 370.940829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:37.124834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:36.752212Z","time spent":"372.539491ms","remote":"127.0.0.1:51074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:14:37.125229Z","caller":"traceutil/trace.go:171","msg":"trace[150213199] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"370.749459ms","start":"2026-02-11T03:14:36.754465Z","end":"2026-02-11T03:14:37.125214Z","steps":["trace[150213199] 'process raft request' (duration: 368.730033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:37.125444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:36.750225Z","time spent":"374.238043ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:37.125488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:36.754456Z","time spent":"370.9593ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:14:37.125657Z","caller":"traceutil/trace.go:171","msg":"trace[375862420] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"381.440155ms","start":"2026-02-11T03:14:36.744203Z","end":"2026-02-11T03:14:37.125643Z","steps":["trace[375862420] 'process raft request' (duration: 120.793193ms)","trace[375862420] 'compare' (duration: 257.542537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:37.126206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:36.744154Z","time spent":"381.984691ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:37.125682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:36.744653Z","time spent":"381.027732ms","remote":"127.0.0.1:51034","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T03:14:37.356303Z","caller":"traceutil/trace.go:171","msg":"trace[1978348640] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"205.465221ms","start":"2026-02-11T03:14:37.150818Z","end":"2026-02-11T03:14:37.356283Z","steps":["trace[1978348640] 'read index received' (duration: 141.747339ms)","trace[1978348640] 'applied index is now lower than readState.Index' (duration: 63.717152ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:37.356536Z","caller":"traceutil/trace.go:171","msg":"trace[527035708] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"220.113659ms","start":"2026-02-11T03:14:37.13641Z","end":"2026-02-11T03:14:37.356524Z","steps":["trace[527035708] 'process raft request' (duration: 156.236205ms)","trace[527035708] 'compare' (duration: 63.536711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:37.357501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.6141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-11T03:14:37.357532Z","caller":"traceutil/trace.go:171","msg":"trace[1983771309] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:362; }","duration":"206.744551ms","start":"2026-02-11T03:14:37.150779Z","end":"2026-02-11T03:14:37.357524Z","steps":["trace[1983771309] 'agreement among raft nodes before linearized reading' (duration: 206.61889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:38.018076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.02801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:14:38.018179Z","caller":"traceutil/trace.go:171","msg":"trace[2078735497] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"574.534997ms","start":"2026-02-11T03:14:37.443625Z","end":"2026-02-11T03:14:38.01816Z","steps":["trace[2078735497] 'process raft request' (duration: 436.347466ms)","trace[2078735497] 'compare' (duration: 137.870199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:38.018242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:37.443598Z","time spent":"574.612358ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:14:38.055584Z","caller":"traceutil/trace.go:171","msg":"trace[1254494848] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"607.882317ms","start":"2026-02-11T03:14:37.447681Z","end":"2026-02-11T03:14:38.055563Z","steps":["trace[1254494848] 'process raft request' (duration: 607.558174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:38.055711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:37.447664Z","time spent":"607.996918ms","remote":"127.0.0.1:51074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":755,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:14:38.654033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.217617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:14:38.654139Z","caller":"traceutil/trace.go:171","msg":"trace[736844272] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"345.789737ms","start":"2026-02-11T03:14:38.308334Z","end":"2026-02-11T03:14:38.654123Z","steps":["trace[736844272] 'read index received' (duration: 240.526411ms)","trace[736844272] 'applied index is now lower than readState.Index' (duration: 105.258266ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:38.654209Z","caller":"traceutil/trace.go:171","msg":"trace[1040019798] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"590.246805ms","start":"2026-02-11T03:14:38.063947Z","end":"2026-02-11T03:14:38.654193Z","steps":["trace[1040019798] 'process raft request' (duration: 484.786966ms)","trace[1040019798] 'compare' (duration: 105.088526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:38.65427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:38.063924Z","time spent":"590.317295ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:38.654654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.313522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:38.654703Z","caller":"traceutil/trace.go:171","msg":"trace[1052927540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"346.398973ms","start":"2026-02-11T03:14:38.308292Z","end":"2026-02-11T03:14:38.654691Z","steps":["trace[1052927540] 'agreement among raft nodes before linearized reading' (duration: 346.313943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:38.654855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:38.308271Z","time spent":"346.573645ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T03:14:38.762676Z","caller":"traceutil/trace.go:171","msg":"trace[504104992] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:376; }","duration":"108.482266ms","start":"2026-02-11T03:14:38.654164Z","end":"2026-02-11T03:14:38.762646Z","steps":["trace[504104992] 'read index received' (duration: 21.134258ms)","trace[504104992] 'applied index is now lower than readState.Index' (duration: 87.346638ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:38.762905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.583462ms","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-11T03:14:38.762976Z","caller":"traceutil/trace.go:171","msg":"trace[1409392809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:366; }","duration":"276.677843ms","start":"2026-02-11T03:14:38.486252Z","end":"2026-02-11T03:14:38.762929Z","steps":["trace[1409392809] 'agreement among raft nodes before linearized reading' (duration: 276.544832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:38.763414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.715412ms","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-11T03:14:38.76345Z","caller":"traceutil/trace.go:171","msg":"trace[1860626851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"277.790022ms","start":"2026-02-11T03:14:38.48565Z","end":"2026-02-11T03:14:38.76344Z","steps":["trace[1860626851] 'agreement among raft nodes before linearized reading' (duration: 277.717952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:38.763722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.94286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:38.76376Z","caller":"traceutil/trace.go:171","msg":"trace[109429686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"107.981741ms","start":"2026-02-11T03:14:38.655768Z","end":"2026-02-11T03:14:38.76375Z","steps":["trace[109429686] 'agreement among raft nodes before linearized reading' (duration: 107.89209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:38.76429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.869804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-11T03:14:38.76433Z","caller":"traceutil/trace.go:171","msg":"trace[849682436] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:366; }","duration":"104.951364ms","start":"2026-02-11T03:14:38.659367Z","end":"2026-02-11T03:14:38.764318Z","steps":["trace[849682436] 'agreement among raft nodes before linearized reading' (duration: 104.792503ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:39.295192Z","caller":"traceutil/trace.go:171","msg":"trace[770099999] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"134.735254ms","start":"2026-02-11T03:14:39.160433Z","end":"2026-02-11T03:14:39.295169Z","steps":["trace[770099999] 'process raft request' (duration: 134.421791ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:39.309495Z","caller":"traceutil/trace.go:171","msg":"trace[771330004] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"138.124548ms","start":"2026-02-11T03:14:39.171354Z","end":"2026-02-11T03:14:39.309479Z","steps":["trace[771330004] 'process raft request' (duration: 138.066637ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:40.186356Z","caller":"traceutil/trace.go:171","msg":"trace[1118943484] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"403.828434ms","start":"2026-02-11T03:14:39.782511Z","end":"2026-02-11T03:14:40.186339Z","steps":["trace[1118943484] 'process raft request' (duration: 403.722193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:40.186682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:39.7825Z","time spent":"404.108827ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4400,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:14:40.343741Z","caller":"traceutil/trace.go:171","msg":"trace[179051956] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"152.469716ms","start":"2026-02-11T03:14:40.191255Z","end":"2026-02-11T03:14:40.343725Z","steps":["trace[179051956] 'process raft request' (duration: 151.972851ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:41.358186Z","caller":"traceutil/trace.go:171","msg":"trace[372105860] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"196.293473ms","start":"2026-02-11T03:14:41.161874Z","end":"2026-02-11T03:14:41.358167Z","steps":["trace[372105860] 'process raft request' (duration: 196.101951ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:41.748611Z","caller":"traceutil/trace.go:171","msg":"trace[1985318279] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"385.774429ms","start":"2026-02-11T03:14:41.362817Z","end":"2026-02-11T03:14:41.748591Z","steps":["trace[1985318279] 'process raft request' (duration: 358.850651ms)","trace[1985318279] 'compare' (duration: 26.626685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:41.748754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:41.362796Z","time spent":"385.89888ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:43.395733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.321071ms","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-11T03:14:43.395906Z","caller":"traceutil/trace.go:171","msg":"trace[601002667] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:382; }","duration":"397.517944ms","start":"2026-02-11T03:14:42.998367Z","end":"2026-02-11T03:14:43.395885Z","steps":["trace[601002667] 'range keys from in-memory index tree' (duration: 397.302191ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:43.396219Z","caller":"traceutil/trace.go:171","msg":"trace[452112482] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"172.872246ms","start":"2026-02-11T03:14:43.223337Z","end":"2026-02-11T03:14:43.396209Z","steps":["trace[452112482] 'read index received' (duration: 131.108741ms)","trace[452112482] 'applied index is now lower than readState.Index' (duration: 41.762105ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:43.396491Z","caller":"traceutil/trace.go:171","msg":"trace[446743001] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"482.24136ms","start":"2026-02-11T03:14:42.914236Z","end":"2026-02-11T03:14:43.396478Z","steps":["trace[446743001] 'process raft request' (duration: 440.280733ms)","trace[446743001] 'compare' (duration: 41.048896ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:43.396598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:42.914218Z","time spent":"482.302161ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:43.396766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.430493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T03:14:43.396825Z","caller":"traceutil/trace.go:171","msg":"trace[31867796] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:383; }","duration":"173.529415ms","start":"2026-02-11T03:14:43.223289Z","end":"2026-02-11T03:14:43.396818Z","steps":["trace[31867796] 'agreement among raft nodes before linearized reading' (duration: 173.447023ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:43.530518Z","caller":"traceutil/trace.go:171","msg":"trace[1818461680] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"129.047404ms","start":"2026-02-11T03:14:43.401379Z","end":"2026-02-11T03:14:43.530427Z","steps":["trace[1818461680] 'process raft request' (duration: 120.658665ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:43.576729Z","caller":"traceutil/trace.go:171","msg":"trace[296354607] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"104.084438ms","start":"2026-02-11T03:14:43.472628Z","end":"2026-02-11T03:14:43.576712Z","steps":["trace[296354607] 'process raft request' (duration: 103.951587ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:45.825985Z","caller":"traceutil/trace.go:171","msg":"trace[1271572375] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"288.854642ms","start":"2026-02-11T03:14:45.537115Z","end":"2026-02-11T03:14:45.82597Z","steps":["trace[1271572375] 'read index received' (duration: 288.73691ms)","trace[1271572375] 'applied index is now lower than readState.Index' (duration: 117.172µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:14:45.826809Z","caller":"traceutil/trace.go:171","msg":"trace[1278744073] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"420.865663ms","start":"2026-02-11T03:14:45.405934Z","end":"2026-02-11T03:14:45.8268Z","steps":["trace[1278744073] 'process raft request' (duration: 419.951239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:14:45.827179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:14:45.405916Z","time spent":"420.921053ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:14:45.827287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.170992ms","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-11T03:14:45.827584Z","caller":"traceutil/trace.go:171","msg":"trace[756594688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"290.394815ms","start":"2026-02-11T03:14:45.537079Z","end":"2026-02-11T03:14:45.827474Z","steps":["trace[756594688] 'agreement among raft nodes before linearized reading' (duration: 290.189872ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:48.02507Z","caller":"traceutil/trace.go:171","msg":"trace[347558858] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"181.519004ms","start":"2026-02-11T03:14:47.843509Z","end":"2026-02-11T03:14:48.025028Z","steps":["trace[347558858] 'process raft request' (duration: 181.351971ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:48.031231Z","caller":"traceutil/trace.go:171","msg":"trace[1098868925] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"184.226962ms","start":"2026-02-11T03:14:47.846985Z","end":"2026-02-11T03:14:48.031212Z","steps":["trace[1098868925] 'process raft request' (duration: 183.176165ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:48.032195Z","caller":"traceutil/trace.go:171","msg":"trace[839874599] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"183.626484ms","start":"2026-02-11T03:14:47.848552Z","end":"2026-02-11T03:14:48.032179Z","steps":["trace[839874599] 'process raft request' (duration: 181.743372ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:14:53.414762Z","caller":"traceutil/trace.go:171","msg":"trace[1098540343] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"111.102958ms","start":"2026-02-11T03:14:53.303643Z","end":"2026-02-11T03:14:53.414746Z","steps":["trace[1098540343] 'read index received' (duration: 34.79259ms)","trace[1098540343] 'applied index is now lower than readState.Index' (duration: 76.309538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:14:53.414845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.18529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:14:53.414861Z","caller":"traceutil/trace.go:171","msg":"trace[336558117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:435; }","duration":"111.244591ms","start":"2026-02-11T03:14:53.303611Z","end":"2026-02-11T03:14:53.414856Z","steps":["trace[336558117] 'agreement among raft nodes before linearized reading' (duration: 111.19401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:01.236399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.643579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:01.236488Z","caller":"traceutil/trace.go:171","msg":"trace[1021719082] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"235.132134ms","start":"2026-02-11T03:15:01.001341Z","end":"2026-02-11T03:15:01.236473Z","steps":["trace[1021719082] 'read index received' (duration: 15.29504ms)","trace[1021719082] 'applied index is now lower than readState.Index' (duration: 219.836134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:01.236576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.227876ms","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-11T03:15:01.236601Z","caller":"traceutil/trace.go:171","msg":"trace[1469515323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:456; }","duration":"235.283478ms","start":"2026-02-11T03:15:01.001309Z","end":"2026-02-11T03:15:01.236592Z","steps":["trace[1469515323] 'agreement among raft nodes before linearized reading' (duration: 235.204746ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:01.237606Z","caller":"traceutil/trace.go:171","msg":"trace[177526838] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"694.768785ms","start":"2026-02-11T03:15:00.54282Z","end":"2026-02-11T03:15:01.237589Z","steps":["trace[177526838] 'process raft request' (duration: 473.877223ms)","trace[177526838] 'compare' (duration: 219.349502ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:01.237714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:00.542807Z","time spent":"694.837756ms","remote":"127.0.0.1:51116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6519,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:01.701229Z","caller":"traceutil/trace.go:171","msg":"trace[1400465245] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"456.96618ms","start":"2026-02-11T03:15:01.24425Z","end":"2026-02-11T03:15:01.701217Z","steps":["trace[1400465245] 'process raft request' (duration: 456.853417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:01.701346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:01.244228Z","time spent":"457.072002ms","remote":"127.0.0.1:51356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:01.704057Z","caller":"traceutil/trace.go:171","msg":"trace[647500] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"460.076398ms","start":"2026-02-11T03:15:01.243942Z","end":"2026-02-11T03:15:01.704018Z","steps":["trace[647500] 'process raft request' (duration: 445.331192ms)","trace[647500] 'compare' (duration: 11.73034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:01.704204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:01.243902Z","time spent":"460.229312ms","remote":"127.0.0.1:51356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:01.704796Z","caller":"traceutil/trace.go:171","msg":"trace[678389324] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"460.009347ms","start":"2026-02-11T03:15:01.244774Z","end":"2026-02-11T03:15:01.704783Z","steps":["trace[678389324] 'process raft request' (duration: 456.355124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:01.704875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:01.244754Z","time spent":"460.079209ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:01.701184Z","caller":"traceutil/trace.go:171","msg":"trace[1592000447] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:472; }","duration":"387.600658ms","start":"2026-02-11T03:15:01.313566Z","end":"2026-02-11T03:15:01.701166Z","steps":["trace[1592000447] 'read index received' (duration: 375.716774ms)","trace[1592000447] 'applied index is now lower than readState.Index' (duration: 11.883264ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:01.707185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.608171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:01.707644Z","caller":"traceutil/trace.go:171","msg":"trace[1406094373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:459; }","duration":"393.682633ms","start":"2026-02-11T03:15:01.313529Z","end":"2026-02-11T03:15:01.707212Z","steps":["trace[1406094373] 'agreement among raft nodes before linearized reading' (duration: 393.518889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:01.707737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:01.313514Z","time spent":"394.174066ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:15:02.108192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.490713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:02.108341Z","caller":"traceutil/trace.go:171","msg":"trace[51930038] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"397.38583ms","start":"2026-02-11T03:15:01.710942Z","end":"2026-02-11T03:15:02.108328Z","steps":["trace[51930038] 'process raft request' (duration: 397.320847ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:02.108404Z","caller":"traceutil/trace.go:171","msg":"trace[427612029] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"397.571385ms","start":"2026-02-11T03:15:01.710824Z","end":"2026-02-11T03:15:02.108396Z","steps":["trace[427612029] 'read index received' (duration: 174.527057ms)","trace[427612029] 'applied index is now lower than readState.Index' (duration: 223.043408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:02.108408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:01.710934Z","time spent":"397.454832ms","remote":"127.0.0.1:51362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:02.108461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.629206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:02.108475Z","caller":"traceutil/trace.go:171","msg":"trace[1233438473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"397.665798ms","start":"2026-02-11T03:15:01.710803Z","end":"2026-02-11T03:15:02.108469Z","steps":["trace[1233438473] 'agreement among raft nodes before linearized reading' (duration: 397.616676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:02.108489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:01.710796Z","time spent":"397.689588ms","remote":"127.0.0.1:51010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T03:15:02.10856Z","caller":"traceutil/trace.go:171","msg":"trace[74973853] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"398.010426ms","start":"2026-02-11T03:15:01.710544Z","end":"2026-02-11T03:15:02.108555Z","steps":["trace[74973853] 'process raft request' (duration: 174.882086ms)","trace[74973853] 'compare' (duration: 222.38886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:02.108607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:01.710528Z","time spent":"398.043256ms","remote":"127.0.0.1:51362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":734,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:02.525523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.084099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:02.525653Z","caller":"traceutil/trace.go:171","msg":"trace[2145565570] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"315.276328ms","start":"2026-02-11T03:15:02.210304Z","end":"2026-02-11T03:15:02.525581Z","steps":["trace[2145565570] 'process raft request' (duration: 92.050295ms)","trace[2145565570] 'compare' (duration: 222.941426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:02.525703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:02.210286Z","time spent":"315.398803ms","remote":"127.0.0.1:51342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:02.593005Z","caller":"traceutil/trace.go:171","msg":"trace[196117801] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:478; }","duration":"348.882467ms","start":"2026-02-11T03:15:02.244098Z","end":"2026-02-11T03:15:02.592981Z","steps":["trace[196117801] 'read index received' (duration: 58.368045ms)","trace[196117801] 'applied index is now lower than readState.Index' (duration: 290.513342ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:02.593324Z","caller":"traceutil/trace.go:171","msg":"trace[1416997303] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"382.897907ms","start":"2026-02-11T03:15:02.210406Z","end":"2026-02-11T03:15:02.593304Z","steps":["trace[1416997303] 'process raft request' (duration: 382.384743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:02.593531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:02.21038Z","time spent":"382.997729ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:02.593546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.436742ms","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-11T03:15:02.593876Z","caller":"traceutil/trace.go:171","msg":"trace[1293647252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"349.77144ms","start":"2026-02-11T03:15:02.244059Z","end":"2026-02-11T03:15:02.59383Z","steps":["trace[1293647252] 'agreement among raft nodes before linearized reading' (duration: 349.271457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:02.59412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:02.244042Z","time spent":"350.062458ms","remote":"127.0.0.1:51258","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-11T03:15:02.922802Z","caller":"traceutil/trace.go:171","msg":"trace[1304707507] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"389.95328ms","start":"2026-02-11T03:15:02.532822Z","end":"2026-02-11T03:15:02.922776Z","steps":["trace[1304707507] 'process raft request' (duration: 389.778146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:02.923215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:02.532804Z","time spent":"390.33876ms","remote":"127.0.0.1:51496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14059,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:02.923463Z","caller":"traceutil/trace.go:171","msg":"trace[1649307739] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:481; }","duration":"330.138586ms","start":"2026-02-11T03:15:02.593307Z","end":"2026-02-11T03:15:02.923445Z","steps":["trace[1649307739] 'read index received' (duration: 330.104216ms)","trace[1649307739] 'applied index is now lower than readState.Index' (duration: 32.99µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:02.923567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.456776ms","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-11T03:15:02.926366Z","caller":"traceutil/trace.go:171","msg":"trace[982874304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:465; }","duration":"664.28555ms","start":"2026-02-11T03:15:02.262064Z","end":"2026-02-11T03:15:02.92635Z","steps":["trace[982874304] 'agreement among raft nodes before linearized reading' (duration: 661.426896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:02.926788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:02.262048Z","time spent":"664.702291ms","remote":"127.0.0.1:51258","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-11T03:15:02.998072Z","caller":"traceutil/trace.go:171","msg":"trace[713721003] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"397.608071ms","start":"2026-02-11T03:15:02.600441Z","end":"2026-02-11T03:15:02.998049Z","steps":["trace[713721003] 'process raft request' (duration: 397.482537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:02.998596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:02.600419Z","time spent":"398.088513ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:03.138113Z","caller":"traceutil/trace.go:171","msg":"trace[1942213207] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"200.878949ms","start":"2026-02-11T03:15:02.937214Z","end":"2026-02-11T03:15:03.138093Z","steps":["trace[1942213207] 'process raft request' (duration: 200.844028ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:03.138638Z","caller":"traceutil/trace.go:171","msg":"trace[306526967] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"206.838304ms","start":"2026-02-11T03:15:02.931788Z","end":"2026-02-11T03:15:03.138626Z","steps":["trace[306526967] 'process raft request' (duration: 204.383028ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:03.138811Z","caller":"traceutil/trace.go:171","msg":"trace[2088204277] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"203.376224ms","start":"2026-02-11T03:15:02.935425Z","end":"2026-02-11T03:15:03.138801Z","steps":["trace[2088204277] 'process raft request' (duration: 202.585133ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:06.131915Z","caller":"traceutil/trace.go:171","msg":"trace[974188582] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"407.823556ms","start":"2026-02-11T03:15:05.724005Z","end":"2026-02-11T03:15:06.131828Z","steps":["trace[974188582] 'process raft request' (duration: 407.673002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:06.132055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:05.723994Z","time spent":"407.98855ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:06.992288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.32187ms","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-11T03:15:06.992358Z","caller":"traceutil/trace.go:171","msg":"trace[856631076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"380.434093ms","start":"2026-02-11T03:15:06.611908Z","end":"2026-02-11T03:15:06.992342Z","steps":["trace[856631076] 'range keys from in-memory index tree' (duration: 380.183076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:06.992401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:06.61189Z","time spent":"380.504296ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T03:15:07.909487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.355471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:07.909543Z","caller":"traceutil/trace.go:171","msg":"trace[1427560723] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"749.745042ms","start":"2026-02-11T03:15:07.159789Z","end":"2026-02-11T03:15:07.909534Z","steps":["trace[1427560723] 'read index received' (duration: 68.239117ms)","trace[1427560723] 'applied index is now lower than readState.Index' (duration: 681.505335ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:07.909599Z","caller":"traceutil/trace.go:171","msg":"trace[1981571686] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"788.491535ms","start":"2026-02-11T03:15:07.121101Z","end":"2026-02-11T03:15:07.909593Z","steps":["trace[1981571686] 'process raft request' (duration: 106.976319ms)","trace[1981571686] 'compare' (duration: 681.225258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:07.909625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:07.121078Z","time spent":"788.533056ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:07.909662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.440286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:07.909703Z","caller":"traceutil/trace.go:171","msg":"trace[1240371494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"601.479507ms","start":"2026-02-11T03:15:07.308211Z","end":"2026-02-11T03:15:07.90969Z","steps":["trace[1240371494] 'agreement among raft nodes before linearized reading' (duration: 601.419905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:07.909734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:07.308153Z","time spent":"601.57399ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:15:07.909807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.02244ms","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-11T03:15:07.909821Z","caller":"traceutil/trace.go:171","msg":"trace[1148651044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"750.062941ms","start":"2026-02-11T03:15:07.159754Z","end":"2026-02-11T03:15:07.909817Z","steps":["trace[1148651044] 'agreement among raft nodes before linearized reading' (duration: 749.99948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:07.909835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:07.159739Z","time spent":"750.093043ms","remote":"127.0.0.1:51258","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-11T03:15:08.810012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.654157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:08.810105Z","caller":"traceutil/trace.go:171","msg":"trace[1943099383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"501.789561ms","start":"2026-02-11T03:15:08.308297Z","end":"2026-02-11T03:15:08.810087Z","steps":["trace[1943099383] 'range keys from in-memory index tree' (duration: 501.556503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:08.810167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:08.308266Z","time spent":"501.891134ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:15:14.73777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085581864330402,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T03:15:14.890108Z","caller":"traceutil/trace.go:171","msg":"trace[851594279] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"653.078145ms","start":"2026-02-11T03:15:14.237009Z","end":"2026-02-11T03:15:14.890087Z","steps":["trace[851594279] 'read index received' (duration: 652.91113ms)","trace[851594279] 'applied index is now lower than readState.Index' (duration: 166.125µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:14.890326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.294513ms","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-11T03:15:14.89036Z","caller":"traceutil/trace.go:171","msg":"trace[901550101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"653.366815ms","start":"2026-02-11T03:15:14.236983Z","end":"2026-02-11T03:15:14.89035Z","steps":["trace[901550101] 'agreement among raft nodes before linearized reading' (duration: 653.184449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:14.890394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:14.236969Z","time spent":"653.417817ms","remote":"127.0.0.1:51258","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-11T03:15:14.890657Z","caller":"traceutil/trace.go:171","msg":"trace[208869023] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"914.650527ms","start":"2026-02-11T03:15:13.975995Z","end":"2026-02-11T03:15:14.890645Z","steps":["trace[208869023] 'process raft request' (duration: 913.978986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:14.890753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:13.975985Z","time spent":"914.697679ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:17.232815Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.341637219s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T03:15:17.233119Z","caller":"traceutil/trace.go:171","msg":"trace[1485056263] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"337.932726ms","start":"2026-02-11T03:15:16.895167Z","end":"2026-02-11T03:15:17.2331Z","steps":["trace[1485056263] 'read index received' (duration: 337.764089ms)","trace[1485056263] 'applied index is now lower than readState.Index' (duration: 167.906µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:17.233674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.540139ms","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-11T03:15:17.233726Z","caller":"traceutil/trace.go:171","msg":"trace[1890531747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"322.603641ms","start":"2026-02-11T03:15:16.911113Z","end":"2026-02-11T03:15:17.233717Z","steps":["trace[1890531747] 'agreement among raft nodes before linearized reading' (duration: 322.462676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:17.233771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:16.911055Z","time spent":"322.706605ms","remote":"127.0.0.1:51258","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-11T03:15:17.233505Z","caller":"traceutil/trace.go:171","msg":"trace[1092754784] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.342471869s","start":"2026-02-11T03:15:15.891021Z","end":"2026-02-11T03:15:17.233493Z","steps":["trace[1092754784] 'process raft request' (duration: 1.341972742s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:17.234192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:15.891002Z","time spent":"1.34313601s","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:17.324021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.835018ms","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-11T03:15:17.324072Z","caller":"traceutil/trace.go:171","msg":"trace[547901966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"428.918271ms","start":"2026-02-11T03:15:16.895141Z","end":"2026-02-11T03:15:17.32406Z","steps":["trace[547901966] 'agreement among raft nodes before linearized reading' (duration: 339.301032ms)","trace[547901966] 'range keys from in-memory index tree' (duration: 89.488415ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:17.324097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:16.895128Z","time spent":"428.963753ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:15:17.324101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.921696ms","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-11T03:15:17.324177Z","caller":"traceutil/trace.go:171","msg":"trace[1649133815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"181.04295ms","start":"2026-02-11T03:15:17.143111Z","end":"2026-02-11T03:15:17.324154Z","steps":["trace[1649133815] 'agreement among raft nodes before linearized reading' (duration: 91.336388ms)","trace[1649133815] 'range keys from in-memory index tree' (duration: 89.500585ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:17.324534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.897262ms","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-11T03:15:17.324572Z","caller":"traceutil/trace.go:171","msg":"trace[635046961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"231.953363ms","start":"2026-02-11T03:15:17.092604Z","end":"2026-02-11T03:15:17.324557Z","steps":["trace[635046961] 'agreement among raft nodes before linearized reading' (duration: 141.831726ms)","trace[635046961] 'range keys from in-memory index tree' (duration: 89.522956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:19.267178Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.739802129s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T03:15:19.740847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.276358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:19.741104Z","caller":"traceutil/trace.go:171","msg":"trace[1064370476] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"2.412298985s","start":"2026-02-11T03:15:17.328791Z","end":"2026-02-11T03:15:19.74109Z","steps":["trace[1064370476] 'process raft request' (duration: 1.938741777s)","trace[1064370476] 'compare' (duration: 473.172125ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:19.741142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:17.328773Z","time spent":"2.412352227s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:19.741296Z","caller":"traceutil/trace.go:171","msg":"trace[1528193091] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"2.40947964s","start":"2026-02-11T03:15:17.331811Z","end":"2026-02-11T03:15:19.741291Z","steps":["trace[1528193091] 'process raft request' (duration: 2.409088857s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:19.74132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:17.331795Z","time spent":"2.409511612s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:19.741409Z","caller":"traceutil/trace.go:171","msg":"trace[830658463] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.768068516s","start":"2026-02-11T03:15:17.973335Z","end":"2026-02-11T03:15:19.741404Z","steps":["trace[830658463] 'process raft request' (duration: 1.767589369s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:19.741431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:17.973318Z","time spent":"1.768100397s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:20.562402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.444043ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T03:15:20.56252Z","caller":"traceutil/trace.go:171","msg":"trace[1775435767] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:567; }","duration":"1.258484006s","start":"2026-02-11T03:15:19.304003Z","end":"2026-02-11T03:15:20.562487Z","steps":["trace[1775435767] 'read index received' (duration: 29.907156ms)","trace[1775435767] 'applied index is now lower than readState.Index' (duration: 1.22857545s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:20.563606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259562194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:20.56374Z","caller":"traceutil/trace.go:171","msg":"trace[1031594131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"1.25975114s","start":"2026-02-11T03:15:19.303973Z","end":"2026-02-11T03:15:20.563724Z","steps":["trace[1031594131] 'agreement among raft nodes before linearized reading' (duration: 1.259555553s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.564034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231330827s","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-11T03:15:20.56409Z","caller":"traceutil/trace.go:171","msg":"trace[974231154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"1.231412209s","start":"2026-02-11T03:15:19.33267Z","end":"2026-02-11T03:15:20.564082Z","steps":["trace[974231154] 'agreement among raft nodes before linearized reading' (duration: 1.231261384s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.564117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.332657Z","time spent":"1.231454421s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T03:15:20.564315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226029222s","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-11T03:15:20.564339Z","caller":"traceutil/trace.go:171","msg":"trace[2130585443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"1.226070593s","start":"2026-02-11T03:15:19.338262Z","end":"2026-02-11T03:15:20.564333Z","steps":["trace[2130585443] 'agreement among raft nodes before linearized reading' (duration: 1.22600678s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.564356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.338254Z","time spent":"1.226098803s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:15:20.563787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.303956Z","time spent":"1.259818972s","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T03:15:21.864055Z","caller":"traceutil/trace.go:171","msg":"trace[1790972926] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"112.405189ms","start":"2026-02-11T03:15:21.751629Z","end":"2026-02-11T03:15:21.864035Z","steps":["trace[1790972926] 'process raft request' (duration: 112.288665ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:21.873522Z","caller":"traceutil/trace.go:171","msg":"trace[679528108] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"118.748186ms","start":"2026-02-11T03:15:21.754751Z","end":"2026-02-11T03:15:21.873499Z","steps":["trace[679528108] 'process raft request' (duration: 118.647402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:22.428607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.3142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:22.428663Z","caller":"traceutil/trace.go:171","msg":"trace[1913338681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"121.414833ms","start":"2026-02-11T03:15:22.307235Z","end":"2026-02-11T03:15:22.42865Z","steps":["trace[1913338681] 'range keys from in-memory index tree' (duration: 121.253107ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:23.174398Z","caller":"traceutil/trace.go:171","msg":"trace[829060098] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"175.722025ms","start":"2026-02-11T03:15:22.99866Z","end":"2026-02-11T03:15:23.174382Z","steps":["trace[829060098] 'process raft request' (duration: 175.646242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.039881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085581864330473,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T03:15:25.278949Z","caller":"traceutil/trace.go:171","msg":"trace[594101144] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"817.81913ms","start":"2026-02-11T03:15:24.461108Z","end":"2026-02-11T03:15:25.278927Z","steps":["trace[594101144] 'process raft request' (duration: 817.723906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.279028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:24.461092Z","time spent":"817.889822ms","remote":"127.0.0.1:51074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:25.279037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.40565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-11T03:15:25.279061Z","caller":"traceutil/trace.go:171","msg":"trace[860803518] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:576; }","duration":"739.460172ms","start":"2026-02-11T03:15:24.539593Z","end":"2026-02-11T03:15:25.279053Z","steps":["trace[860803518] 'agreement among raft nodes before linearized reading' (duration: 739.373088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.27908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:24.539579Z","time spent":"739.495192ms","remote":"127.0.0.1:51120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-11T03:15:25.278931Z","caller":"traceutil/trace.go:171","msg":"trace[1775494365] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"739.290965ms","start":"2026-02-11T03:15:24.539624Z","end":"2026-02-11T03:15:25.278915Z","steps":["trace[1775494365] 'read index received' (duration: 739.164541ms)","trace[1775494365] 'applied index is now lower than readState.Index' (duration: 125.614µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:25.279285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.975223ms","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-11T03:15:25.279307Z","caller":"traceutil/trace.go:171","msg":"trace[1345314801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"604.018035ms","start":"2026-02-11T03:15:24.675283Z","end":"2026-02-11T03:15:25.279301Z","steps":["trace[1345314801] 'agreement among raft nodes before linearized reading' (duration: 603.940062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.279326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:24.675269Z","time spent":"604.051785ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:15:25.279386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.973052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-m8xf6\" ","response":"range_response_count:1 size:4639"} {"level":"info","ts":"2026-02-11T03:15:25.279409Z","caller":"traceutil/trace.go:171","msg":"trace[1548598201] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-m8xf6; range_end:; response_count:1; response_revision:576; }","duration":"344.022953ms","start":"2026-02-11T03:15:24.93538Z","end":"2026-02-11T03:15:25.279403Z","steps":["trace[1548598201] 'agreement among raft nodes before linearized reading' (duration: 343.961951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.279427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:24.935363Z","time spent":"344.058835ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4663,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-m8xf6\" "} {"level":"warn","ts":"2026-02-11T03:15:25.279453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.458709ms","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-11T03:15:25.279471Z","caller":"traceutil/trace.go:171","msg":"trace[646017593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"580.494721ms","start":"2026-02-11T03:15:24.698971Z","end":"2026-02-11T03:15:25.279466Z","steps":["trace[646017593] 'agreement among raft nodes before linearized reading' (duration: 580.441129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.279488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:24.698961Z","time spent":"580.523041ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T03:15:25.279286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.366497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:15:25.279565Z","caller":"traceutil/trace.go:171","msg":"trace[496426512] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:576; }","duration":"149.689258ms","start":"2026-02-11T03:15:25.129869Z","end":"2026-02-11T03:15:25.279559Z","steps":["trace[496426512] 'agreement among raft nodes before linearized reading' (duration: 149.379047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:27.343141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.980832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-11T03:15:27.343212Z","caller":"traceutil/trace.go:171","msg":"trace[2084742830] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:619; }","duration":"161.098627ms","start":"2026-02-11T03:15:27.182096Z","end":"2026-02-11T03:15:27.343195Z","steps":["trace[2084742830] 'range keys from in-memory index tree' (duration: 160.841567ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:32.185698Z","caller":"traceutil/trace.go:171","msg":"trace[908736867] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"163.014572ms","start":"2026-02-11T03:15:32.022663Z","end":"2026-02-11T03:15:32.185678Z","steps":["trace[908736867] 'read index received' (duration: 162.804553ms)","trace[908736867] 'applied index is now lower than readState.Index' (duration: 209.129µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:32.185862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.185929ms","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-11T03:15:32.185888Z","caller":"traceutil/trace.go:171","msg":"trace[931456849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"163.263652ms","start":"2026-02-11T03:15:32.022615Z","end":"2026-02-11T03:15:32.185879Z","steps":["trace[931456849] 'agreement among raft nodes before linearized reading' (duration: 163.152117ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:32.185926Z","caller":"traceutil/trace.go:171","msg":"trace[649213063] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"212.728971ms","start":"2026-02-11T03:15:31.973168Z","end":"2026-02-11T03:15:32.185897Z","steps":["trace[649213063] 'process raft request' (duration: 212.379338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:32.334093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.797051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:32.334172Z","caller":"traceutil/trace.go:171","msg":"trace[1351605020] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"142.695595ms","start":"2026-02-11T03:15:32.191461Z","end":"2026-02-11T03:15:32.334157Z","steps":["trace[1351605020] 'process raft request' (duration: 29.774279ms)","trace[1351605020] 'compare' (duration: 112.679207ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:33.592076Z","caller":"traceutil/trace.go:171","msg":"trace[1657083493] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"164.734882ms","start":"2026-02-11T03:15:33.427326Z","end":"2026-02-11T03:15:33.592061Z","steps":["trace[1657083493] 'read index received' (duration: 134.39934ms)","trace[1657083493] 'applied index is now lower than readState.Index' (duration: 30.334942ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:33.592262Z","caller":"traceutil/trace.go:171","msg":"trace[2122274913] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"267.247806ms","start":"2026-02-11T03:15:33.325007Z","end":"2026-02-11T03:15:33.592255Z","steps":["trace[2122274913] 'process raft request' (duration: 236.755798ms)","trace[2122274913] 'compare' (duration: 29.936696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:33.592508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.17534ms","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-11T03:15:33.59258Z","caller":"traceutil/trace.go:171","msg":"trace[831347634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:677; }","duration":"165.277224ms","start":"2026-02-11T03:15:33.427297Z","end":"2026-02-11T03:15:33.592574Z","steps":["trace[831347634] 'agreement among raft nodes before linearized reading' (duration: 165.133118ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:33.790063Z","caller":"traceutil/trace.go:171","msg":"trace[1071203707] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"181.465072ms","start":"2026-02-11T03:15:33.608583Z","end":"2026-02-11T03:15:33.790048Z","steps":["trace[1071203707] 'read index received' (duration: 26.249186ms)","trace[1071203707] 'applied index is now lower than readState.Index' (duration: 155.215366ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:33.790491Z","caller":"traceutil/trace.go:171","msg":"trace[1028913199] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"192.963959ms","start":"2026-02-11T03:15:33.59752Z","end":"2026-02-11T03:15:33.790484Z","steps":["trace[1028913199] 'process raft request' (duration: 112.646266ms)","trace[1028913199] 'compare' (duration: 79.819333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:33.790696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.105718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:33.79074Z","caller":"traceutil/trace.go:171","msg":"trace[597066867] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:679; }","duration":"182.192281ms","start":"2026-02-11T03:15:33.608541Z","end":"2026-02-11T03:15:33.790733Z","steps":["trace[597066867] 'agreement among raft nodes before linearized reading' (duration: 182.117468ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:34.172248Z","caller":"traceutil/trace.go:171","msg":"trace[977488357] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"379.749857ms","start":"2026-02-11T03:15:33.792461Z","end":"2026-02-11T03:15:34.172211Z","steps":["trace[977488357] 'process raft request' (duration: 300.943297ms)","trace[977488357] 'compare' (duration: 78.581091ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:34.172489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:33.79245Z","time spent":"379.934174ms","remote":"127.0.0.1:48964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:34.395687Z","caller":"traceutil/trace.go:171","msg":"trace[1234085111] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:704; }","duration":"571.442035ms","start":"2026-02-11T03:15:33.824232Z","end":"2026-02-11T03:15:34.395674Z","steps":["trace[1234085111] 'read index received' (duration: 269.179917ms)","trace[1234085111] 'applied index is now lower than readState.Index' (duration: 302.261538ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:34.395788Z","caller":"traceutil/trace.go:171","msg":"trace[1260310246] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"602.978816ms","start":"2026-02-11T03:15:33.792803Z","end":"2026-02-11T03:15:34.395782Z","steps":["trace[1260310246] 'process raft request' (duration: 589.766834ms)","trace[1260310246] 'compare' (duration: 12.958982ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:34.395834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:33.792797Z","time spent":"603.005767ms","remote":"127.0.0.1:48964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:34.395941Z","caller":"traceutil/trace.go:171","msg":"trace[1594667756] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"595.122817ms","start":"2026-02-11T03:15:33.800813Z","end":"2026-02-11T03:15:34.395935Z","steps":["trace[1594667756] 'process raft request' (duration: 594.794263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:34.395962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:33.800799Z","time spent":"595.154088ms","remote":"127.0.0.1:48964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:34.396066Z","caller":"traceutil/trace.go:171","msg":"trace[1003152765] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"594.262293ms","start":"2026-02-11T03:15:33.801799Z","end":"2026-02-11T03:15:34.396061Z","steps":["trace[1003152765] 'process raft request' (duration: 593.830795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:34.396095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:33.801791Z","time spent":"594.284973ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:34.396179Z","caller":"traceutil/trace.go:171","msg":"trace[2108905748] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"398.0087ms","start":"2026-02-11T03:15:33.998164Z","end":"2026-02-11T03:15:34.396173Z","steps":["trace[2108905748] 'process raft request' (duration: 397.489939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:34.396198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:33.998149Z","time spent":"398.040011ms","remote":"127.0.0.1:48990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1671,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:34.396303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.070811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4kb6w\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-11T03:15:34.396318Z","caller":"traceutil/trace.go:171","msg":"trace[1162096842] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4kb6w; range_end:; response_count:1; response_revision:684; }","duration":"572.107752ms","start":"2026-02-11T03:15:33.824206Z","end":"2026-02-11T03:15:34.396313Z","steps":["trace[1162096842] 'agreement among raft nodes before linearized reading' (duration: 572.045939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:34.396333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:33.824193Z","time spent":"572.135623ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18274,"request content":"key:\"/registry/pods/kube-system/cilium-4kb6w\" "} {"level":"info","ts":"2026-02-11T03:15:34.420939Z","caller":"traceutil/trace.go:171","msg":"trace[1443349811] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"271.509197ms","start":"2026-02-11T03:15:34.149416Z","end":"2026-02-11T03:15:34.420925Z","steps":["trace[1443349811] 'process raft request' (duration: 271.452155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:34.421495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.348328ms","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-11T03:15:34.421573Z","caller":"traceutil/trace.go:171","msg":"trace[1656036451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"228.453562ms","start":"2026-02-11T03:15:34.193111Z","end":"2026-02-11T03:15:34.421564Z","steps":["trace[1656036451] 'agreement among raft nodes before linearized reading' (duration: 228.322977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:34.421725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.571357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:34.421802Z","caller":"traceutil/trace.go:171","msg":"trace[534884577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"116.663621ms","start":"2026-02-11T03:15:34.305132Z","end":"2026-02-11T03:15:34.421796Z","steps":["trace[534884577] 'agreement among raft nodes before linearized reading' (duration: 116.579027ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:34.625383Z","caller":"traceutil/trace.go:171","msg":"trace[308838963] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"211.937166ms","start":"2026-02-11T03:15:34.413432Z","end":"2026-02-11T03:15:34.625369Z","steps":["trace[308838963] 'process raft request' (duration: 211.859163ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:34.625687Z","caller":"traceutil/trace.go:171","msg":"trace[364521160] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:711; }","duration":"201.386324ms","start":"2026-02-11T03:15:34.424295Z","end":"2026-02-11T03:15:34.625681Z","steps":["trace[364521160] 'read index received' (duration: 201.383864ms)","trace[364521160] 'applied index is now lower than readState.Index' (duration: 2.06µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:34.625745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.436886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:34.625759Z","caller":"traceutil/trace.go:171","msg":"trace[1590691688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"201.474937ms","start":"2026-02-11T03:15:34.424279Z","end":"2026-02-11T03:15:34.625754Z","steps":["trace[1590691688] 'agreement among raft nodes before linearized reading' (duration: 201.440016ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:34.711295Z","caller":"traceutil/trace.go:171","msg":"trace[267125660] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"286.321405ms","start":"2026-02-11T03:15:34.424948Z","end":"2026-02-11T03:15:34.711269Z","steps":["trace[267125660] 'process raft request' (duration: 285.938949ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:34.711385Z","caller":"traceutil/trace.go:171","msg":"trace[1487756377] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"280.628852ms","start":"2026-02-11T03:15:34.430751Z","end":"2026-02-11T03:15:34.71138Z","steps":["trace[1487756377] 'process raft request' (duration: 280.203854ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:34.711484Z","caller":"traceutil/trace.go:171","msg":"trace[1817054486] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"275.980361ms","start":"2026-02-11T03:15:34.4355Z","end":"2026-02-11T03:15:34.71148Z","steps":["trace[1817054486] 'process raft request' (duration: 275.472809ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:34.924528Z","caller":"traceutil/trace.go:171","msg":"trace[1481143497] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"198.702054ms","start":"2026-02-11T03:15:34.725811Z","end":"2026-02-11T03:15:34.924513Z","steps":["trace[1481143497] 'process raft request' (duration: 198.329718ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:35.488129Z","caller":"traceutil/trace.go:171","msg":"trace[1549746687] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"549.134867ms","start":"2026-02-11T03:15:34.938971Z","end":"2026-02-11T03:15:35.488106Z","steps":["trace[1549746687] 'process raft request' (duration: 458.847545ms)","trace[1549746687] 'compare' (duration: 90.158977ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:35.488255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:34.938956Z","time spent":"549.240811ms","remote":"127.0.0.1:51496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:35.771122Z","caller":"traceutil/trace.go:171","msg":"trace[2042624525] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:715; }","duration":"698.426151ms","start":"2026-02-11T03:15:35.072681Z","end":"2026-02-11T03:15:35.771107Z","steps":["trace[2042624525] 'read index received' (duration: 325.145261ms)","trace[2042624525] 'applied index is now lower than readState.Index' (duration: 373.28039ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:35.771275Z","caller":"traceutil/trace.go:171","msg":"trace[1518969132] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"828.264628ms","start":"2026-02-11T03:15:34.943004Z","end":"2026-02-11T03:15:35.771269Z","steps":["trace[1518969132] 'process raft request' (duration: 766.715623ms)","trace[1518969132] 'compare' (duration: 61.119207ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:35.771345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:34.94299Z","time spent":"828.31318ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:35.771451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.773256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:35.771472Z","caller":"traceutil/trace.go:171","msg":"trace[1514027676] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:692; }","duration":"698.822058ms","start":"2026-02-11T03:15:35.072645Z","end":"2026-02-11T03:15:35.771467Z","steps":["trace[1514027676] 'agreement among raft nodes before linearized reading' (duration: 698.786936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:35.771487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:35.072631Z","time spent":"698.851898ms","remote":"127.0.0.1:51238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-11T03:15:35.771593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.673358ms","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-11T03:15:35.771611Z","caller":"traceutil/trace.go:171","msg":"trace[922319294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"133.724839ms","start":"2026-02-11T03:15:35.637882Z","end":"2026-02-11T03:15:35.771607Z","steps":["trace[922319294] 'agreement among raft nodes before linearized reading' (duration: 133.665807ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:36.547414Z","caller":"traceutil/trace.go:171","msg":"trace[1116290775] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"107.915429ms","start":"2026-02-11T03:15:36.439453Z","end":"2026-02-11T03:15:36.547368Z","steps":["trace[1116290775] 'process raft request' (duration: 49.588828ms)","trace[1116290775] 'compare' (duration: 58.215337ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:36.832368Z","caller":"traceutil/trace.go:171","msg":"trace[1524488293] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"115.675643ms","start":"2026-02-11T03:15:36.716677Z","end":"2026-02-11T03:15:36.832353Z","steps":["trace[1524488293] 'read index received' (duration: 69.770829ms)","trace[1524488293] 'applied index is now lower than readState.Index' (duration: 45.904194ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:36.832448Z","caller":"traceutil/trace.go:171","msg":"trace[1646911266] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"216.653142ms","start":"2026-02-11T03:15:36.615787Z","end":"2026-02-11T03:15:36.832441Z","steps":["trace[1646911266] 'process raft request' (duration: 170.716346ms)","trace[1646911266] 'compare' (duration: 45.757119ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:36.832646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.956405ms","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-11T03:15:36.832663Z","caller":"traceutil/trace.go:171","msg":"trace[159075458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:698; }","duration":"116.008687ms","start":"2026-02-11T03:15:36.716649Z","end":"2026-02-11T03:15:36.832658Z","steps":["trace[159075458] 'agreement among raft nodes before linearized reading' (duration: 115.903793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:36.832805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.740955ms","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-11T03:15:36.832819Z","caller":"traceutil/trace.go:171","msg":"trace[13744161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:698; }","duration":"115.784256ms","start":"2026-02-11T03:15:36.717031Z","end":"2026-02-11T03:15:36.832815Z","steps":["trace[13744161] 'agreement among raft nodes before linearized reading' (duration: 115.714324ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:37.223274Z","caller":"traceutil/trace.go:171","msg":"trace[1044763174] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"297.429104ms","start":"2026-02-11T03:15:36.925831Z","end":"2026-02-11T03:15:37.22326Z","steps":["trace[1044763174] 'process raft request' (duration: 297.406433ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:37.223515Z","caller":"traceutil/trace.go:171","msg":"trace[127598588] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"382.089993ms","start":"2026-02-11T03:15:36.841418Z","end":"2026-02-11T03:15:37.223508Z","steps":["trace[127598588] 'process raft request' (duration: 323.016691ms)","trace[127598588] 'compare' (duration: 58.714727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:37.223578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:36.841412Z","time spent":"382.124745ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:37.22365Z","caller":"traceutil/trace.go:171","msg":"trace[328406340] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"382.188808ms","start":"2026-02-11T03:15:36.841456Z","end":"2026-02-11T03:15:37.223645Z","steps":["trace[328406340] 'process raft request' (duration: 381.75572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:37.223677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:36.841453Z","time spent":"382.212329ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:37.967456Z","caller":"traceutil/trace.go:171","msg":"trace[1315102728] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"117.326572ms","start":"2026-02-11T03:15:37.850119Z","end":"2026-02-11T03:15:37.967445Z","steps":["trace[1315102728] 'process raft request' (duration: 117.079492ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:38.597593Z","caller":"traceutil/trace.go:171","msg":"trace[261778292] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"162.905112ms","start":"2026-02-11T03:15:38.434673Z","end":"2026-02-11T03:15:38.597578Z","steps":["trace[261778292] 'process raft request' (duration: 162.796647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:39.057326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.005319ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T03:15:39.057722Z","caller":"traceutil/trace.go:171","msg":"trace[229873037] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"210.052717ms","start":"2026-02-11T03:15:38.847656Z","end":"2026-02-11T03:15:39.057708Z","steps":["trace[229873037] 'process raft request' (duration: 209.744014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:39.445128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.493169ms","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-11T03:15:39.445193Z","caller":"traceutil/trace.go:171","msg":"trace[1780522708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:706; }","duration":"214.580482ms","start":"2026-02-11T03:15:39.230602Z","end":"2026-02-11T03:15:39.445183Z","steps":["trace[1780522708] 'range keys from in-memory index tree' (duration: 214.392885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:39.445442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.037129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:15:39.445465Z","caller":"traceutil/trace.go:171","msg":"trace[2141208837] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:706; }","duration":"384.081412ms","start":"2026-02-11T03:15:39.061378Z","end":"2026-02-11T03:15:39.445459Z","steps":["trace[2141208837] 'count revisions from in-memory index tree' (duration: 384.001378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:39.445542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:39.061369Z","time spent":"384.166105ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-11T03:15:39.445694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.359139ms","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-11T03:15:39.445713Z","caller":"traceutil/trace.go:171","msg":"trace[1906481097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:706; }","duration":"216.404821ms","start":"2026-02-11T03:15:39.229303Z","end":"2026-02-11T03:15:39.445708Z","steps":["trace[1906481097] 'range keys from in-memory index tree' (duration: 216.287816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:39.446052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.800109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:39.446074Z","caller":"traceutil/trace.go:171","msg":"trace[1882533500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"139.82104ms","start":"2026-02-11T03:15:39.306247Z","end":"2026-02-11T03:15:39.446068Z","steps":["trace[1882533500] 'range keys from in-memory index tree' (duration: 139.761078ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:39.853327Z","caller":"traceutil/trace.go:171","msg":"trace[593239929] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"403.939058ms","start":"2026-02-11T03:15:39.449369Z","end":"2026-02-11T03:15:39.853308Z","steps":["trace[593239929] 'process raft request' (duration: 308.881279ms)","trace[593239929] 'compare' (duration: 94.963655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:39.853416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:39.44936Z","time spent":"404.00827ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:39.854485Z","caller":"traceutil/trace.go:171","msg":"trace[1528891295] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"402.10005ms","start":"2026-02-11T03:15:39.452374Z","end":"2026-02-11T03:15:39.854474Z","steps":["trace[1528891295] 'process raft request' (duration: 402.063929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:39.854537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:39.452365Z","time spent":"402.146282ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:40.05437Z","caller":"traceutil/trace.go:171","msg":"trace[1139395121] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"108.02967ms","start":"2026-02-11T03:15:39.946318Z","end":"2026-02-11T03:15:40.054348Z","steps":["trace[1139395121] 'read index received' (duration: 15.291164ms)","trace[1139395121] 'applied index is now lower than readState.Index' (duration: 92.737636ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:40.054492Z","caller":"traceutil/trace.go:171","msg":"trace[1493575143] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"159.203394ms","start":"2026-02-11T03:15:39.895266Z","end":"2026-02-11T03:15:40.05447Z","steps":["trace[1493575143] 'process raft request' (duration: 66.378644ms)","trace[1493575143] 'compare' (duration: 92.608921ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:40.054855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.50923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:15:40.054897Z","caller":"traceutil/trace.go:171","msg":"trace[330839895] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:710; }","duration":"108.578623ms","start":"2026-02-11T03:15:39.946308Z","end":"2026-02-11T03:15:40.054886Z","steps":["trace[330839895] 'agreement among raft nodes before linearized reading' (duration: 108.460148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:40.588355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.921544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:40.588478Z","caller":"traceutil/trace.go:171","msg":"trace[1971820289] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"441.32062ms","start":"2026-02-11T03:15:40.147144Z","end":"2026-02-11T03:15:40.588465Z","steps":["trace[1971820289] 'process raft request' (duration: 441.297729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:40.588515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.147117Z","time spent":"441.380252ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:40.588686Z","caller":"traceutil/trace.go:171","msg":"trace[1898885128] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"527.926946ms","start":"2026-02-11T03:15:40.060754Z","end":"2026-02-11T03:15:40.588681Z","steps":["trace[1898885128] 'process raft request' (duration: 250.620674ms)","trace[1898885128] 'compare' (duration: 276.837021ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:40.588714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.060734Z","time spent":"527.965558ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:40.588773Z","caller":"traceutil/trace.go:171","msg":"trace[938377841] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"527.802681ms","start":"2026-02-11T03:15:40.060966Z","end":"2026-02-11T03:15:40.588768Z","steps":["trace[938377841] 'process raft request' (duration: 527.449696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:40.588796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.060956Z","time spent":"527.826721ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:40.794927Z","caller":"traceutil/trace.go:171","msg":"trace[1334592233] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:736; }","duration":"364.540035ms","start":"2026-02-11T03:15:40.430361Z","end":"2026-02-11T03:15:40.794901Z","steps":["trace[1334592233] 'read index received' (duration: 111.384702ms)","trace[1334592233] 'applied index is now lower than readState.Index' (duration: 253.154402ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:40.795034Z","caller":"traceutil/trace.go:171","msg":"trace[1397307504] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"368.985566ms","start":"2026-02-11T03:15:40.426039Z","end":"2026-02-11T03:15:40.795025Z","steps":["trace[1397307504] 'process raft request' (duration: 368.748786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:40.795333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.426024Z","time spent":"369.043108ms","remote":"127.0.0.1:51074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:40.795755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.386242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-cn6d6\" ","response":"range_response_count:1 size:4733"} {"level":"warn","ts":"2026-02-11T03:15:40.796125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.105948ms","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-11T03:15:40.79617Z","caller":"traceutil/trace.go:171","msg":"trace[896946423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:714; }","duration":"196.174391ms","start":"2026-02-11T03:15:40.599986Z","end":"2026-02-11T03:15:40.79616Z","steps":["trace[896946423] 'agreement among raft nodes before linearized reading' (duration: 196.063076ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:40.7958Z","caller":"traceutil/trace.go:171","msg":"trace[1272976107] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-cn6d6; range_end:; response_count:1; response_revision:714; }","duration":"365.451534ms","start":"2026-02-11T03:15:40.430337Z","end":"2026-02-11T03:15:40.795789Z","steps":["trace[1272976107] 'agreement among raft nodes before linearized reading' (duration: 365.284986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:40.796346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.43033Z","time spent":"366.004429ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4757,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-cn6d6\" "} {"level":"info","ts":"2026-02-11T03:15:41.390738Z","caller":"traceutil/trace.go:171","msg":"trace[1576701538] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"585.931796ms","start":"2026-02-11T03:15:40.804783Z","end":"2026-02-11T03:15:41.390714Z","steps":["trace[1576701538] 'process raft request' (duration: 585.823321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.390843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.804763Z","time spent":"586.02277ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5008,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:41.661065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.45549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:41.661123Z","caller":"traceutil/trace.go:171","msg":"trace[408933154] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"356.504414ms","start":"2026-02-11T03:15:41.304609Z","end":"2026-02-11T03:15:41.661113Z","steps":["trace[408933154] 'read index received' (duration: 86.623455ms)","trace[408933154] 'applied index is now lower than readState.Index' (duration: 269.880329ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:41.661221Z","caller":"traceutil/trace.go:171","msg":"trace[1420710465] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"853.061146ms","start":"2026-02-11T03:15:40.808153Z","end":"2026-02-11T03:15:41.661215Z","steps":["trace[1420710465] 'process raft request' (duration: 661.410589ms)","trace[1420710465] 'compare' (duration: 191.322693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:41.661249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.808143Z","time spent":"853.091597ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:41.661341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.732284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:41.661355Z","caller":"traceutil/trace.go:171","msg":"trace[357144993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"356.768165ms","start":"2026-02-11T03:15:41.304583Z","end":"2026-02-11T03:15:41.661351Z","steps":["trace[357144993] 'agreement among raft nodes before linearized reading' (duration: 356.744324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.661368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:41.304569Z","time spent":"356.795768ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T03:15:41.73163Z","caller":"traceutil/trace.go:171","msg":"trace[2113465590] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"331.46842ms","start":"2026-02-11T03:15:41.400146Z","end":"2026-02-11T03:15:41.731615Z","steps":["trace[2113465590] 'process raft request' (duration: 331.251341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.731732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:41.400131Z","time spent":"331.549963ms","remote":"127.0.0.1:51178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:41.73191Z","caller":"traceutil/trace.go:171","msg":"trace[335406219] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"331.350394ms","start":"2026-02-11T03:15:41.400553Z","end":"2026-02-11T03:15:41.731904Z","steps":["trace[335406219] 'process raft request' (duration: 330.913346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.731937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:41.400547Z","time spent":"331.374585ms","remote":"127.0.0.1:51262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1291,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:41.732049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.847196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-cn6d6\" ","response":"range_response_count:1 size:5023"} {"level":"info","ts":"2026-02-11T03:15:41.732065Z","caller":"traceutil/trace.go:171","msg":"trace[1684136934] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-cn6d6; range_end:; response_count:1; response_revision:718; }","duration":"331.876157ms","start":"2026-02-11T03:15:41.400184Z","end":"2026-02-11T03:15:41.73206Z","steps":["trace[1684136934] 'agreement among raft nodes before linearized reading' (duration: 331.808504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.732079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:41.400179Z","time spent":"331.896588ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5047,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-cn6d6\" "} {"level":"warn","ts":"2026-02-11T03:15:43.665721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.267277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:43.665857Z","caller":"traceutil/trace.go:171","msg":"trace[2059792393] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"222.758546ms","start":"2026-02-11T03:15:43.443089Z","end":"2026-02-11T03:15:43.665848Z","steps":["trace[2059792393] 'process raft request' (duration: 222.697884ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:43.66602Z","caller":"traceutil/trace.go:171","msg":"trace[1468076924] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"354.351984ms","start":"2026-02-11T03:15:43.311661Z","end":"2026-02-11T03:15:43.666013Z","steps":["trace[1468076924] 'process raft request' (duration: 143.728811ms)","trace[1468076924] 'compare' (duration: 210.171693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:43.666065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:43.311651Z","time spent":"354.386565ms","remote":"127.0.0.1:51034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:43.666153Z","caller":"traceutil/trace.go:171","msg":"trace[619860756] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"223.180525ms","start":"2026-02-11T03:15:43.442967Z","end":"2026-02-11T03:15:43.666148Z","steps":["trace[619860756] 'read index received' (duration: 12.390095ms)","trace[619860756] 'applied index is now lower than readState.Index' (duration: 210.78963ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:43.666285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.314551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-6nqxl\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-11T03:15:43.666299Z","caller":"traceutil/trace.go:171","msg":"trace[1638743805] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-6nqxl; range_end:; response_count:1; response_revision:747; }","duration":"223.365804ms","start":"2026-02-11T03:15:43.442929Z","end":"2026-02-11T03:15:43.666295Z","steps":["trace[1638743805] 'agreement among raft nodes before linearized reading' (duration: 223.289401ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:48.945203Z","caller":"traceutil/trace.go:171","msg":"trace[228219688] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"321.454137ms","start":"2026-02-11T03:15:48.623728Z","end":"2026-02-11T03:15:48.945182Z","steps":["trace[228219688] 'read index received' (duration: 312.832774ms)","trace[228219688] 'applied index is now lower than readState.Index' (duration: 8.620102ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:48.945399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.660215ms","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-11T03:15:48.945436Z","caller":"traceutil/trace.go:171","msg":"trace[980832154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:767; }","duration":"321.735129ms","start":"2026-02-11T03:15:48.62369Z","end":"2026-02-11T03:15:48.945425Z","steps":["trace[980832154] 'agreement among raft nodes before linearized reading' (duration: 321.603893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:48.94547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:48.623664Z","time spent":"321.794412ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:15:48.946272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.788148ms","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-11T03:15:48.946298Z","caller":"traceutil/trace.go:171","msg":"trace[512319202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:767; }","duration":"315.868123ms","start":"2026-02-11T03:15:48.630423Z","end":"2026-02-11T03:15:48.946291Z","steps":["trace[512319202] 'agreement among raft nodes before linearized reading' (duration: 315.784899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:48.946318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:48.630394Z","time spent":"315.918625ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T03:16:00.430796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.627442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:00.430858Z","caller":"traceutil/trace.go:171","msg":"trace[188127925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"120.724927ms","start":"2026-02-11T03:16:00.310119Z","end":"2026-02-11T03:16:00.430844Z","steps":["trace[188127925] 'range keys from in-memory index tree' (duration: 120.560359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:01.171366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.11611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:16:01.171443Z","caller":"traceutil/trace.go:171","msg":"trace[559122868] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:807; }","duration":"182.207404ms","start":"2026-02-11T03:16:00.989221Z","end":"2026-02-11T03:16:01.171428Z","steps":["trace[559122868] 'count revisions from in-memory index tree' (duration: 182.051047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:01.171711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.964867ms","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-11T03:16:01.171739Z","caller":"traceutil/trace.go:171","msg":"trace[1038829423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:807; }","duration":"130.020779ms","start":"2026-02-11T03:16:01.041712Z","end":"2026-02-11T03:16:01.171732Z","steps":["trace[1038829423] 'range keys from in-memory index tree' (duration: 129.83857ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:01.430166Z","caller":"traceutil/trace.go:171","msg":"trace[913688566] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"250.613968ms","start":"2026-02-11T03:16:01.179527Z","end":"2026-02-11T03:16:01.430141Z","steps":["trace[913688566] 'process raft request' (duration: 229.494047ms)","trace[913688566] 'compare' (duration: 20.616826ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:02.299759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.630117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-11T03:16:02.299805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.910372ms","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-11T03:16:02.299821Z","caller":"traceutil/trace.go:171","msg":"trace[1140634505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:810; }","duration":"122.725521ms","start":"2026-02-11T03:16:02.177082Z","end":"2026-02-11T03:16:02.299807Z","steps":["trace[1140634505] 'range keys from in-memory index tree' (duration: 122.511671ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:02.299829Z","caller":"traceutil/trace.go:171","msg":"trace[1380891242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"121.932923ms","start":"2026-02-11T03:16:02.177888Z","end":"2026-02-11T03:16:02.299821Z","steps":["trace[1380891242] 'range keys from in-memory index tree' (duration: 121.846508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:02.299928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.445951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:16:02.299949Z","caller":"traceutil/trace.go:171","msg":"trace[1782724328] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:810; }","duration":"194.468342ms","start":"2026-02-11T03:16:02.105474Z","end":"2026-02-11T03:16:02.299942Z","steps":["trace[1782724328] 'count revisions from in-memory index tree' (duration: 194.349866ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:03.475238Z","caller":"traceutil/trace.go:171","msg":"trace[1985793236] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"147.323461ms","start":"2026-02-11T03:16:03.327902Z","end":"2026-02-11T03:16:03.475225Z","steps":["trace[1985793236] 'process raft request' (duration: 48.769103ms)","trace[1985793236] 'compare' (duration: 98.293745ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:03.701793Z","caller":"traceutil/trace.go:171","msg":"trace[165808535] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"222.850165ms","start":"2026-02-11T03:16:03.478927Z","end":"2026-02-11T03:16:03.701777Z","steps":["trace[165808535] 'read index received' (duration: 189.546774ms)","trace[165808535] 'applied index is now lower than readState.Index' (duration: 33.302711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:03.701943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.999713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T03:16:03.701963Z","caller":"traceutil/trace.go:171","msg":"trace[444238909] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:815; }","duration":"223.042395ms","start":"2026-02-11T03:16:03.478915Z","end":"2026-02-11T03:16:03.701958Z","steps":["trace[444238909] 'agreement among raft nodes before linearized reading' (duration: 222.917829ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:03.702097Z","caller":"traceutil/trace.go:171","msg":"trace[828619495] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"223.410803ms","start":"2026-02-11T03:16:03.47868Z","end":"2026-02-11T03:16:03.702091Z","steps":["trace[828619495] 'process raft request' (duration: 189.843308ms)","trace[828619495] 'compare' (duration: 32.986846ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:11.475186Z","caller":"traceutil/trace.go:171","msg":"trace[674154310] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"119.195018ms","start":"2026-02-11T03:16:11.355961Z","end":"2026-02-11T03:16:11.475156Z","steps":["trace[674154310] 'process raft request' (duration: 119.02153ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:29.704944Z","caller":"traceutil/trace.go:171","msg":"trace[1484470967] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"102.284395ms","start":"2026-02-11T03:16:29.602582Z","end":"2026-02-11T03:16:29.704866Z","steps":["trace[1484470967] 'process raft request' (duration: 102.120186ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:37.743499Z","caller":"traceutil/trace.go:171","msg":"trace[997601613] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"144.902185ms","start":"2026-02-11T03:16:37.598573Z","end":"2026-02-11T03:16:37.743475Z","steps":["trace[997601613] 'process raft request' (duration: 144.668332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:47.668897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.810686ms","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-11T03:16:47.669036Z","caller":"traceutil/trace.go:171","msg":"trace[1929888190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"642.997616ms","start":"2026-02-11T03:16:47.026026Z","end":"2026-02-11T03:16:47.669023Z","steps":["trace[1929888190] 'range keys from in-memory index tree' (duration: 642.635816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:47.669098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:47.026011Z","time spent":"643.06424ms","remote":"127.0.0.1:51258","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-11T03:16:47.670594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.729052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:47.670695Z","caller":"traceutil/trace.go:171","msg":"trace[465442288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"362.858299ms","start":"2026-02-11T03:16:47.307803Z","end":"2026-02-11T03:16:47.670661Z","steps":["trace[465442288] 'range keys from in-memory index tree' (duration: 362.644697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:47.670759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:47.307788Z","time spent":"362.960985ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:16:47.671529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.241283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:47.671591Z","caller":"traceutil/trace.go:171","msg":"trace[1444420993] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:967; }","duration":"634.350319ms","start":"2026-02-11T03:16:47.037228Z","end":"2026-02-11T03:16:47.671578Z","steps":["trace[1444420993] 'count revisions from in-memory index tree' (duration: 634.143787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:47.671743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:47.037177Z","time spent":"634.55123ms","remote":"127.0.0.1:49010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-02-11T03:16:48.462938Z","caller":"traceutil/trace.go:171","msg":"trace[54587518] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"264.338487ms","start":"2026-02-11T03:16:48.198583Z","end":"2026-02-11T03:16:48.462922Z","steps":["trace[54587518] 'process raft request' (duration: 264.094813ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:54.029847Z","caller":"traceutil/trace.go:171","msg":"trace[1459712897] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"163.476089ms","start":"2026-02-11T03:16:53.866349Z","end":"2026-02-11T03:16:54.029825Z","steps":["trace[1459712897] 'process raft request' (duration: 163.346912ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:54.890694Z","caller":"traceutil/trace.go:171","msg":"trace[571227362] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"389.002539ms","start":"2026-02-11T03:16:54.501666Z","end":"2026-02-11T03:16:54.890668Z","steps":["trace[571227362] 'process raft request' (duration: 388.700892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:54.89137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:54.501655Z","time spent":"389.110164ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:16:55.028647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.178813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:16:55.028718Z","caller":"traceutil/trace.go:171","msg":"trace[1228748354] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"416.792321ms","start":"2026-02-11T03:16:54.611913Z","end":"2026-02-11T03:16:55.028705Z","steps":["trace[1228748354] 'read index received' (duration: 280.912123ms)","trace[1228748354] 'applied index is now lower than readState.Index' (duration: 135.879398ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:55.029603Z","caller":"traceutil/trace.go:171","msg":"trace[328451394] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"527.647284ms","start":"2026-02-11T03:16:54.501944Z","end":"2026-02-11T03:16:55.029591Z","steps":["trace[328451394] 'process raft request' (duration: 397.466413ms)","trace[328451394] 'compare' (duration: 128.982662ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:55.029793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:54.501938Z","time spent":"527.691786ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:16:55.030515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.598833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:16:55.030542Z","caller":"traceutil/trace.go:171","msg":"trace[1394803101] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:994; }","duration":"418.696789ms","start":"2026-02-11T03:16:54.611837Z","end":"2026-02-11T03:16:55.030534Z","steps":["trace[1394803101] 'agreement among raft nodes before linearized reading' (duration: 418.643616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:55.030722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:54.611814Z","time spent":"418.89982ms","remote":"127.0.0.1:51412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-11T03:16:55.031003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.115742ms","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-11T03:16:55.031028Z","caller":"traceutil/trace.go:171","msg":"trace[378571848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:994; }","duration":"117.167956ms","start":"2026-02-11T03:16:54.913854Z","end":"2026-02-11T03:16:55.031022Z","steps":["trace[378571848] 'agreement among raft nodes before linearized reading' (duration: 117.091011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:56.42792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058851454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:56.42797Z","caller":"traceutil/trace.go:171","msg":"trace[1658374302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"1.058908807s","start":"2026-02-11T03:16:55.369049Z","end":"2026-02-11T03:16:56.427958Z","steps":["trace[1658374302] 'range keys from in-memory index tree' (duration: 1.058701595s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:56.427992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:55.368999Z","time spent":"1.058988482s","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:16:56.428106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.402055ms","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-11T03:16:56.428191Z","caller":"traceutil/trace.go:171","msg":"trace[808844819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"394.525923ms","start":"2026-02-11T03:16:56.03365Z","end":"2026-02-11T03:16:56.428175Z","steps":["trace[808844819] 'range keys from in-memory index tree' (duration: 394.258337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:56.428222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:56.033632Z","time spent":"394.582456ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:16:56.428294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.048449ms","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-11T03:16:56.428311Z","caller":"traceutil/trace.go:171","msg":"trace[1519967049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"643.100362ms","start":"2026-02-11T03:16:55.785206Z","end":"2026-02-11T03:16:56.428306Z","steps":["trace[1519967049] 'range keys from in-memory index tree' (duration: 642.905952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:56.428324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:55.785188Z","time spent":"643.133184ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T03:16:56.428491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.859096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:56.428515Z","caller":"traceutil/trace.go:171","msg":"trace[1236294936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"121.907999ms","start":"2026-02-11T03:16:56.306599Z","end":"2026-02-11T03:16:56.428507Z","steps":["trace[1236294936] 'range keys from in-memory index tree' (duration: 121.803373ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:02.104757Z","caller":"traceutil/trace.go:171","msg":"trace[394625969] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"107.027124ms","start":"2026-02-11T03:17:01.997715Z","end":"2026-02-11T03:17:02.104743Z","steps":["trace[394625969] 'process raft request' (duration: 106.667733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:03.225453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.47332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:1 size:1930"} {"level":"info","ts":"2026-02-11T03:17:03.22553Z","caller":"traceutil/trace.go:171","msg":"trace[151613761] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:1; response_revision:1020; }","duration":"107.585227ms","start":"2026-02-11T03:17:03.117926Z","end":"2026-02-11T03:17:03.225512Z","steps":["trace[151613761] 'range keys from in-memory index tree' (duration: 107.373955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:03.225759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.667609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-02-11T03:17:03.225788Z","caller":"traceutil/trace.go:171","msg":"trace[901809830] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:1020; }","duration":"150.718132ms","start":"2026-02-11T03:17:03.075061Z","end":"2026-02-11T03:17:03.225779Z","steps":["trace[901809830] 'range keys from in-memory index tree' (duration: 150.617336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:03.225806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.311772ms","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-11T03:17:03.225872Z","caller":"traceutil/trace.go:171","msg":"trace[1608746471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"102.405947ms","start":"2026-02-11T03:17:03.123449Z","end":"2026-02-11T03:17:03.225855Z","steps":["trace[1608746471] 'range keys from in-memory index tree' (duration: 102.205864ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:03.459281Z","caller":"traceutil/trace.go:171","msg":"trace[1110273502] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"112.704234ms","start":"2026-02-11T03:17:03.346553Z","end":"2026-02-11T03:17:03.459257Z","steps":["trace[1110273502] 'process raft request' (duration: 112.657162ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:03.459528Z","caller":"traceutil/trace.go:171","msg":"trace[1958633591] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"218.029391ms","start":"2026-02-11T03:17:03.241474Z","end":"2026-02-11T03:17:03.459503Z","steps":["trace[1958633591] 'process raft request' (duration: 217.67357ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:03.459721Z","caller":"traceutil/trace.go:171","msg":"trace[829490620] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"219.747329ms","start":"2026-02-11T03:17:03.239963Z","end":"2026-02-11T03:17:03.45971Z","steps":["trace[829490620] 'process raft request' (duration: 210.074658ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:03.46004Z","caller":"traceutil/trace.go:171","msg":"trace[1960211880] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1065; }","duration":"160.216863ms","start":"2026-02-11T03:17:03.299812Z","end":"2026-02-11T03:17:03.460029Z","steps":["trace[1960211880] 'read index received' (duration: 46.420145ms)","trace[1960211880] 'applied index is now lower than readState.Index' (duration: 113.795598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:03.460147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.32015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-02-11T03:17:03.460177Z","caller":"traceutil/trace.go:171","msg":"trace[422445313] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:1023; }","duration":"160.393904ms","start":"2026-02-11T03:17:03.299775Z","end":"2026-02-11T03:17:03.460169Z","steps":["trace[422445313] 'agreement among raft nodes before linearized reading' (duration: 160.32176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:03.460313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.869849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:03.460342Z","caller":"traceutil/trace.go:171","msg":"trace[2067536336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"151.934472ms","start":"2026-02-11T03:17:03.3084Z","end":"2026-02-11T03:17:03.460334Z","steps":["trace[2067536336] 'agreement among raft nodes before linearized reading' (duration: 151.89373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:03.460492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.178267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-02-11T03:17:03.46052Z","caller":"traceutil/trace.go:171","msg":"trace[47652566] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:1023; }","duration":"105.21613ms","start":"2026-02-11T03:17:03.355294Z","end":"2026-02-11T03:17:03.46051Z","steps":["trace[47652566] 'agreement among raft nodes before linearized reading' (duration: 105.171097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:05.622312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.773891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:17:05.622711Z","caller":"traceutil/trace.go:171","msg":"trace[733757587] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"136.622787ms","start":"2026-02-11T03:17:05.486068Z","end":"2026-02-11T03:17:05.62269Z","steps":["trace[733757587] 'read index received' (duration: 30.276633ms)","trace[733757587] 'applied index is now lower than readState.Index' (duration: 106.344784ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:17:05.62289Z","caller":"traceutil/trace.go:171","msg":"trace[1132601609] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"150.674276ms","start":"2026-02-11T03:17:05.472199Z","end":"2026-02-11T03:17:05.622873Z","steps":["trace[1132601609] 'process raft request' (duration: 44.230056ms)","trace[1132601609] 'compare' (duration: 105.642913ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:05.623307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.234853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-11T03:17:05.623405Z","caller":"traceutil/trace.go:171","msg":"trace[646719632] range","detail":"{range_begin:/registry/clusterroles/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1028; }","duration":"137.363141ms","start":"2026-02-11T03:17:05.486026Z","end":"2026-02-11T03:17:05.623389Z","steps":["trace[646719632] 'agreement among raft nodes before linearized reading' (duration: 137.231293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:05.837218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.158143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:get-nodes\" ","response":"range_response_count:1 size:469"} {"level":"info","ts":"2026-02-11T03:17:05.837334Z","caller":"traceutil/trace.go:171","msg":"trace[878463373] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1028; }","duration":"106.328393ms","start":"2026-02-11T03:17:05.730979Z","end":"2026-02-11T03:17:05.837308Z","steps":["trace[878463373] 'range keys from in-memory index tree' (duration: 106.025805ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:06.715542Z","caller":"traceutil/trace.go:171","msg":"trace[66344617] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"111.408149ms","start":"2026-02-11T03:17:06.604116Z","end":"2026-02-11T03:17:06.715524Z","steps":["trace[66344617] 'process raft request' (duration: 104.281434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:06.982337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.086438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-02-11T03:17:06.982632Z","caller":"traceutil/trace.go:171","msg":"trace[1439060008] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1031; }","duration":"123.379797ms","start":"2026-02-11T03:17:06.859199Z","end":"2026-02-11T03:17:06.982578Z","steps":["trace[1439060008] 'range keys from in-memory index tree' (duration: 123.004273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:07.331915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.086666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T03:17:07.332009Z","caller":"traceutil/trace.go:171","msg":"trace[1909546391] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1032; }","duration":"125.218783ms","start":"2026-02-11T03:17:07.206771Z","end":"2026-02-11T03:17:07.33199Z","steps":["trace[1909546391] 'range keys from in-memory index tree' (duration: 124.973319ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:07.638464Z","caller":"traceutil/trace.go:171","msg":"trace[1924315727] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"144.944956ms","start":"2026-02-11T03:17:07.493507Z","end":"2026-02-11T03:17:07.638452Z","steps":["trace[1924315727] 'read index received' (duration: 144.8389ms)","trace[1924315727] 'applied index is now lower than readState.Index' (duration: 105.486µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:17:07.638748Z","caller":"traceutil/trace.go:171","msg":"trace[1120203808] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"161.016595ms","start":"2026-02-11T03:17:07.477721Z","end":"2026-02-11T03:17:07.638738Z","steps":["trace[1120203808] 'process raft request' (duration: 160.659025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:07.63883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.311958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:1 size:1081"} {"level":"info","ts":"2026-02-11T03:17:07.639326Z","caller":"traceutil/trace.go:171","msg":"trace[139139299] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:1; response_revision:1033; }","duration":"145.827337ms","start":"2026-02-11T03:17:07.493491Z","end":"2026-02-11T03:17:07.639318Z","steps":["trace[139139299] 'agreement among raft nodes before linearized reading' (duration: 145.275315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:07.638948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.141899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:1076"} {"level":"info","ts":"2026-02-11T03:17:07.639582Z","caller":"traceutil/trace.go:171","msg":"trace[521440661] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1033; }","duration":"119.801298ms","start":"2026-02-11T03:17:07.519775Z","end":"2026-02-11T03:17:07.639576Z","steps":["trace[521440661] 'agreement among raft nodes before linearized reading' (duration: 119.1476ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:18.944994Z","caller":"traceutil/trace.go:171","msg":"trace[2130500956] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"319.283986ms","start":"2026-02-11T03:17:18.625686Z","end":"2026-02-11T03:17:18.94497Z","steps":["trace[2130500956] 'process raft request' (duration: 319.142947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:18.945492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:18.625678Z","time spent":"319.400252ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:17:18.946157Z","caller":"traceutil/trace.go:171","msg":"trace[700573346] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1110; }","duration":"270.535418ms","start":"2026-02-11T03:17:18.675607Z","end":"2026-02-11T03:17:18.946142Z","steps":["trace[700573346] 'read index received' (duration: 270.530317ms)","trace[700573346] 'applied index is now lower than readState.Index' (duration: 3.941µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:18.946461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.846176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-node-lease/\" range_end:\"/registry/roles/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:18.94651Z","caller":"traceutil/trace.go:171","msg":"trace[1642440932] range","detail":"{range_begin:/registry/roles/kube-node-lease/; range_end:/registry/roles/kube-node-lease0; response_count:0; response_revision:1063; }","duration":"270.929681ms","start":"2026-02-11T03:17:18.675569Z","end":"2026-02-11T03:17:18.946498Z","steps":["trace[1642440932] 'agreement among raft nodes before linearized reading' (duration: 270.848476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:19.439082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.856385ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T03:17:19.439454Z","caller":"traceutil/trace.go:171","msg":"trace[1883366683] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"493.232599ms","start":"2026-02-11T03:17:18.946201Z","end":"2026-02-11T03:17:19.439434Z","steps":["trace[1883366683] 'read index received' (duration: 49.986312ms)","trace[1883366683] 'applied index is now lower than readState.Index' (duration: 443.244567ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:19.43981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.421988ms","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-11T03:17:19.440114Z","caller":"traceutil/trace.go:171","msg":"trace[1068028480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"523.769309ms","start":"2026-02-11T03:17:18.916331Z","end":"2026-02-11T03:17:19.4401Z","steps":["trace[1068028480] 'agreement among raft nodes before linearized reading' (duration: 523.330833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:19.440309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:18.916307Z","time spent":"523.985511ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T03:17:19.440336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.497195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/\" range_end:\"/registry/serviceaccounts/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-02-11T03:17:19.440604Z","caller":"traceutil/trace.go:171","msg":"trace[1557455706] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/; range_end:/registry/serviceaccounts/kube-node-lease0; response_count:1; response_revision:1063; }","duration":"412.786392ms","start":"2026-02-11T03:17:19.027728Z","end":"2026-02-11T03:17:19.440514Z","steps":["trace[1557455706] 'agreement among raft nodes before linearized reading' (duration: 412.366617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:19.440711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:19.027699Z","time spent":"412.994305ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":211,"request content":"key:\"/registry/serviceaccounts/kube-node-lease/\" range_end:\"/registry/serviceaccounts/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-02-11T03:17:19.441281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.863482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:19.441332Z","caller":"traceutil/trace.go:171","msg":"trace[1213702728] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:1063; }","duration":"763.941257ms","start":"2026-02-11T03:17:18.677378Z","end":"2026-02-11T03:17:19.441319Z","steps":["trace[1213702728] 'agreement among raft nodes before linearized reading' (duration: 763.856882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:19.441758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.875764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:17:19.441962Z","caller":"traceutil/trace.go:171","msg":"trace[1284605041] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1063; }","duration":"185.114588ms","start":"2026-02-11T03:17:19.256834Z","end":"2026-02-11T03:17:19.441948Z","steps":["trace[1284605041] 'agreement among raft nodes before linearized reading' (duration: 184.775998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:19.442356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.777214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:19.442463Z","caller":"traceutil/trace.go:171","msg":"trace[1036737340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"132.908351ms","start":"2026-02-11T03:17:19.309546Z","end":"2026-02-11T03:17:19.442454Z","steps":["trace[1036737340] 'agreement among raft nodes before linearized reading' (duration: 132.778674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:19.442924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.253447ms","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-11T03:17:19.443009Z","caller":"traceutil/trace.go:171","msg":"trace[619241477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"240.374505ms","start":"2026-02-11T03:17:19.202627Z","end":"2026-02-11T03:17:19.443001Z","steps":["trace[619241477] 'agreement among raft nodes before linearized reading' (duration: 240.248738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:19.441367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:18.677362Z","time spent":"763.996461ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" limit:500 "} {"level":"info","ts":"2026-02-11T03:17:19.615759Z","caller":"traceutil/trace.go:171","msg":"trace[823622088] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"158.139749ms","start":"2026-02-11T03:17:19.457603Z","end":"2026-02-11T03:17:19.615743Z","steps":["trace[823622088] 'process raft request' (duration: 90.776874ms)","trace[823622088] 'compare' (duration: 66.93075ms)"],"step_count":2}