{"level":"warn","ts":"2026-02-06T00:41:38.775534Z","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-06T00:41:38.77564Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.102:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.102:2380","--initial-cluster=instance=https://199.204.45.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.102: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-06T00:41:38.775717Z","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-06T00:41:38.775727Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-02-06T00:41:38.775747Z","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-06T00:41:38.777592Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"]} {"level":"info","ts":"2026-02-06T00:41:38.7797Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.102: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-06T00:41:38.785911Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.814947ms"} {"level":"info","ts":"2026-02-06T00:41:38.798823Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e7960f761c1028e","cluster-id":"502e2b31b867adf"} {"level":"info","ts":"2026-02-06T00:41:38.798886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T00:41:38.798941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 0"} {"level":"info","ts":"2026-02-06T00:41:38.798951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e7960f761c1028e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T00:41:38.79896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 1"} {"level":"info","ts":"2026-02-06T00:41:38.799023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"warn","ts":"2026-02-06T00:41:38.820294Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T00:41:38.82341Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T00:41:38.827233Z","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-06T00:41:38.834228Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e7960f761c1028e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T00:41:38.834996Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e7960f761c1028e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T00:41:38.83511Z","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-06T00:41:38.835146Z","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-06T00:41:38.835161Z","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-06T00:41:38.835352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"info","ts":"2026-02-06T00:41:38.835426Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","added-peer-id":"6e7960f761c1028e","added-peer-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-02-06T00:41:38.847304Z","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-06T00:41:38.847641Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e7960f761c1028e","initial-advertise-peer-urls":["https://199.204.45.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T00:41:38.847684Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-02-06T00:41:38.847735Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-02-06T00:41:38.847713Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T00:41:39.001301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T00:41:39.001352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T00:41:39.001375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgPreVoteResp from 6e7960f761c1028e at term 1"} {"level":"info","ts":"2026-02-06T00:41:39.001484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became candidate at term 2"} {"level":"info","ts":"2026-02-06T00:41:39.001524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgVoteResp from 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-02-06T00:41:39.001565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became leader at term 2"} {"level":"info","ts":"2026-02-06T00:41:39.001577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e7960f761c1028e elected leader 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-02-06T00:41:39.006141Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e7960f761c1028e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.102:2379]}","request-path":"/0/members/6e7960f761c1028e/attributes","cluster-id":"502e2b31b867adf","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T00:41:39.0063Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:41:39.006445Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:41:39.006732Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:41:39.008947Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T00:41:39.009027Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T00:41:39.01043Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.102:2379"} {"level":"info","ts":"2026-02-06T00:41:39.012169Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T00:41:39.014548Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:41:39.014678Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:41:39.014703Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-06T00:41:51.638769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000998717s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:41:51.644766Z","caller":"traceutil/trace.go:171","msg":"trace[701614059] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"435.625447ms","start":"2026-02-06T00:41:51.209109Z","end":"2026-02-06T00:41:51.644734Z","steps":["trace[701614059] 'read index received' (duration: 20.02µs)","trace[701614059] 'applied index is now lower than readState.Index' (duration: 435.604097ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:41:51.645003Z","caller":"traceutil/trace.go:171","msg":"trace[1057876806] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"1.094481341s","start":"2026-02-06T00:41:50.550512Z","end":"2026-02-06T00:41:51.644994Z","steps":["trace[1057876806] 'process raft request' (duration: 86.341775ms)","trace[1057876806] 'compare' (duration: 999.43233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:51.645158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:50.550499Z","time spent":"1.094602654s","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:41:51.645482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.380076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-06T00:41:51.646843Z","caller":"traceutil/trace.go:171","msg":"trace[181486701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:340; }","duration":"437.764198ms","start":"2026-02-06T00:41:51.209068Z","end":"2026-02-06T00:41:51.646832Z","steps":["trace[181486701] 'agreement among raft nodes before linearized reading' (duration: 436.353744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:51.646949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.209056Z","time spent":"437.88291ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:41:52.145362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256366447751703,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:41:52.532275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.794464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:41:52.532533Z","caller":"traceutil/trace.go:171","msg":"trace[738161079] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:348; }","duration":"887.547291ms","start":"2026-02-06T00:41:51.644975Z","end":"2026-02-06T00:41:52.532522Z","steps":["trace[738161079] 'read index received' (duration: 651.453239ms)","trace[738161079] 'applied index is now lower than readState.Index' (duration: 236.093222ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:41:52.532598Z","caller":"traceutil/trace.go:171","msg":"trace[959768685] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"887.95712ms","start":"2026-02-06T00:41:51.644635Z","end":"2026-02-06T00:41:52.532592Z","steps":["trace[959768685] 'process raft request' (duration: 651.795597ms)","trace[959768685] 'compare' (duration: 235.682322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:52.532635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.644616Z","time spent":"888.002102ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:41:52.532697Z","caller":"traceutil/trace.go:171","msg":"trace[1177725164] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"878.194559ms","start":"2026-02-06T00:41:51.654473Z","end":"2026-02-06T00:41:52.532668Z","steps":["trace[1177725164] 'process raft request' (duration: 877.938483ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.53282Z","caller":"traceutil/trace.go:171","msg":"trace[94310485] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"878.170548ms","start":"2026-02-06T00:41:51.654643Z","end":"2026-02-06T00:41:52.532813Z","steps":["trace[94310485] 'process raft request' (duration: 877.80952ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.53287Z","caller":"traceutil/trace.go:171","msg":"trace[1261681208] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"702.333245ms","start":"2026-02-06T00:41:51.830531Z","end":"2026-02-06T00:41:52.532864Z","steps":["trace[1261681208] 'process raft request' (duration: 701.965926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:52.532935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080969122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:52.53295Z","caller":"traceutil/trace.go:171","msg":"trace[866006424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:346; }","duration":"1.081013683s","start":"2026-02-06T00:41:51.451931Z","end":"2026-02-06T00:41:52.532944Z","steps":["trace[866006424] 'agreement among raft nodes before linearized reading' (duration: 1.080965391s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:52.532962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.451909Z","time spent":"1.081049864s","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:41:52.533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.654465Z","time spent":"878.288781ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:41:52.533046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.017488ms","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-06T00:41:52.533063Z","caller":"traceutil/trace.go:171","msg":"trace[1868169900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:346; }","duration":"868.059539ms","start":"2026-02-06T00:41:51.665Z","end":"2026-02-06T00:41:52.533059Z","steps":["trace[1868169900] 'agreement among raft nodes before linearized reading' (duration: 868.030519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:52.533075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.664987Z","time spent":"868.08465ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:41:52.533163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.104465ms","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-06T00:41:52.533177Z","caller":"traceutil/trace.go:171","msg":"trace[1652670831] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:346; }","duration":"519.153026ms","start":"2026-02-06T00:41:52.01402Z","end":"2026-02-06T00:41:52.533173Z","steps":["trace[1652670831] 'agreement among raft nodes before linearized reading' (duration: 519.125475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:52.533192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:52.014004Z","time spent":"519.182086ms","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6920,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-06T00:41:52.533212Z","caller":"traceutil/trace.go:171","msg":"trace[976789883] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"882.082982ms","start":"2026-02-06T00:41:51.651121Z","end":"2026-02-06T00:41:52.533204Z","steps":["trace[976789883] 'process raft request' (duration: 881.233152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:52.53325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.651107Z","time spent":"882.125152ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:41:52.533272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.728685ms","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-06T00:41:52.533287Z","caller":"traceutil/trace.go:171","msg":"trace[553178166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:346; }","duration":"860.765347ms","start":"2026-02-06T00:41:51.672518Z","end":"2026-02-06T00:41:52.533283Z","steps":["trace[553178166] 'agreement among raft nodes before linearized reading' (duration: 860.739566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:52.533301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.672508Z","time spent":"860.790397ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:41:52.533374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.771021ms","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-06T00:41:52.533386Z","caller":"traceutil/trace.go:171","msg":"trace[2128390880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:346; }","duration":"861.826042ms","start":"2026-02-06T00:41:51.671555Z","end":"2026-02-06T00:41:52.533381Z","steps":["trace[2128390880] 'agreement among raft nodes before linearized reading' (duration: 861.801301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:52.533397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.671542Z","time spent":"861.852092ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-06T00:41:52.533464Z","caller":"traceutil/trace.go:171","msg":"trace[567647984] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"882.246876ms","start":"2026-02-06T00:41:51.65121Z","end":"2026-02-06T00:41:52.533456Z","steps":["trace[567647984] 'process raft request' (duration: 881.17252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:52.533499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.651206Z","time spent":"882.277436ms","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:41:52.533636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.654638Z","time spent":"878.197539ms","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:41:52.533725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:51.830512Z","time spent":"702.375515ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:41:52.715007Z","caller":"traceutil/trace.go:171","msg":"trace[800260813] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"166.937854ms","start":"2026-02-06T00:41:52.548051Z","end":"2026-02-06T00:41:52.714988Z","steps":["trace[800260813] 'read index received' (duration: 165.289335ms)","trace[800260813] 'applied index is now lower than readState.Index' (duration: 1.645799ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:52.715123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.051366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:52.715148Z","caller":"traceutil/trace.go:171","msg":"trace[475439260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:347; }","duration":"167.115967ms","start":"2026-02-06T00:41:52.548026Z","end":"2026-02-06T00:41:52.715142Z","steps":["trace[475439260] 'agreement among raft nodes before linearized reading' (duration: 167.032355ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.715391Z","caller":"traceutil/trace.go:171","msg":"trace[1404220872] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"167.821604ms","start":"2026-02-06T00:41:52.547563Z","end":"2026-02-06T00:41:52.715384Z","steps":["trace[1404220872] 'process raft request' (duration: 165.337115ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.717712Z","caller":"traceutil/trace.go:171","msg":"trace[1481839924] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"162.289844ms","start":"2026-02-06T00:41:52.555407Z","end":"2026-02-06T00:41:52.717697Z","steps":["trace[1481839924] 'process raft request' (duration: 162.267314ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.71787Z","caller":"traceutil/trace.go:171","msg":"trace[219441296] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"166.810321ms","start":"2026-02-06T00:41:52.551055Z","end":"2026-02-06T00:41:52.717866Z","steps":["trace[219441296] 'process raft request' (duration: 166.404271ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.718053Z","caller":"traceutil/trace.go:171","msg":"trace[770683947] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"166.934533ms","start":"2026-02-06T00:41:52.551112Z","end":"2026-02-06T00:41:52.718047Z","steps":["trace[770683947] 'process raft request' (duration: 166.431031ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.718173Z","caller":"traceutil/trace.go:171","msg":"trace[1596099877] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"167.026366ms","start":"2026-02-06T00:41:52.55114Z","end":"2026-02-06T00:41:52.718167Z","steps":["trace[1596099877] 'process raft request' (duration: 166.444752ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.71824Z","caller":"traceutil/trace.go:171","msg":"trace[772276321] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"167.070987ms","start":"2026-02-06T00:41:52.551165Z","end":"2026-02-06T00:41:52.718236Z","steps":["trace[772276321] 'process raft request' (duration: 166.446782ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.71829Z","caller":"traceutil/trace.go:171","msg":"trace[1327771099] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"165.712975ms","start":"2026-02-06T00:41:52.552573Z","end":"2026-02-06T00:41:52.718286Z","steps":["trace[1327771099] 'process raft request' (duration: 165.05977ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:52.718389Z","caller":"traceutil/trace.go:171","msg":"trace[1731888831] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"164.597688ms","start":"2026-02-06T00:41:52.553785Z","end":"2026-02-06T00:41:52.718383Z","steps":["trace[1731888831] 'process raft request' (duration: 163.869891ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:10.155053Z","caller":"traceutil/trace.go:171","msg":"trace[1812096804] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"215.000943ms","start":"2026-02-06T00:42:09.940021Z","end":"2026-02-06T00:42:10.155022Z","steps":["trace[1812096804] 'process raft request' (duration: 214.87087ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:13.212737Z","caller":"traceutil/trace.go:171","msg":"trace[2041925689] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"182.579694ms","start":"2026-02-06T00:42:13.030142Z","end":"2026-02-06T00:42:13.212721Z","steps":["trace[2041925689] 'read index received' (duration: 182.485312ms)","trace[2041925689] 'applied index is now lower than readState.Index' (duration: 93.622µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:42:13.21282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.663906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T00:42:13.212846Z","caller":"traceutil/trace.go:171","msg":"trace[970778021] range","detail":"{range_begin:/registry/masterleases/199.204.45.102; range_end:; response_count:1; response_revision:480; }","duration":"182.730228ms","start":"2026-02-06T00:42:13.030107Z","end":"2026-02-06T00:42:13.212837Z","steps":["trace[970778021] 'agreement among raft nodes before linearized reading' (duration: 182.675517ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:13.212974Z","caller":"traceutil/trace.go:171","msg":"trace[1103751541] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"336.07482ms","start":"2026-02-06T00:42:12.876865Z","end":"2026-02-06T00:42:13.21294Z","steps":["trace[1103751541] 'process raft request' (duration: 335.761552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:13.213095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:12.87685Z","time spent":"336.170822ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:42:19.214819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256366447752010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:42:19.335271Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.27729465s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T00:42:19.33565Z","caller":"traceutil/trace.go:171","msg":"trace[1842939750] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.284193204s","start":"2026-02-06T00:42:18.051437Z","end":"2026-02-06T00:42:19.33563Z","steps":["trace[1842939750] 'process raft request' (duration: 1.284067951s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:19.335753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:18.051423Z","time spent":"1.284264135s","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:19.779928Z","caller":"traceutil/trace.go:171","msg":"trace[162784706] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:507; }","duration":"1.065807193s","start":"2026-02-06T00:42:18.714103Z","end":"2026-02-06T00:42:19.77991Z","steps":["trace[162784706] 'read index received' (duration: 621.461758ms)","trace[162784706] 'applied index is now lower than readState.Index' (duration: 444.344555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:42:19.780044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065925745s","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-06T00:42:19.78006Z","caller":"traceutil/trace.go:171","msg":"trace[1957334119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"1.065996657s","start":"2026-02-06T00:42:18.714058Z","end":"2026-02-06T00:42:19.780055Z","steps":["trace[1957334119] 'agreement among raft nodes before linearized reading' (duration: 1.065935106s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:19.780096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:18.714039Z","time spent":"1.066034748s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:42:19.780274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.61083ms","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-06T00:42:19.780288Z","caller":"traceutil/trace.go:171","msg":"trace[2088747449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"900.659451ms","start":"2026-02-06T00:42:18.879624Z","end":"2026-02-06T00:42:19.780284Z","steps":["trace[2088747449] 'agreement among raft nodes before linearized reading' (duration: 900.634651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:19.780301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:18.879605Z","time spent":"900.692042ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:42:19.780385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.353251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:19.780397Z","caller":"traceutil/trace.go:171","msg":"trace[168518154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"329.419402ms","start":"2026-02-06T00:42:19.450974Z","end":"2026-02-06T00:42:19.780394Z","steps":["trace[168518154] 'agreement among raft nodes before linearized reading' (duration: 329.396662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:19.780409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:19.450954Z","time spent":"329.452352ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:42:19.780494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.01268ms","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-06T00:42:19.780521Z","caller":"traceutil/trace.go:171","msg":"trace[596186645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"551.086012ms","start":"2026-02-06T00:42:19.229418Z","end":"2026-02-06T00:42:19.780504Z","steps":["trace[596186645] 'agreement among raft nodes before linearized reading' (duration: 551.060891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:19.780533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:19.229398Z","time spent":"551.131853ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:42:19.780608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.501237ms","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-06T00:42:19.78062Z","caller":"traceutil/trace.go:171","msg":"trace[2029831662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"897.535027ms","start":"2026-02-06T00:42:18.883081Z","end":"2026-02-06T00:42:19.780616Z","steps":["trace[2029831662] 'agreement among raft nodes before linearized reading' (duration: 897.511767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:19.78064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:18.883069Z","time spent":"897.559878ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T00:42:34.20797Z","caller":"traceutil/trace.go:171","msg":"trace[67290338] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"290.381795ms","start":"2026-02-06T00:42:33.917564Z","end":"2026-02-06T00:42:34.207946Z","steps":["trace[67290338] 'process raft request' (duration: 290.271032ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:34.209108Z","caller":"traceutil/trace.go:171","msg":"trace[859341928] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"230.407561ms","start":"2026-02-06T00:42:33.978691Z","end":"2026-02-06T00:42:34.209098Z","steps":["trace[859341928] 'read index received' (duration: 229.617802ms)","trace[859341928] 'applied index is now lower than readState.Index' (duration: 789.329µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:34.20922Z","caller":"traceutil/trace.go:171","msg":"trace[669752298] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"289.401232ms","start":"2026-02-06T00:42:33.919812Z","end":"2026-02-06T00:42:34.209213Z","steps":["trace[669752298] 'process raft request' (duration: 289.233538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:34.209363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.667357ms","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-06T00:42:34.209384Z","caller":"traceutil/trace.go:171","msg":"trace[66141448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"230.745139ms","start":"2026-02-06T00:42:33.978633Z","end":"2026-02-06T00:42:34.209378Z","steps":["trace[66141448] 'agreement among raft nodes before linearized reading' (duration: 230.664937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:34.209493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.074863ms","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-06T00:42:34.20951Z","caller":"traceutil/trace.go:171","msg":"trace[1490360382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"188.108114ms","start":"2026-02-06T00:42:34.021397Z","end":"2026-02-06T00:42:34.209505Z","steps":["trace[1490360382] 'agreement among raft nodes before linearized reading' (duration: 188.056242ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:40.677952Z","caller":"traceutil/trace.go:171","msg":"trace[349329170] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"430.833077ms","start":"2026-02-06T00:42:40.247093Z","end":"2026-02-06T00:42:40.677927Z","steps":["trace[349329170] 'read index received' (duration: 430.594191ms)","trace[349329170] 'applied index is now lower than readState.Index' (duration: 237.805µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:40.678108Z","caller":"traceutil/trace.go:171","msg":"trace[1387474078] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"432.285101ms","start":"2026-02-06T00:42:40.245814Z","end":"2026-02-06T00:42:40.678099Z","steps":["trace[1387474078] 'process raft request' (duration: 431.921722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:40.678219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:40.245797Z","time spent":"432.339681ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:42:40.67846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.374894ms","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-06T00:42:40.678532Z","caller":"traceutil/trace.go:171","msg":"trace[121020794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"424.484166ms","start":"2026-02-06T00:42:40.254027Z","end":"2026-02-06T00:42:40.678511Z","steps":["trace[121020794] 'agreement among raft nodes before linearized reading' (duration: 424.289722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:40.678571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:40.254018Z","time spent":"424.545058ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:42:40.678613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.9134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:40.67865Z","caller":"traceutil/trace.go:171","msg":"trace[1710004143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"229.94775ms","start":"2026-02-06T00:42:40.448692Z","end":"2026-02-06T00:42:40.678639Z","steps":["trace[1710004143] 'agreement among raft nodes before linearized reading' (duration: 229.890839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:40.678782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.728099ms","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-06T00:42:40.678814Z","caller":"traceutil/trace.go:171","msg":"trace[872866818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"423.770209ms","start":"2026-02-06T00:42:40.255034Z","end":"2026-02-06T00:42:40.678805Z","steps":["trace[872866818] 'agreement among raft nodes before linearized reading' (duration: 423.686407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:40.678874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:40.255027Z","time spent":"423.840711ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:42:40.678874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.783999ms","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-06T00:42:40.678905Z","caller":"traceutil/trace.go:171","msg":"trace[31394987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"431.833599ms","start":"2026-02-06T00:42:40.247063Z","end":"2026-02-06T00:42:40.678896Z","steps":["trace[31394987] 'agreement among raft nodes before linearized reading' (duration: 431.720107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:40.678931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:40.247052Z","time spent":"431.873721ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:42:40.679046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.64462ms","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-06T00:42:40.679095Z","caller":"traceutil/trace.go:171","msg":"trace[1559599557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"424.704442ms","start":"2026-02-06T00:42:40.25438Z","end":"2026-02-06T00:42:40.679085Z","steps":["trace[1559599557] 'agreement among raft nodes before linearized reading' (duration: 424.602179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:40.679122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:40.254375Z","time spent":"424.740203ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-06T00:42:41.478164Z","caller":"traceutil/trace.go:171","msg":"trace[3444351] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"790.74478ms","start":"2026-02-06T00:42:40.687396Z","end":"2026-02-06T00:42:41.47814Z","steps":["trace[3444351] 'process raft request' (duration: 790.581196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:41.478411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:40.687378Z","time spent":"790.945805ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:41.478542Z","caller":"traceutil/trace.go:171","msg":"trace[817347648] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"790.139466ms","start":"2026-02-06T00:42:40.688382Z","end":"2026-02-06T00:42:41.478522Z","steps":["trace[817347648] 'process raft request' (duration: 789.720846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:41.47871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:40.688372Z","time spent":"790.246888ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:41.479072Z","caller":"traceutil/trace.go:171","msg":"trace[294182111] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"789.261146ms","start":"2026-02-06T00:42:40.689799Z","end":"2026-02-06T00:42:41.47906Z","steps":["trace[294182111] 'process raft request' (duration: 788.6064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:41.479135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:40.689757Z","time spent":"789.344378ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:57.168261Z","caller":"traceutil/trace.go:171","msg":"trace[1282559681] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"291.510172ms","start":"2026-02-06T00:42:56.876722Z","end":"2026-02-06T00:42:57.168232Z","steps":["trace[1282559681] 'read index received' (duration: 291.469671ms)","trace[1282559681] 'applied index is now lower than readState.Index' (duration: 39.961µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:57.168364Z","caller":"traceutil/trace.go:171","msg":"trace[1780840777] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"314.34631ms","start":"2026-02-06T00:42:56.854012Z","end":"2026-02-06T00:42:57.168359Z","steps":["trace[1780840777] 'process raft request' (duration: 314.119555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:57.168471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:56.854003Z","time spent":"314.377401ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:42:57.168518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.810279ms","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-06T00:42:57.168569Z","caller":"traceutil/trace.go:171","msg":"trace[1822300114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:734; }","duration":"291.896141ms","start":"2026-02-06T00:42:56.876659Z","end":"2026-02-06T00:42:57.168555Z","steps":["trace[1822300114] 'agreement among raft nodes before linearized reading' (duration: 291.769398ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:57.168837Z","caller":"traceutil/trace.go:171","msg":"trace[1489362707] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"247.735539ms","start":"2026-02-06T00:42:56.921094Z","end":"2026-02-06T00:42:57.168829Z","steps":["trace[1489362707] 'process raft request' (duration: 247.625607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:59.691609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.716666ms","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-06T00:42:59.691671Z","caller":"traceutil/trace.go:171","msg":"trace[1129417749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"148.804888ms","start":"2026-02-06T00:42:59.542852Z","end":"2026-02-06T00:42:59.691656Z","steps":["trace[1129417749] 'range keys from in-memory index tree' (duration: 148.608313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:59.691705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.90841ms","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-06T00:42:59.691758Z","caller":"traceutil/trace.go:171","msg":"trace[1187582714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"134.978772ms","start":"2026-02-06T00:42:59.556764Z","end":"2026-02-06T00:42:59.691743Z","steps":["trace[1187582714] 'range keys from in-memory index tree' (duration: 134.810419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:59.691942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.358133ms","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-06T00:42:59.691962Z","caller":"traceutil/trace.go:171","msg":"trace[2036615996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"155.393503ms","start":"2026-02-06T00:42:59.536562Z","end":"2026-02-06T00:42:59.691956Z","steps":["trace[2036615996] 'range keys from in-memory index tree' (duration: 155.303721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:59.692007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.329886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:59.692035Z","caller":"traceutil/trace.go:171","msg":"trace[1698541024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"243.381218ms","start":"2026-02-06T00:42:59.448649Z","end":"2026-02-06T00:42:59.69203Z","steps":["trace[1698541024] 'range keys from in-memory index tree' (duration: 243.288905ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:00.128143Z","caller":"traceutil/trace.go:171","msg":"trace[1554853004] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"432.744012ms","start":"2026-02-06T00:42:59.695382Z","end":"2026-02-06T00:43:00.128126Z","steps":["trace[1554853004] 'process raft request' (duration: 432.651779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:00.128246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:59.695371Z","time spent":"432.823083ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:00.552373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.719987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:00.552482Z","caller":"traceutil/trace.go:171","msg":"trace[1443820990] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:767; }","duration":"103.182022ms","start":"2026-02-06T00:43:00.449281Z","end":"2026-02-06T00:43:00.552463Z","steps":["trace[1443820990] 'read index received' (duration: 26.471µs)","trace[1443820990] 'applied index is now lower than readState.Index' (duration: 103.154951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:00.552529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.247354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:00.552546Z","caller":"traceutil/trace.go:171","msg":"trace[1372027459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"103.295535ms","start":"2026-02-06T00:43:00.449246Z","end":"2026-02-06T00:43:00.552542Z","steps":["trace[1372027459] 'agreement among raft nodes before linearized reading' (duration: 103.256024ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:00.55271Z","caller":"traceutil/trace.go:171","msg":"trace[1124999524] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"856.140932ms","start":"2026-02-06T00:42:59.696563Z","end":"2026-02-06T00:43:00.552704Z","steps":["trace[1124999524] 'process raft request' (duration: 704.032256ms)","trace[1124999524] 'compare' (duration: 151.620474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:00.552745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:59.696554Z","time spent":"856.175082ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:00.55282Z","caller":"traceutil/trace.go:171","msg":"trace[1083124626] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"854.057732ms","start":"2026-02-06T00:42:59.698758Z","end":"2026-02-06T00:43:00.552816Z","steps":["trace[1083124626] 'process raft request' (duration: 853.673813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:00.552843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:59.698749Z","time spent":"854.081763ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:04.88457Z","caller":"traceutil/trace.go:171","msg":"trace[186563383] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"315.455737ms","start":"2026-02-06T00:43:04.56909Z","end":"2026-02-06T00:43:04.884545Z","steps":["trace[186563383] 'process raft request' (duration: 315.328394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:04.88471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:04.56908Z","time spent":"315.558449ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:04.967997Z","caller":"traceutil/trace.go:171","msg":"trace[1616194149] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"398.015972ms","start":"2026-02-06T00:43:04.569966Z","end":"2026-02-06T00:43:04.967982Z","steps":["trace[1616194149] 'process raft request' (duration: 397.796517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:04.968265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:04.569959Z","time spent":"398.218877ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:05.465596Z","caller":"traceutil/trace.go:171","msg":"trace[195020310] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"148.396399ms","start":"2026-02-06T00:43:05.317175Z","end":"2026-02-06T00:43:05.465572Z","steps":["trace[195020310] 'read index received' (duration: 148.195484ms)","trace[195020310] 'applied index is now lower than readState.Index' (duration: 200.005µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:05.465734Z","caller":"traceutil/trace.go:171","msg":"trace[1527444382] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"247.474624ms","start":"2026-02-06T00:43:05.21825Z","end":"2026-02-06T00:43:05.465724Z","steps":["trace[1527444382] 'process raft request' (duration: 247.200757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:05.466074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.88115ms","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-06T00:43:05.466141Z","caller":"traceutil/trace.go:171","msg":"trace[1160642457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"148.980982ms","start":"2026-02-06T00:43:05.317141Z","end":"2026-02-06T00:43:05.466122Z","steps":["trace[1160642457] 'agreement among raft nodes before linearized reading' (duration: 148.844639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:05.737778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.164645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:43:05.73784Z","caller":"traceutil/trace.go:171","msg":"trace[487601564] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:758; }","duration":"104.287719ms","start":"2026-02-06T00:43:05.633539Z","end":"2026-02-06T00:43:05.737827Z","steps":["trace[487601564] 'count revisions from in-memory index tree' (duration: 104.093884ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:06.584117Z","caller":"traceutil/trace.go:171","msg":"trace[261169488] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"434.996944ms","start":"2026-02-06T00:43:06.149098Z","end":"2026-02-06T00:43:06.584094Z","steps":["trace[261169488] 'process raft request' (duration: 434.868421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:06.584296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:06.149072Z","time spent":"435.154718ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:07.138194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.522391ms","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-06T00:43:07.138285Z","caller":"traceutil/trace.go:171","msg":"trace[265398983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"249.656094ms","start":"2026-02-06T00:43:06.888611Z","end":"2026-02-06T00:43:07.138267Z","steps":["trace[265398983] 'range keys from in-memory index tree' (duration: 249.349817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:07.138581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.768666ms","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-06T00:43:07.13862Z","caller":"traceutil/trace.go:171","msg":"trace[1317966429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"165.862509ms","start":"2026-02-06T00:43:06.972748Z","end":"2026-02-06T00:43:07.13861Z","steps":["trace[1317966429] 'range keys from in-memory index tree' (duration: 165.647344ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:07.721606Z","caller":"traceutil/trace.go:171","msg":"trace[1476863855] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"577.528103ms","start":"2026-02-06T00:43:07.144054Z","end":"2026-02-06T00:43:07.721582Z","steps":["trace[1476863855] 'process raft request' (duration: 577.420961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:07.721703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.144034Z","time spent":"577.620265ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:07.98583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.008508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:07.985975Z","caller":"traceutil/trace.go:171","msg":"trace[1517327966] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"565.16108ms","start":"2026-02-06T00:43:07.420799Z","end":"2026-02-06T00:43:07.98596Z","steps":["trace[1517327966] 'read index received' (duration: 300.82392ms)","trace[1517327966] 'applied index is now lower than readState.Index' (duration: 264.33623ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:07.986207Z","caller":"traceutil/trace.go:171","msg":"trace[1100114307] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"791.547907ms","start":"2026-02-06T00:43:07.194646Z","end":"2026-02-06T00:43:07.986194Z","steps":["trace[1100114307] 'process raft request' (duration: 685.089558ms)","trace[1100114307] 'compare' (duration: 105.756952ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:07.986267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.194628Z","time spent":"791.607768ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:07.986419Z","caller":"traceutil/trace.go:171","msg":"trace[494798023] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"629.716242ms","start":"2026-02-06T00:43:07.356694Z","end":"2026-02-06T00:43:07.98641Z","steps":["trace[494798023] 'process raft request' (duration: 629.21434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:07.986463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.356674Z","time spent":"629.765344ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:07.986606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.802215ms","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-06T00:43:07.986638Z","caller":"traceutil/trace.go:171","msg":"trace[1660959279] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:762; }","duration":"565.833346ms","start":"2026-02-06T00:43:07.420792Z","end":"2026-02-06T00:43:07.986626Z","steps":["trace[1660959279] 'agreement among raft nodes before linearized reading' (duration: 565.778035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:07.986665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.420736Z","time spent":"565.921799ms","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:43:07.986817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.984205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:07.986842Z","caller":"traceutil/trace.go:171","msg":"trace[286482791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"534.035467ms","start":"2026-02-06T00:43:07.452799Z","end":"2026-02-06T00:43:07.986834Z","steps":["trace[286482791] 'agreement among raft nodes before linearized reading' (duration: 533.993896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:07.986867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.452786Z","time spent":"534.075638ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:43:07.987374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.0221ms","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-06T00:43:07.987404Z","caller":"traceutil/trace.go:171","msg":"trace[847443100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"465.093641ms","start":"2026-02-06T00:43:07.522302Z","end":"2026-02-06T00:43:07.987395Z","steps":["trace[847443100] 'agreement among raft nodes before linearized reading' (duration: 464.998769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:07.987429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.522238Z","time spent":"465.139913ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T00:43:07.987577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.355793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-06T00:43:07.987603Z","caller":"traceutil/trace.go:171","msg":"trace[550584717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"517.399595ms","start":"2026-02-06T00:43:07.470196Z","end":"2026-02-06T00:43:07.987595Z","steps":["trace[550584717] 'agreement among raft nodes before linearized reading' (duration: 517.331483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:07.987627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.470185Z","time spent":"517.436655ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T00:43:08.220898Z","caller":"traceutil/trace.go:171","msg":"trace[1389600474] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"223.953548ms","start":"2026-02-06T00:43:07.996925Z","end":"2026-02-06T00:43:08.220879Z","steps":["trace[1389600474] 'process raft request' (duration: 223.914757ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:08.221183Z","caller":"traceutil/trace.go:171","msg":"trace[257181116] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"226.13887ms","start":"2026-02-06T00:43:07.995035Z","end":"2026-02-06T00:43:08.221173Z","steps":["trace[257181116] 'process raft request' (duration: 131.417068ms)","trace[257181116] 'compare' (duration: 94.21626ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:21.511606Z","caller":"traceutil/trace.go:171","msg":"trace[355540567] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"870.423878ms","start":"2026-02-06T00:43:20.64115Z","end":"2026-02-06T00:43:21.511574Z","steps":["trace[355540567] 'process raft request' (duration: 870.152071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:21.511688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:20.64114Z","time spent":"870.504691ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:22.373997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.480035ms","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-06T00:43:22.374046Z","caller":"traceutil/trace.go:171","msg":"trace[1915996970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:849; }","duration":"323.582198ms","start":"2026-02-06T00:43:22.050451Z","end":"2026-02-06T00:43:22.374033Z","steps":["trace[1915996970] 'range keys from in-memory index tree' (duration: 323.383473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:22.374064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:22.050421Z","time spent":"323.637869ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:43:22.374048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.714898ms","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-06T00:43:22.374184Z","caller":"traceutil/trace.go:171","msg":"trace[688271052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:849; }","duration":"602.888122ms","start":"2026-02-06T00:43:21.771291Z","end":"2026-02-06T00:43:22.374179Z","steps":["trace[688271052] 'range keys from in-memory index tree' (duration: 602.609445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:22.374199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:21.771279Z","time spent":"602.915252ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T00:43:23.551428Z","caller":"traceutil/trace.go:171","msg":"trace[949336812] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"102.393454ms","start":"2026-02-06T00:43:23.449019Z","end":"2026-02-06T00:43:23.551412Z","steps":["trace[949336812] 'read index received' (duration: 102.288961ms)","trace[949336812] 'applied index is now lower than readState.Index' (duration: 103.833µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:23.551496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:23.036245Z","time spent":"515.248796ms","remote":"127.0.0.1:33278","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-06T00:43:23.551689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.65846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:23.551705Z","caller":"traceutil/trace.go:171","msg":"trace[49835627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"102.705671ms","start":"2026-02-06T00:43:23.448995Z","end":"2026-02-06T00:43:23.5517Z","steps":["trace[49835627] 'agreement among raft nodes before linearized reading' (duration: 102.66364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:23.75375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.171436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:23.753919Z","caller":"traceutil/trace.go:171","msg":"trace[1701504229] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"197.70789ms","start":"2026-02-06T00:43:23.556195Z","end":"2026-02-06T00:43:23.753903Z","steps":["trace[1701504229] 'process raft request' (duration: 197.644778ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:23.754283Z","caller":"traceutil/trace.go:171","msg":"trace[206571019] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"200.21969ms","start":"2026-02-06T00:43:23.554049Z","end":"2026-02-06T00:43:23.754268Z","steps":["trace[206571019] 'process raft request' (duration: 92.419659ms)","trace[206571019] 'compare' (duration: 107.070183ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:26.079387Z","caller":"traceutil/trace.go:171","msg":"trace[556340036] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"319.114331ms","start":"2026-02-06T00:43:25.760256Z","end":"2026-02-06T00:43:26.07937Z","steps":["trace[556340036] 'process raft request' (duration: 319.000088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:26.079492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:25.760241Z","time spent":"319.199693ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:26.518646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.612444ms","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-06T00:43:26.518693Z","caller":"traceutil/trace.go:171","msg":"trace[541847634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:861; }","duration":"120.691335ms","start":"2026-02-06T00:43:26.397991Z","end":"2026-02-06T00:43:26.518683Z","steps":["trace[541847634] 'range keys from in-memory index tree' (duration: 120.49475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:26.518827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.707098ms","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-06T00:43:26.518879Z","caller":"traceutil/trace.go:171","msg":"trace[1657879784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:861; }","duration":"118.7765ms","start":"2026-02-06T00:43:26.400088Z","end":"2026-02-06T00:43:26.518864Z","steps":["trace[1657879784] 'range keys from in-memory index tree' (duration: 118.606686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:26.518951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.535637ms","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-06T00:43:26.518967Z","caller":"traceutil/trace.go:171","msg":"trace[2049185031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:861; }","duration":"102.562887ms","start":"2026-02-06T00:43:26.416399Z","end":"2026-02-06T00:43:26.518962Z","steps":["trace[2049185031] 'range keys from in-memory index tree' (duration: 102.492886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:26.519051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.398604ms","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-06T00:43:26.519063Z","caller":"traceutil/trace.go:171","msg":"trace[1060558397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:861; }","duration":"105.420945ms","start":"2026-02-06T00:43:26.413638Z","end":"2026-02-06T00:43:26.519059Z","steps":["trace[1060558397] 'range keys from in-memory index tree' (duration: 105.361934ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:28.649808Z","caller":"traceutil/trace.go:171","msg":"trace[1020266735] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"100.991961ms","start":"2026-02-06T00:43:28.548798Z","end":"2026-02-06T00:43:28.64979Z","steps":["trace[1020266735] 'process raft request' (duration: 100.864758ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:32.990245Z","caller":"traceutil/trace.go:171","msg":"trace[1047780945] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"329.278189ms","start":"2026-02-06T00:43:32.660938Z","end":"2026-02-06T00:43:32.990216Z","steps":["trace[1047780945] 'process raft request' (duration: 329.181527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:32.990373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:32.66093Z","time spent":"329.379361ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:34.377148Z","caller":"traceutil/trace.go:171","msg":"trace[721139012] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"272.143964ms","start":"2026-02-06T00:43:34.104983Z","end":"2026-02-06T00:43:34.377127Z","steps":["trace[721139012] 'read index received' (duration: 271.953719ms)","trace[721139012] 'applied index is now lower than readState.Index' (duration: 189.345µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:34.377363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.360599ms","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-06T00:43:34.377405Z","caller":"traceutil/trace.go:171","msg":"trace[997748829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"272.439951ms","start":"2026-02-06T00:43:34.104954Z","end":"2026-02-06T00:43:34.377394Z","steps":["trace[997748829] 'agreement among raft nodes before linearized reading' (duration: 272.261897ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:34.377729Z","caller":"traceutil/trace.go:171","msg":"trace[864134155] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"337.589014ms","start":"2026-02-06T00:43:34.04013Z","end":"2026-02-06T00:43:34.377719Z","steps":["trace[864134155] 'process raft request' (duration: 336.858267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:34.377808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:34.040071Z","time spent":"337.687347ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:34.724192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.676259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:34.724247Z","caller":"traceutil/trace.go:171","msg":"trace[1534741383] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"176.540394ms","start":"2026-02-06T00:43:34.547697Z","end":"2026-02-06T00:43:34.724237Z","steps":["trace[1534741383] 'read index received' (duration: 20.961µs)","trace[1534741383] 'applied index is now lower than readState.Index' (duration: 176.518473ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:34.724355Z","caller":"traceutil/trace.go:171","msg":"trace[1507776219] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"342.560831ms","start":"2026-02-06T00:43:34.381787Z","end":"2026-02-06T00:43:34.724348Z","steps":["trace[1507776219] 'process raft request' (duration: 142.684517ms)","trace[1507776219] 'compare' (duration: 199.576697ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:34.724385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:34.381773Z","time spent":"342.595602ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:34.724508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.81099ms","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-06T00:43:34.724521Z","caller":"traceutil/trace.go:171","msg":"trace[695143751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:884; }","duration":"176.823451ms","start":"2026-02-06T00:43:34.547693Z","end":"2026-02-06T00:43:34.724516Z","steps":["trace[695143751] 'agreement among raft nodes before linearized reading' (duration: 176.767049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:34.724607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.806647ms","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-06T00:43:34.724619Z","caller":"traceutil/trace.go:171","msg":"trace[1149281548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"175.818857ms","start":"2026-02-06T00:43:34.548797Z","end":"2026-02-06T00:43:34.724615Z","steps":["trace[1149281548] 'agreement among raft nodes before linearized reading' (duration: 175.773656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:34.724713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.342096ms","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-06T00:43:34.724725Z","caller":"traceutil/trace.go:171","msg":"trace[914711488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"161.363877ms","start":"2026-02-06T00:43:34.563357Z","end":"2026-02-06T00:43:34.724721Z","steps":["trace[914711488] 'agreement among raft nodes before linearized reading' (duration: 161.330837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:35.541432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.394663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:35.541608Z","caller":"traceutil/trace.go:171","msg":"trace[666242990] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:918; }","duration":"592.38468ms","start":"2026-02-06T00:43:34.949209Z","end":"2026-02-06T00:43:35.541593Z","steps":["trace[666242990] 'read index received' (duration: 38.767612ms)","trace[666242990] 'applied index is now lower than readState.Index' (duration: 553.616178ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:35.541802Z","caller":"traceutil/trace.go:171","msg":"trace[1030285403] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"814.718002ms","start":"2026-02-06T00:43:34.727072Z","end":"2026-02-06T00:43:35.54179Z","steps":["trace[1030285403] 'process raft request' (duration: 260.897839ms)","trace[1030285403] 'compare' (duration: 552.527082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:35.541867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:34.727061Z","time spent":"814.773313ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:35.542932Z","caller":"traceutil/trace.go:171","msg":"trace[402351203] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"814.137748ms","start":"2026-02-06T00:43:34.727862Z","end":"2026-02-06T00:43:35.541999Z","steps":["trace[402351203] 'process raft request' (duration: 813.654156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:35.543013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:34.727856Z","time spent":"815.117452ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:35.543163Z","caller":"traceutil/trace.go:171","msg":"trace[32335226] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"813.587565ms","start":"2026-02-06T00:43:34.729564Z","end":"2026-02-06T00:43:35.543151Z","steps":["trace[32335226] 'process raft request' (duration: 812.001268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:35.543258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:34.729558Z","time spent":"813.670518ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:35.543415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.200013ms","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-06T00:43:35.543452Z","caller":"traceutil/trace.go:171","msg":"trace[2084715444] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:887; }","duration":"594.296585ms","start":"2026-02-06T00:43:34.949144Z","end":"2026-02-06T00:43:35.543441Z","steps":["trace[2084715444] 'agreement among raft nodes before linearized reading' (duration: 594.227303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:35.543486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:34.949132Z","time spent":"594.346056ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:43:35.544851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.805671ms","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-06T00:43:35.544938Z","caller":"traceutil/trace.go:171","msg":"trace[1150700093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"550.855822ms","start":"2026-02-06T00:43:34.994029Z","end":"2026-02-06T00:43:35.544884Z","steps":["trace[1150700093] 'agreement among raft nodes before linearized reading' (duration: 549.593163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:35.544975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:34.993982Z","time spent":"550.985275ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-06T00:43:42.977045Z","caller":"traceutil/trace.go:171","msg":"trace[1798150563] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"208.65547ms","start":"2026-02-06T00:43:42.768373Z","end":"2026-02-06T00:43:42.977029Z","steps":["trace[1798150563] 'process raft request' (duration: 208.268061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:43.282601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.877926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T00:43:43.282656Z","caller":"traceutil/trace.go:171","msg":"trace[1475426698] range","detail":"{range_begin:/registry/masterleases/199.204.45.102; range_end:; response_count:1; response_revision:925; }","duration":"245.975639ms","start":"2026-02-06T00:43:43.036669Z","end":"2026-02-06T00:43:43.282644Z","steps":["trace[1475426698] 'range keys from in-memory index tree' (duration: 245.715512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:43.282869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.871485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:43:43.282882Z","caller":"traceutil/trace.go:171","msg":"trace[1403301021] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:925; }","duration":"222.916976ms","start":"2026-02-06T00:43:43.059961Z","end":"2026-02-06T00:43:43.282878Z","steps":["trace[1403301021] 'count revisions from in-memory index tree' (duration: 222.818664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:43.282963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.84501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:43.282975Z","caller":"traceutil/trace.go:171","msg":"trace[926071856] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:925; }","duration":"184.88355ms","start":"2026-02-06T00:43:43.098088Z","end":"2026-02-06T00:43:43.282971Z","steps":["trace[926071856] 'count revisions from in-memory index tree' (duration: 184.792289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:43.948347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256366447752992,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:43:44.092077Z","caller":"traceutil/trace.go:171","msg":"trace[1054446712] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"683.315899ms","start":"2026-02-06T00:43:43.408751Z","end":"2026-02-06T00:43:44.092067Z","steps":["trace[1054446712] 'process raft request' (duration: 677.173674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:44.092182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:43.408735Z","time spent":"683.381881ms","remote":"127.0.0.1:33278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:44.092036Z","caller":"traceutil/trace.go:171","msg":"trace[1757335651] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"644.030814ms","start":"2026-02-06T00:43:43.447984Z","end":"2026-02-06T00:43:44.092014Z","steps":["trace[1757335651] 'read index received' (duration: 637.90241ms)","trace[1757335651] 'applied index is now lower than readState.Index' (duration: 6.127544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:44.09257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.581287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:44.092601Z","caller":"traceutil/trace.go:171","msg":"trace[1321566373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"644.639079ms","start":"2026-02-06T00:43:43.447952Z","end":"2026-02-06T00:43:44.092591Z","steps":["trace[1321566373] 'agreement among raft nodes before linearized reading' (duration: 644.565907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:44.092627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:43.447941Z","time spent":"644.68008ms","remote":"127.0.0.1:33250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:43:44.096652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.293895ms","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-06T00:43:44.096695Z","caller":"traceutil/trace.go:171","msg":"trace[442434764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"515.370766ms","start":"2026-02-06T00:43:43.581312Z","end":"2026-02-06T00:43:44.096683Z","steps":["trace[442434764] 'agreement among raft nodes before linearized reading' (duration: 515.239993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:44.096725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:43.581292Z","time spent":"515.426458ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:43:44.096999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.470702ms","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-06T00:43:44.097028Z","caller":"traceutil/trace.go:171","msg":"trace[894142864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"482.528104ms","start":"2026-02-06T00:43:43.614492Z","end":"2026-02-06T00:43:44.09702Z","steps":["trace[894142864] 'agreement among raft nodes before linearized reading' (duration: 482.443942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:44.097054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:43.614478Z","time spent":"482.570425ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T00:43:44.097243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.952268ms","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-06T00:43:44.09727Z","caller":"traceutil/trace.go:171","msg":"trace[2076647734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"506.99509ms","start":"2026-02-06T00:43:43.590267Z","end":"2026-02-06T00:43:44.097262Z","steps":["trace[2076647734] 'agreement among raft nodes before linearized reading' (duration: 506.916248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:44.097295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:43.590259Z","time spent":"507.03059ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:43:44.097453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.54639ms","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-06T00:43:44.097478Z","caller":"traceutil/trace.go:171","msg":"trace[625778640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"512.600632ms","start":"2026-02-06T00:43:43.58487Z","end":"2026-02-06T00:43:44.09747Z","steps":["trace[625778640] 'agreement among raft nodes before linearized reading' (duration: 512.53348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:44.097505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:43.584842Z","time spent":"512.657572ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:43:45.056228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.690264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:45.056393Z","caller":"traceutil/trace.go:171","msg":"trace[1007268439] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:963; }","duration":"604.745251ms","start":"2026-02-06T00:43:44.451637Z","end":"2026-02-06T00:43:45.056383Z","steps":["trace[1007268439] 'read index received' (duration: 230.853022ms)","trace[1007268439] 'applied index is now lower than readState.Index' (duration: 373.891709ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:45.056512Z","caller":"traceutil/trace.go:171","msg":"trace[1264589139] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"951.705396ms","start":"2026-02-06T00:43:44.104801Z","end":"2026-02-06T00:43:45.056506Z","steps":["trace[1264589139] 'process raft request' (duration: 577.678344ms)","trace[1264589139] 'compare' (duration: 373.556081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:45.056546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:44.104793Z","time spent":"951.735996ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:45.056642Z","caller":"traceutil/trace.go:171","msg":"trace[1328008937] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"951.308196ms","start":"2026-02-06T00:43:44.10533Z","end":"2026-02-06T00:43:45.056638Z","steps":["trace[1328008937] 'process raft request' (duration: 950.970638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:45.056666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:44.105322Z","time spent":"951.330477ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:45.056722Z","caller":"traceutil/trace.go:171","msg":"trace[1610254856] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"945.448169ms","start":"2026-02-06T00:43:44.11127Z","end":"2026-02-06T00:43:45.056718Z","steps":["trace[1610254856] 'process raft request' (duration: 945.08158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:45.056755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:44.111261Z","time spent":"945.480039ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:45.056822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.185731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:45.056836Z","caller":"traceutil/trace.go:171","msg":"trace[465351624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"605.231002ms","start":"2026-02-06T00:43:44.4516Z","end":"2026-02-06T00:43:45.056831Z","steps":["trace[465351624] 'agreement among raft nodes before linearized reading' (duration: 605.207962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:45.056849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:44.451582Z","time spent":"605.264214ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T00:43:45.063276Z","caller":"traceutil/trace.go:171","msg":"trace[1052743981] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"610.726371ms","start":"2026-02-06T00:43:44.452534Z","end":"2026-02-06T00:43:45.06326Z","steps":["trace[1052743981] 'process raft request' (duration: 610.514676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:45.063372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:44.452512Z","time spent":"610.807254ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:45.950152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.012967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:45.950239Z","caller":"traceutil/trace.go:171","msg":"trace[859061790] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"344.600339ms","start":"2026-02-06T00:43:45.605624Z","end":"2026-02-06T00:43:45.950224Z","steps":["trace[859061790] 'read index received' (duration: 28.2µs)","trace[859061790] 'applied index is now lower than readState.Index' (duration: 344.571039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:45.950326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.701632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:45.950354Z","caller":"traceutil/trace.go:171","msg":"trace[425548032] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:932; }","duration":"344.758512ms","start":"2026-02-06T00:43:45.605586Z","end":"2026-02-06T00:43:45.950344Z","steps":["trace[425548032] 'agreement among raft nodes before linearized reading' (duration: 344.681941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:45.950386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:45.60557Z","time spent":"344.807664ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-02-06T00:43:45.950634Z","caller":"traceutil/trace.go:171","msg":"trace[578078180] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"882.641971ms","start":"2026-02-06T00:43:45.06798Z","end":"2026-02-06T00:43:45.950622Z","steps":["trace[578078180] 'process raft request' (duration: 195.076811ms)","trace[578078180] 'compare' (duration: 686.332891ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:45.950694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:45.067965Z","time spent":"882.699032ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:56.60959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.309096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:43:56.609819Z","caller":"traceutil/trace.go:171","msg":"trace[672717962] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1005; }","duration":"379.572392ms","start":"2026-02-06T00:43:56.230216Z","end":"2026-02-06T00:43:56.609789Z","steps":["trace[672717962] 'count revisions from in-memory index tree' (duration: 379.238654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.609874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:56.230193Z","time spent":"379.667505ms","remote":"127.0.0.1:33426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:43:56.610084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.910561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-02-06T00:43:56.610346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.502417ms","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-06T00:43:56.610383Z","caller":"traceutil/trace.go:171","msg":"trace[956396025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1005; }","duration":"378.560848ms","start":"2026-02-06T00:43:56.231816Z","end":"2026-02-06T00:43:56.610377Z","steps":["trace[956396025] 'range keys from in-memory index tree' (duration: 378.351793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.61041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:56.231757Z","time spent":"378.647221ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-06T00:43:56.611632Z","caller":"traceutil/trace.go:171","msg":"trace[1761472998] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1005; }","duration":"286.998423ms","start":"2026-02-06T00:43:56.323131Z","end":"2026-02-06T00:43:56.61013Z","steps":["trace[1761472998] 'range keys from in-memory index tree' (duration: 286.523092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.611762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.862651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:56.611848Z","caller":"traceutil/trace.go:171","msg":"trace[1502418072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"159.970644ms","start":"2026-02-06T00:43:56.451871Z","end":"2026-02-06T00:43:56.611841Z","steps":["trace[1502418072] 'range keys from in-memory index tree' (duration: 159.8033ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:05.038299Z","caller":"traceutil/trace.go:171","msg":"trace[879067027] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"108.350938ms","start":"2026-02-06T00:47:04.929922Z","end":"2026-02-06T00:47:05.038272Z","steps":["trace[879067027] 'process raft request' (duration: 108.205684ms)"],"step_count":1}