{"level":"warn","ts":"2026-02-11T18:18:17.907282Z","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-11T18:18:17.907424Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-11T18:18:17.907683Z","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-11T18:18:17.907701Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-11T18:18:17.907731Z","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-11T18:18:17.908876Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-02-11T18:18:17.909053Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-11T18:18:17.931878Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"22.511879ms"} {"level":"info","ts":"2026-02-11T18:18:17.968372Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-02-11T18:18:17.968515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T18:18:17.968545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-02-11T18:18:17.968586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T18:18:17.968595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-02-11T18:18:17.96867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-02-11T18:18:17.998843Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T18:18:18.004497Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T18:18:18.03617Z","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-11T18:18:18.047093Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T18:18:18.047831Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T18:18:18.047844Z","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-11T18:18:18.048115Z","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-11T18:18:18.048124Z","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-11T18:18:18.051454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-02-11T18:18:18.051589Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-11T18:18:18.091167Z","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-11T18:18:18.091353Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-11T18:18:18.091391Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-11T18:18:18.091559Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T18:18:18.091629Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T18:18:18.869271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T18:18:18.869334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T18:18:18.869357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-02-11T18:18:18.869379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-02-11T18:18:18.869386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-11T18:18:18.869405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-02-11T18:18:18.869415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-11T18:18:18.872574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T18:18:18.884558Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T18:18:18.884937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T18:18:18.887559Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T18:18:18.890152Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T18:18:18.894236Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T18:18:18.888718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T18:18:18.892003Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T18:18:18.894465Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T18:18:18.90366Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T18:18:18.921137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-02-11T18:18:36.629428Z","caller":"traceutil/trace.go:171","msg":"trace[1928963016] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"224.467475ms","start":"2026-02-11T18:18:36.404938Z","end":"2026-02-11T18:18:36.629406Z","steps":["trace[1928963016] 'process raft request' (duration: 224.358392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:37.981815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.087911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:18:37.982088Z","caller":"traceutil/trace.go:171","msg":"trace[1006764500] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"1.283594926s","start":"2026-02-11T18:18:36.698479Z","end":"2026-02-11T18:18:37.982073Z","steps":["trace[1006764500] 'process raft request' (duration: 553.939408ms)","trace[1006764500] 'compare' (duration: 729.000387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:18:37.982155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:36.698468Z","time spent":"1.283655828s","remote":"127.0.0.1:41084","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-11T18:18:37.982355Z","caller":"traceutil/trace.go:171","msg":"trace[1462281149] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"1.282025357s","start":"2026-02-11T18:18:36.700325Z","end":"2026-02-11T18:18:37.98235Z","steps":["trace[1462281149] 'process raft request' (duration: 1.281576363s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:37.982386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:36.700314Z","time spent":"1.282057448s","remote":"127.0.0.1:41084","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-11T18:18:37.982479Z","caller":"traceutil/trace.go:171","msg":"trace[1509952818] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:463; }","duration":"986.579967ms","start":"2026-02-11T18:18:36.995893Z","end":"2026-02-11T18:18:37.982473Z","steps":["trace[1509952818] 'read index received' (duration: 256.533906ms)","trace[1509952818] 'applied index is now lower than readState.Index' (duration: 730.045371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:18:38.032018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.496253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:18:38.032111Z","caller":"traceutil/trace.go:171","msg":"trace[608783805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:453; }","duration":"979.635207ms","start":"2026-02-11T18:18:37.052461Z","end":"2026-02-11T18:18:38.032096Z","steps":["trace[608783805] 'agreement among raft nodes before linearized reading' (duration: 979.500173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:38.032144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:37.052445Z","time spent":"979.691619ms","remote":"127.0.0.1:40796","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-11T18:18:38.032383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036486917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T18:18:38.03241Z","caller":"traceutil/trace.go:171","msg":"trace[617188866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:453; }","duration":"1.036513568s","start":"2026-02-11T18:18:36.995888Z","end":"2026-02-11T18:18:38.032402Z","steps":["trace[617188866] 'agreement among raft nodes before linearized reading' (duration: 986.640698ms)","trace[617188866] 'range keys from in-memory index tree' (duration: 49.833608ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:18:38.032435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:36.995842Z","time spent":"1.03658714s","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T18:18:50.557354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959381,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:18:51.057656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959381,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:18:51.275894Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.235490881s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T18:18:51.276258Z","caller":"traceutil/trace.go:171","msg":"trace[1973061670] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.235884964s","start":"2026-02-11T18:18:50.040329Z","end":"2026-02-11T18:18:51.276214Z","steps":["trace[1973061670] 'process raft request' (duration: 1.235731349s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:51.276394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:50.040319Z","time spent":"1.235998337s","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T18:18:51.39374Z","caller":"traceutil/trace.go:171","msg":"trace[1953209978] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"632.278013ms","start":"2026-02-11T18:18:50.761431Z","end":"2026-02-11T18:18:51.393709Z","steps":["trace[1953209978] 'process raft request' (duration: 582.033161ms)","trace[1953209978] 'compare' (duration: 49.119496ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:18:51.394026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34154279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:18:51.394063Z","caller":"traceutil/trace.go:171","msg":"trace[1826238109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"1.341616722s","start":"2026-02-11T18:18:50.052436Z","end":"2026-02-11T18:18:51.394053Z","steps":["trace[1826238109] 'agreement among raft nodes before linearized reading' (duration: 1.34154355s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:51.394071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:50.761395Z","time spent":"632.627053ms","remote":"127.0.0.1:41084","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-11T18:18:51.394096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:50.052426Z","time spent":"1.341659303s","remote":"127.0.0.1:40796","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-11T18:18:51.393954Z","caller":"traceutil/trace.go:171","msg":"trace[726237828] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"1.340444485s","start":"2026-02-11T18:18:50.052465Z","end":"2026-02-11T18:18:51.392909Z","steps":["trace[726237828] 'read index received' (duration: 1.224383832s)","trace[726237828] 'applied index is now lower than readState.Index' (duration: 116.059653ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:18:51.394223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333457276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T18:18:51.394238Z","caller":"traceutil/trace.go:171","msg":"trace[859968383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"1.333513097s","start":"2026-02-11T18:18:50.06072Z","end":"2026-02-11T18:18:51.394233Z","steps":["trace[859968383] 'agreement among raft nodes before linearized reading' (duration: 1.333484206s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:51.394251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:50.060705Z","time spent":"1.333542658s","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T18:18:51.394355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.97224ms","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-11T18:18:51.394368Z","caller":"traceutil/trace.go:171","msg":"trace[1378396335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"711.027572ms","start":"2026-02-11T18:18:50.683336Z","end":"2026-02-11T18:18:51.394364Z","steps":["trace[1378396335] 'agreement among raft nodes before linearized reading' (duration: 710.97473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:51.394384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:50.683316Z","time spent":"711.064403ms","remote":"127.0.0.1:41084","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-11T18:18:51.394411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292223947s","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-11T18:18:51.394439Z","caller":"traceutil/trace.go:171","msg":"trace[842882023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"1.292282629s","start":"2026-02-11T18:18:50.102148Z","end":"2026-02-11T18:18:51.394431Z","steps":["trace[842882023] 'agreement among raft nodes before linearized reading' (duration: 1.292196347s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:51.394466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:50.102131Z","time spent":"1.292328261s","remote":"127.0.0.1:41084","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-11T18:18:55.918554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959413,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T18:18:55.920983Z","caller":"traceutil/trace.go:171","msg":"trace[1343321089] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"502.786838ms","start":"2026-02-11T18:18:55.418168Z","end":"2026-02-11T18:18:55.920955Z","steps":["trace[1343321089] 'read index received' (duration: 502.292462ms)","trace[1343321089] 'applied index is now lower than readState.Index' (duration: 493.516µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:18:55.921383Z","caller":"traceutil/trace.go:171","msg":"trace[902920388] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"618.313974ms","start":"2026-02-11T18:18:55.303061Z","end":"2026-02-11T18:18:55.921375Z","steps":["trace[902920388] 'process raft request' (duration: 617.442507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:55.921968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.234816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T18:18:55.921991Z","caller":"traceutil/trace.go:171","msg":"trace[1191271765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"493.275438ms","start":"2026-02-11T18:18:55.428708Z","end":"2026-02-11T18:18:55.921984Z","steps":["trace[1191271765] 'agreement among raft nodes before linearized reading' (duration: 493.200676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:55.922225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:55.428698Z","time spent":"493.520086ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T18:18:55.922525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.357447ms","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-11T18:18:55.922547Z","caller":"traceutil/trace.go:171","msg":"trace[451473723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"504.415839ms","start":"2026-02-11T18:18:55.41812Z","end":"2026-02-11T18:18:55.922536Z","steps":["trace[451473723] 'agreement among raft nodes before linearized reading' (duration: 504.348216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:55.922561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:55.418101Z","time spent":"504.45632ms","remote":"127.0.0.1:41084","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-11T18:18:55.922774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.718001ms","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-11T18:18:55.922793Z","caller":"traceutil/trace.go:171","msg":"trace[595205760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"486.789193ms","start":"2026-02-11T18:18:55.435995Z","end":"2026-02-11T18:18:55.922784Z","steps":["trace[595205760] 'agreement among raft nodes before linearized reading' (duration: 486.68708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:18:55.922805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:55.435968Z","time spent":"486.833995ms","remote":"127.0.0.1:41084","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-11T18:18:55.921454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:55.303038Z","time spent":"618.365586ms","remote":"127.0.0.1:41084","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-11T18:18:56.082921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.403732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:18:56.083347Z","caller":"traceutil/trace.go:171","msg":"trace[791249680] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"150.108765ms","start":"2026-02-11T18:18:55.933217Z","end":"2026-02-11T18:18:56.083325Z","steps":["trace[791249680] 'process raft request' (duration: 149.843597ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:18:56.084092Z","caller":"traceutil/trace.go:171","msg":"trace[911371966] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"154.71612ms","start":"2026-02-11T18:18:55.929356Z","end":"2026-02-11T18:18:56.084072Z","steps":["trace[911371966] 'process raft request' (duration: 38.079439ms)","trace[911371966] 'compare' (duration: 115.30894ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:18:56.083882Z","caller":"traceutil/trace.go:171","msg":"trace[53043428] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"153.652107ms","start":"2026-02-11T18:18:55.930216Z","end":"2026-02-11T18:18:56.083868Z","steps":["trace[53043428] 'process raft request' (duration: 152.8006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.031068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.135634ms","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-11T18:19:01.0331Z","caller":"traceutil/trace.go:171","msg":"trace[1734720295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"935.21462ms","start":"2026-02-11T18:19:00.097866Z","end":"2026-02-11T18:19:01.03308Z","steps":["trace[1734720295] 'range keys from in-memory index tree' (duration: 932.938158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.033176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:00.097855Z","time spent":"935.305633ms","remote":"127.0.0.1:41084","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-11T18:19:01.031134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.928626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T18:19:01.03338Z","caller":"traceutil/trace.go:171","msg":"trace[88385100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"934.208208ms","start":"2026-02-11T18:19:00.099161Z","end":"2026-02-11T18:19:01.033369Z","steps":["trace[88385100] 'range keys from in-memory index tree' (duration: 931.822673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.033416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:00.099151Z","time spent":"934.253029ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T18:19:01.032194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048232008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4pwmg\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-11T18:19:01.033564Z","caller":"traceutil/trace.go:171","msg":"trace[399049320] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4pwmg; range_end:; response_count:1; response_revision:535; }","duration":"1.049628351s","start":"2026-02-11T18:18:59.983926Z","end":"2026-02-11T18:19:01.033555Z","steps":["trace[399049320] 'range keys from in-memory index tree' (duration: 1.048117964s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.033599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:59.983911Z","time spent":"1.049679593s","remote":"127.0.0.1:40976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-4pwmg\" "} {"level":"warn","ts":"2026-02-11T18:19:01.032265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088861546s","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-11T18:19:01.033759Z","caller":"traceutil/trace.go:171","msg":"trace[8011896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"1.090378734s","start":"2026-02-11T18:18:59.943372Z","end":"2026-02-11T18:19:01.03375Z","steps":["trace[8011896] 'range keys from in-memory index tree' (duration: 1.088769974s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.033794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:18:59.943357Z","time spent":"1.090429446s","remote":"127.0.0.1:41084","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-11T18:19:01.032349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.190264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:01.034002Z","caller":"traceutil/trace.go:171","msg":"trace[1490286666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"980.880817ms","start":"2026-02-11T18:19:00.053112Z","end":"2026-02-11T18:19:01.033993Z","steps":["trace[1490286666] 'range keys from in-memory index tree' (duration: 979.092301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.034041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:00.053091Z","time spent":"980.941479ms","remote":"127.0.0.1:40796","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-11T18:19:01.032457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.207181ms","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-11T18:19:01.034272Z","caller":"traceutil/trace.go:171","msg":"trace[612969265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"938.050759ms","start":"2026-02-11T18:19:00.096212Z","end":"2026-02-11T18:19:01.034263Z","steps":["trace[612969265] 'range keys from in-memory index tree' (duration: 936.125428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.034305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:00.096194Z","time spent":"938.10195ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T18:19:01.549474Z","caller":"traceutil/trace.go:171","msg":"trace[1819920733] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"504.003737ms","start":"2026-02-11T18:19:01.045446Z","end":"2026-02-11T18:19:01.54945Z","steps":["trace[1819920733] 'process raft request' (duration: 503.833982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.549623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:01.045436Z","time spent":"504.11058ms","remote":"127.0.0.1:41084","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-11T18:19:01.58392Z","caller":"traceutil/trace.go:171","msg":"trace[2008690920] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"536.419977ms","start":"2026-02-11T18:19:01.047474Z","end":"2026-02-11T18:19:01.583894Z","steps":["trace[2008690920] 'process raft request' (duration: 536.293703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.584013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.474681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:01.58404Z","caller":"traceutil/trace.go:171","msg":"trace[1082934455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"112.550184ms","start":"2026-02-11T18:19:01.471483Z","end":"2026-02-11T18:19:01.584033Z","steps":["trace[1082934455] 'agreement among raft nodes before linearized reading' (duration: 112.479442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.584058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:01.047456Z","time spent":"536.538671ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T18:19:01.584187Z","caller":"traceutil/trace.go:171","msg":"trace[1240848602] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"536.611223ms","start":"2026-02-11T18:19:01.047569Z","end":"2026-02-11T18:19:01.584181Z","steps":["trace[1240848602] 'process raft request' (duration: 536.270143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.584235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:01.047564Z","time spent":"536.636674ms","remote":"127.0.0.1:41084","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-11T18:19:01.584307Z","caller":"traceutil/trace.go:171","msg":"trace[551515450] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"536.622453ms","start":"2026-02-11T18:19:01.047679Z","end":"2026-02-11T18:19:01.584301Z","steps":["trace[551515450] 'process raft request' (duration: 536.181309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.584331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:01.047672Z","time spent":"536.645395ms","remote":"127.0.0.1:41084","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-11T18:19:01.58443Z","caller":"traceutil/trace.go:171","msg":"trace[656426390] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"535.396995ms","start":"2026-02-11T18:19:01.049029Z","end":"2026-02-11T18:19:01.584426Z","steps":["trace[656426390] 'process raft request' (duration: 534.851458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:01.584457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:01.04902Z","time spent":"535.422656ms","remote":"127.0.0.1:40976","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-11T18:19:01.583933Z","caller":"traceutil/trace.go:171","msg":"trace[1327535669] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:553; }","duration":"112.394248ms","start":"2026-02-11T18:19:01.471524Z","end":"2026-02-11T18:19:01.583919Z","steps":["trace[1327535669] 'read index received' (duration: 78.659786ms)","trace[1327535669] 'applied index is now lower than readState.Index' (duration: 33.733662ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:19:08.302172Z","caller":"traceutil/trace.go:171","msg":"trace[1398458856] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"149.567239ms","start":"2026-02-11T18:19:08.152595Z","end":"2026-02-11T18:19:08.302162Z","steps":["trace[1398458856] 'process raft request' (duration: 149.183407ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:08.301925Z","caller":"traceutil/trace.go:171","msg":"trace[1609604695] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"117.231031ms","start":"2026-02-11T18:19:08.184679Z","end":"2026-02-11T18:19:08.30191Z","steps":["trace[1609604695] 'read index received' (duration: 117.010474ms)","trace[1609604695] 'applied index is now lower than readState.Index' (duration: 219.887µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:08.302488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.737296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-11T18:19:08.302535Z","caller":"traceutil/trace.go:171","msg":"trace[1372216318] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:632; }","duration":"117.801488ms","start":"2026-02-11T18:19:08.184727Z","end":"2026-02-11T18:19:08.302528Z","steps":["trace[1372216318] 'agreement among raft nodes before linearized reading' (duration: 117.693755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:08.304016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.48552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-11T18:19:08.304047Z","caller":"traceutil/trace.go:171","msg":"trace[480215462] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:632; }","duration":"119.386318ms","start":"2026-02-11T18:19:08.184653Z","end":"2026-02-11T18:19:08.304039Z","steps":["trace[480215462] 'agreement among raft nodes before linearized reading' (duration: 118.49057ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:08.851991Z","caller":"traceutil/trace.go:171","msg":"trace[1231434154] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"111.22993ms","start":"2026-02-11T18:19:08.740731Z","end":"2026-02-11T18:19:08.851961Z","steps":["trace[1231434154] 'read index received' (duration: 110.701184ms)","trace[1231434154] 'applied index is now lower than readState.Index' (duration: 528.146µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:08.852182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.432977ms","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-11T18:19:08.852209Z","caller":"traceutil/trace.go:171","msg":"trace[747688729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:634; }","duration":"111.497959ms","start":"2026-02-11T18:19:08.740705Z","end":"2026-02-11T18:19:08.852203Z","steps":["trace[747688729] 'agreement among raft nodes before linearized reading' (duration: 111.330374ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:08.853164Z","caller":"traceutil/trace.go:171","msg":"trace[595859767] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"125.990046ms","start":"2026-02-11T18:19:08.727165Z","end":"2026-02-11T18:19:08.853155Z","steps":["trace[595859767] 'process raft request' (duration: 124.442307ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:08.861655Z","caller":"traceutil/trace.go:171","msg":"trace[1300166930] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"120.460101ms","start":"2026-02-11T18:19:08.741189Z","end":"2026-02-11T18:19:08.861649Z","steps":["trace[1300166930] 'process raft request' (duration: 120.367528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:14.117137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:19:14.617966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:19:15.118335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:19:15.447173Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.980986392s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T18:19:15.447985Z","caller":"traceutil/trace.go:171","msg":"trace[1619942776] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.98186387s","start":"2026-02-11T18:19:13.466093Z","end":"2026-02-11T18:19:15.447957Z","steps":["trace[1619942776] 'process raft request' (duration: 1.981437706s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:15.448283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:13.466067Z","time spent":"1.982099137s","remote":"127.0.0.1:40976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:19:15.748027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.615121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:19:15.74815Z","caller":"traceutil/trace.go:171","msg":"trace[1344254447] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:682; }","duration":"2.131900466s","start":"2026-02-11T18:19:13.616214Z","end":"2026-02-11T18:19:15.748114Z","steps":["trace[1344254447] 'read index received' (duration: 1.831325897s)","trace[1344254447] 'applied index is now lower than readState.Index' (duration: 300.573399ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:19:15.751077Z","caller":"traceutil/trace.go:171","msg":"trace[604409640] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"860.287297ms","start":"2026-02-11T18:19:14.890747Z","end":"2026-02-11T18:19:15.751035Z","steps":["trace[604409640] 'process raft request' (duration: 623.545327ms)","trace[604409640] 'compare' (duration: 230.887265ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:15.751178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:14.890723Z","time spent":"860.394079ms","remote":"127.0.0.1:41084","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-11T18:19:15.751641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135399307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T18:19:15.751679Z","caller":"traceutil/trace.go:171","msg":"trace[724713885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"2.13548619s","start":"2026-02-11T18:19:13.61618Z","end":"2026-02-11T18:19:15.751666Z","steps":["trace[724713885] 'agreement among raft nodes before linearized reading' (duration: 2.135312634s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:15.751727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:13.616148Z","time spent":"2.135571202s","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T18:19:15.752346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.120162275s","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-11T18:19:15.752405Z","caller":"traceutil/trace.go:171","msg":"trace[998362035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"2.120258968s","start":"2026-02-11T18:19:13.632136Z","end":"2026-02-11T18:19:15.752395Z","steps":["trace[998362035] 'agreement among raft nodes before linearized reading' (duration: 2.120110723s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:15.752435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:13.632123Z","time spent":"2.120304819s","remote":"127.0.0.1:41084","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-11T18:19:15.753769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.348424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4pwmg\" ","response":"range_response_count:1 size:18250"} {"level":"warn","ts":"2026-02-11T18:19:15.75392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.110177789s","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-11T18:19:15.753952Z","caller":"traceutil/trace.go:171","msg":"trace[430561229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"2.11020753s","start":"2026-02-11T18:19:13.643732Z","end":"2026-02-11T18:19:15.753939Z","steps":["trace[430561229] 'agreement among raft nodes before linearized reading' (duration: 2.110158308s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:15.753979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:13.643726Z","time spent":"2.110247311s","remote":"127.0.0.1:41084","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":"info","ts":"2026-02-11T18:19:15.754051Z","caller":"traceutil/trace.go:171","msg":"trace[677089041] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4pwmg; range_end:; response_count:1; response_revision:663; }","duration":"295.57056ms","start":"2026-02-11T18:19:15.458358Z","end":"2026-02-11T18:19:15.753928Z","steps":["trace[677089041] 'agreement among raft nodes before linearized reading' (duration: 295.006502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:15.753788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.110249771s","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-11T18:19:15.756126Z","caller":"traceutil/trace.go:171","msg":"trace[1009318531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"2.112620527s","start":"2026-02-11T18:19:13.643499Z","end":"2026-02-11T18:19:15.75612Z","steps":["trace[1009318531] 'agreement among raft nodes before linearized reading' (duration: 2.109966063s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:15.756148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:13.643492Z","time spent":"2.112649807s","remote":"127.0.0.1:41084","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-11T18:19:15.75384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.980765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:15.756347Z","caller":"traceutil/trace.go:171","msg":"trace[1365850202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"702.527295ms","start":"2026-02-11T18:19:15.053804Z","end":"2026-02-11T18:19:15.756331Z","steps":["trace[1365850202] 'agreement among raft nodes before linearized reading' (duration: 700.024616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:15.75637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:15.053774Z","time spent":"702.590557ms","remote":"127.0.0.1:40796","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-11T18:19:15.753882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.78471ms","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-11T18:19:15.757721Z","caller":"traceutil/trace.go:171","msg":"trace[551768650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:663; }","duration":"783.620561ms","start":"2026-02-11T18:19:14.974093Z","end":"2026-02-11T18:19:15.757713Z","steps":["trace[551768650] 'agreement among raft nodes before linearized reading' (duration: 779.755489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:15.757744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:14.974027Z","time spent":"783.710434ms","remote":"127.0.0.1:41084","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-11T18:19:26.311643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:19:26.812246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:19:27.31315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:19:27.813543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:19:28.314116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075959819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T18:19:28.647579Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.865993469s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T18:19:28.647887Z","caller":"traceutil/trace.go:171","msg":"trace[152099297] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"2.866368232s","start":"2026-02-11T18:19:25.781503Z","end":"2026-02-11T18:19:28.647872Z","steps":["trace[152099297] 'process raft request' (duration: 2.866255338s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:28.647975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:25.781486Z","time spent":"2.866429053s","remote":"127.0.0.1:40960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:19:29.050357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000057865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T18:19:29.05045Z","caller":"traceutil/trace.go:171","msg":"trace[1060672995] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000199169s","start":"2026-02-11T18:19:27.05023Z","end":"2026-02-11T18:19:29.05043Z","steps":["trace[1060672995] 'agreement among raft nodes before linearized reading' (duration: 2.000092326s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.050489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.050209Z","time spent":"2.000272122s","remote":"127.0.0.1:40796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 18:19:29 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T18:19:29.604544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.752785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:19:29.604933Z","caller":"traceutil/trace.go:171","msg":"trace[1588642025] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"3.560568174s","start":"2026-02-11T18:19:26.044329Z","end":"2026-02-11T18:19:29.604897Z","steps":["trace[1588642025] 'process raft request' (duration: 2.758400696s)","trace[1588642025] 'compare' (duration: 801.490357ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:29.605047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:26.044309Z","time spent":"3.560676427s","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T18:19:29.684768Z","caller":"traceutil/trace.go:171","msg":"trace[1354359814] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:755; }","duration":"3.873293607s","start":"2026-02-11T18:19:25.811429Z","end":"2026-02-11T18:19:29.684722Z","steps":["trace[1354359814] 'read index received' (duration: 2.836853338s)","trace[1354359814] 'applied index is now lower than readState.Index' (duration: 1.036439309s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:29.684867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:28.575392Z","time spent":"1.10947217s","remote":"127.0.0.1:40872","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T18:19:29.685196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.869137976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T18:19:29.685233Z","caller":"traceutil/trace.go:171","msg":"trace[2030825414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"3.869193797s","start":"2026-02-11T18:19:25.81603Z","end":"2026-02-11T18:19:29.685224Z","steps":["trace[2030825414] 'agreement among raft nodes before linearized reading' (duration: 3.869055813s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.685258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:25.816025Z","time spent":"3.869228168s","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T18:19:29.685387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.873961088s","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-11T18:19:29.68541Z","caller":"traceutil/trace.go:171","msg":"trace[1639472665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"3.87400892s","start":"2026-02-11T18:19:25.811395Z","end":"2026-02-11T18:19:29.685404Z","steps":["trace[1639472665] 'agreement among raft nodes before linearized reading' (duration: 3.873952598s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.685427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:25.811379Z","time spent":"3.87404423s","remote":"127.0.0.1:41084","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-11T18:19:29.685511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.870615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.685532Z","caller":"traceutil/trace.go:171","msg":"trace[807632356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"634.913507ms","start":"2026-02-11T18:19:29.050612Z","end":"2026-02-11T18:19:29.685526Z","steps":["trace[807632356] 'agreement among raft nodes before linearized reading' (duration: 634.883016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.685548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:29.050598Z","time spent":"634.946487ms","remote":"127.0.0.1:40804","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-11T18:19:29.686013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.412624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.686042Z","caller":"traceutil/trace.go:171","msg":"trace[34294318] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:733; }","duration":"929.479486ms","start":"2026-02-11T18:19:28.756555Z","end":"2026-02-11T18:19:29.686035Z","steps":["trace[34294318] 'agreement among raft nodes before linearized reading' (duration: 929.431334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.686062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:28.756533Z","time spent":"929.524126ms","remote":"127.0.0.1:41022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:29.686184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602770576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.686212Z","caller":"traceutil/trace.go:171","msg":"trace[1991401971] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:733; }","duration":"1.602837698s","start":"2026-02-11T18:19:28.083368Z","end":"2026-02-11T18:19:29.686205Z","steps":["trace[1991401971] 'agreement among raft nodes before linearized reading' (duration: 1.602794357s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.686242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:28.083349Z","time spent":"1.6028848s","remote":"127.0.0.1:41366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:29.686382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888567597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.686417Z","caller":"traceutil/trace.go:171","msg":"trace[1301181985] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:733; }","duration":"1.88860889s","start":"2026-02-11T18:19:27.797799Z","end":"2026-02-11T18:19:29.686408Z","steps":["trace[1301181985] 'agreement among raft nodes before linearized reading' (duration: 1.888556868s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.68645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.797797Z","time spent":"1.888644801s","remote":"127.0.0.1:36698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:29.686592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888791995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.686617Z","caller":"traceutil/trace.go:171","msg":"trace[1837458103] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:733; }","duration":"1.888824446s","start":"2026-02-11T18:19:27.797787Z","end":"2026-02-11T18:19:29.686612Z","steps":["trace[1837458103] 'agreement among raft nodes before linearized reading' (duration: 1.888787745s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.686635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.797785Z","time spent":"1.888845617s","remote":"127.0.0.1:36698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T18:19:29.686709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.8889372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.686726Z","caller":"traceutil/trace.go:171","msg":"trace[1178371844] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:733; }","duration":"1.88897113s","start":"2026-02-11T18:19:27.797749Z","end":"2026-02-11T18:19:29.68672Z","steps":["trace[1178371844] 'agreement among raft nodes before linearized reading' (duration: 1.8889475s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.686746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.797736Z","time spent":"1.889004842s","remote":"127.0.0.1:36698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-11T18:19:29.687041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919494936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.687063Z","caller":"traceutil/trace.go:171","msg":"trace[1125411277] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:733; }","duration":"1.919524857s","start":"2026-02-11T18:19:27.767532Z","end":"2026-02-11T18:19:29.687057Z","steps":["trace[1125411277] 'agreement among raft nodes before linearized reading' (duration: 1.919486256s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.687082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.767513Z","time spent":"1.919564299s","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T18:19:29.687184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919668582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.687205Z","caller":"traceutil/trace.go:171","msg":"trace[1759831663] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:733; }","duration":"1.919697643s","start":"2026-02-11T18:19:27.767502Z","end":"2026-02-11T18:19:29.687199Z","steps":["trace[1759831663] 'agreement among raft nodes before linearized reading' (duration: 1.919667212s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.687238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.767499Z","time spent":"1.919732144s","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:29.687319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919824607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.687335Z","caller":"traceutil/trace.go:171","msg":"trace[190163326] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:733; }","duration":"1.919850948s","start":"2026-02-11T18:19:27.767479Z","end":"2026-02-11T18:19:29.68733Z","steps":["trace[190163326] 'agreement among raft nodes before linearized reading' (duration: 1.919824177s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.687353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.767472Z","time spent":"1.919877258s","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-11T18:19:29.687425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.932578851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.687442Z","caller":"traceutil/trace.go:171","msg":"trace[223207769] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:733; }","duration":"1.932595781s","start":"2026-02-11T18:19:27.754841Z","end":"2026-02-11T18:19:29.687437Z","steps":["trace[223207769] 'agreement among raft nodes before linearized reading' (duration: 1.93257072s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.687458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.754794Z","time spent":"1.932660033s","remote":"127.0.0.1:36688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-11T18:19:29.687541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.933499079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.68756Z","caller":"traceutil/trace.go:171","msg":"trace[445145335] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:733; }","duration":"1.93352444s","start":"2026-02-11T18:19:27.754029Z","end":"2026-02-11T18:19:29.687554Z","steps":["trace[445145335] 'agreement among raft nodes before linearized reading' (duration: 1.933497449s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.687577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.754026Z","time spent":"1.93354645s","remote":"127.0.0.1:36688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:29.687646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.933622784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.687661Z","caller":"traceutil/trace.go:171","msg":"trace[1544537701] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:733; }","duration":"1.933660234s","start":"2026-02-11T18:19:27.753996Z","end":"2026-02-11T18:19:29.687657Z","steps":["trace[1544537701] 'agreement among raft nodes before linearized reading' (duration: 1.933636243s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.687679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.753982Z","time spent":"1.933691536s","remote":"127.0.0.1:36688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T18:19:29.687786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.335439705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T18:19:29.687801Z","caller":"traceutil/trace.go:171","msg":"trace[539789733] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:733; }","duration":"2.335473916s","start":"2026-02-11T18:19:27.352323Z","end":"2026-02-11T18:19:29.687797Z","steps":["trace[539789733] 'agreement among raft nodes before linearized reading' (duration: 2.335411735s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.687818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.35231Z","time spent":"2.335503517s","remote":"127.0.0.1:40872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":96,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:29.688537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.383463115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.688567Z","caller":"traceutil/trace.go:171","msg":"trace[749805742] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:733; }","duration":"2.383497746s","start":"2026-02-11T18:19:27.305063Z","end":"2026-02-11T18:19:29.688561Z","steps":["trace[749805742] 'agreement among raft nodes before linearized reading' (duration: 2.383446824s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.688588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.305049Z","time spent":"2.383532976s","remote":"127.0.0.1:36680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:29.688709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.383853817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.688732Z","caller":"traceutil/trace.go:171","msg":"trace[512585871] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:733; }","duration":"2.383898938s","start":"2026-02-11T18:19:27.304828Z","end":"2026-02-11T18:19:29.688727Z","steps":["trace[512585871] 'agreement among raft nodes before linearized reading' (duration: 2.383863407s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.688751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.304812Z","time spent":"2.383932909s","remote":"127.0.0.1:36680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-11T18:19:29.688787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.383994312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.688807Z","caller":"traceutil/trace.go:171","msg":"trace[1783271349] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:733; }","duration":"2.384033842s","start":"2026-02-11T18:19:27.304767Z","end":"2026-02-11T18:19:29.688801Z","steps":["trace[1783271349] 'agreement among raft nodes before linearized reading' (duration: 2.384007491s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.688824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:27.304755Z","time spent":"2.384064204s","remote":"127.0.0.1:36680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T18:19:29.688904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.759941995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.688926Z","caller":"traceutil/trace.go:171","msg":"trace[1815711650] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:733; }","duration":"2.759992787s","start":"2026-02-11T18:19:26.928928Z","end":"2026-02-11T18:19:29.688921Z","steps":["trace[1815711650] 'agreement among raft nodes before linearized reading' (duration: 2.759960296s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.688943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:26.928907Z","time spent":"2.760031378s","remote":"127.0.0.1:40942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:29.689039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.836381699s","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-11T18:19:29.689071Z","caller":"traceutil/trace.go:171","msg":"trace[2139171223] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:733; }","duration":"3.836444291s","start":"2026-02-11T18:19:25.852622Z","end":"2026-02-11T18:19:29.689067Z","steps":["trace[2139171223] 'agreement among raft nodes before linearized reading' (duration: 3.836397269s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.689093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:25.852603Z","time spent":"3.836484562s","remote":"127.0.0.1:40894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:29.689217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.860334997s","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-11T18:19:29.689238Z","caller":"traceutil/trace.go:171","msg":"trace[501171741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"3.860368088s","start":"2026-02-11T18:19:25.828865Z","end":"2026-02-11T18:19:29.689233Z","steps":["trace[501171741] 'agreement among raft nodes before linearized reading' (duration: 3.860307306s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.689258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:25.828859Z","time spent":"3.860395829s","remote":"127.0.0.1:41084","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-11T18:19:29.689361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.861211755s","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-11T18:19:29.689383Z","caller":"traceutil/trace.go:171","msg":"trace[70381827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"3.861242545s","start":"2026-02-11T18:19:25.828136Z","end":"2026-02-11T18:19:29.689378Z","steps":["trace[70381827] 'agreement among raft nodes before linearized reading' (duration: 3.861190154s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.689404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:25.828132Z","time spent":"3.861267366s","remote":"127.0.0.1:41084","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-11T18:19:29.68952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.86139211s","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-11T18:19:29.689543Z","caller":"traceutil/trace.go:171","msg":"trace[1343536329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"3.861432631s","start":"2026-02-11T18:19:25.828104Z","end":"2026-02-11T18:19:29.689537Z","steps":["trace[1343536329] 'agreement among raft nodes before linearized reading' (duration: 3.86137968s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:29.689563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:25.828095Z","time spent":"3.861463722s","remote":"127.0.0.1:41084","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":"info","ts":"2026-02-11T18:19:29.85756Z","caller":"traceutil/trace.go:171","msg":"trace[882908503] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"159.536207ms","start":"2026-02-11T18:19:29.698001Z","end":"2026-02-11T18:19:29.857537Z","steps":["trace[882908503] 'process raft request' (duration: 71.593095ms)","trace[882908503] 'compare' (duration: 87.86344ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:19:29.90139Z","caller":"traceutil/trace.go:171","msg":"trace[1123415540] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:758; }","duration":"193.14707ms","start":"2026-02-11T18:19:29.708228Z","end":"2026-02-11T18:19:29.901375Z","steps":["trace[1123415540] 'read index received' (duration: 61.378222ms)","trace[1123415540] 'applied index is now lower than readState.Index' (duration: 131.768248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:29.901486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.244483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:29.901507Z","caller":"traceutil/trace.go:171","msg":"trace[1257377679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"193.305045ms","start":"2026-02-11T18:19:29.708197Z","end":"2026-02-11T18:19:29.901502Z","steps":["trace[1257377679] 'agreement among raft nodes before linearized reading' (duration: 193.241243ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:29.901731Z","caller":"traceutil/trace.go:171","msg":"trace[1368455453] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"202.334091ms","start":"2026-02-11T18:19:29.69939Z","end":"2026-02-11T18:19:29.901724Z","steps":["trace[1368455453] 'process raft request' (duration: 201.785104ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:29.901819Z","caller":"traceutil/trace.go:171","msg":"trace[1192081072] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"200.72401ms","start":"2026-02-11T18:19:29.701089Z","end":"2026-02-11T18:19:29.901813Z","steps":["trace[1192081072] 'process raft request' (duration: 200.185973ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:29.901891Z","caller":"traceutil/trace.go:171","msg":"trace[1088697602] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"197.419665ms","start":"2026-02-11T18:19:29.704467Z","end":"2026-02-11T18:19:29.901886Z","steps":["trace[1088697602] 'process raft request' (duration: 196.833377ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:29.901979Z","caller":"traceutil/trace.go:171","msg":"trace[1736801031] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"197.445856ms","start":"2026-02-11T18:19:29.704528Z","end":"2026-02-11T18:19:29.901973Z","steps":["trace[1736801031] 'process raft request' (duration: 196.802396ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:29.902053Z","caller":"traceutil/trace.go:171","msg":"trace[1053743194] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"197.468018ms","start":"2026-02-11T18:19:29.70458Z","end":"2026-02-11T18:19:29.902048Z","steps":["trace[1053743194] 'process raft request' (duration: 196.771596ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:32.054375Z","caller":"traceutil/trace.go:171","msg":"trace[534949015] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"146.950149ms","start":"2026-02-11T18:19:31.907406Z","end":"2026-02-11T18:19:32.054356Z","steps":["trace[534949015] 'read index received' (duration: 146.797674ms)","trace[534949015] 'applied index is now lower than readState.Index' (duration: 151.505µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:19:32.054666Z","caller":"traceutil/trace.go:171","msg":"trace[848685706] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"191.519489ms","start":"2026-02-11T18:19:31.863135Z","end":"2026-02-11T18:19:32.054654Z","steps":["trace[848685706] 'process raft request' (duration: 191.118586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:32.055597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.248618ms","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-11T18:19:32.055645Z","caller":"traceutil/trace.go:171","msg":"trace[480551515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"147.336831ms","start":"2026-02-11T18:19:31.908296Z","end":"2026-02-11T18:19:32.055633Z","steps":["trace[480551515] 'agreement among raft nodes before linearized reading' (duration: 147.197386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:32.055954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.110294ms","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-11T18:19:32.056006Z","caller":"traceutil/trace.go:171","msg":"trace[1895123781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"147.153835ms","start":"2026-02-11T18:19:31.908827Z","end":"2026-02-11T18:19:32.055981Z","steps":["trace[1895123781] 'agreement among raft nodes before linearized reading' (duration: 147.072282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:32.056169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.774805ms","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-11T18:19:32.0562Z","caller":"traceutil/trace.go:171","msg":"trace[271434564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"147.824767ms","start":"2026-02-11T18:19:31.908368Z","end":"2026-02-11T18:19:32.056192Z","steps":["trace[271434564] 'agreement among raft nodes before linearized reading' (duration: 147.747944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:32.05751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.094318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T18:19:32.05757Z","caller":"traceutil/trace.go:171","msg":"trace[564387920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"150.1787ms","start":"2026-02-11T18:19:31.907367Z","end":"2026-02-11T18:19:32.057546Z","steps":["trace[564387920] 'agreement among raft nodes before linearized reading' (duration: 147.459564ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:32.284504Z","caller":"traceutil/trace.go:171","msg":"trace[994011981] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"221.525768ms","start":"2026-02-11T18:19:32.062963Z","end":"2026-02-11T18:19:32.284489Z","steps":["trace[994011981] 'process raft request' (duration: 221.421025ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:32.463631Z","caller":"traceutil/trace.go:171","msg":"trace[1575783744] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:766; }","duration":"364.448ms","start":"2026-02-11T18:19:32.099165Z","end":"2026-02-11T18:19:32.463613Z","steps":["trace[1575783744] 'read index received' (duration: 185.271072ms)","trace[1575783744] 'applied index is now lower than readState.Index' (duration: 179.176228ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:19:32.46385Z","caller":"traceutil/trace.go:171","msg":"trace[312654560] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"400.771579ms","start":"2026-02-11T18:19:32.063071Z","end":"2026-02-11T18:19:32.463842Z","steps":["trace[312654560] 'process raft request' (duration: 400.363026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:32.463935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:32.063065Z","time spent":"400.80518ms","remote":"127.0.0.1:41084","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-11T18:19:32.46406Z","caller":"traceutil/trace.go:171","msg":"trace[2022312733] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"400.944644ms","start":"2026-02-11T18:19:32.063109Z","end":"2026-02-11T18:19:32.464054Z","steps":["trace[2022312733] 'process raft request' (duration: 400.452359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:32.464093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:32.063098Z","time spent":"400.976386ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T18:19:32.464184Z","caller":"traceutil/trace.go:171","msg":"trace[123716528] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"399.908472ms","start":"2026-02-11T18:19:32.064268Z","end":"2026-02-11T18:19:32.464177Z","steps":["trace[123716528] 'process raft request' (duration: 399.322323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:32.464219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:32.064261Z","time spent":"399.940183ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:19:32.464325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.157463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:32.464349Z","caller":"traceutil/trace.go:171","msg":"trace[1614737512] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:745; }","duration":"365.205704ms","start":"2026-02-11T18:19:32.099135Z","end":"2026-02-11T18:19:32.46434Z","steps":["trace[1614737512] 'agreement among raft nodes before linearized reading' (duration: 365.169463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:32.464372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:32.09912Z","time spent":"365.246555ms","remote":"127.0.0.1:41356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:19:32.464514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.663176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T18:19:32.464533Z","caller":"traceutil/trace.go:171","msg":"trace[313883205] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:745; }","duration":"339.692317ms","start":"2026-02-11T18:19:32.124833Z","end":"2026-02-11T18:19:32.464526Z","steps":["trace[313883205] 'agreement among raft nodes before linearized reading' (duration: 339.618645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:32.464548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:32.124825Z","time spent":"339.719568ms","remote":"127.0.0.1:40844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"info","ts":"2026-02-11T18:19:40.7674Z","caller":"traceutil/trace.go:171","msg":"trace[1738737037] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"271.128288ms","start":"2026-02-11T18:19:40.496255Z","end":"2026-02-11T18:19:40.767383Z","steps":["trace[1738737037] 'read index received' (duration: 270.992264ms)","trace[1738737037] 'applied index is now lower than readState.Index' (duration: 135.344µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:19:40.767668Z","caller":"traceutil/trace.go:171","msg":"trace[623779541] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"271.606683ms","start":"2026-02-11T18:19:40.496052Z","end":"2026-02-11T18:19:40.767659Z","steps":["trace[623779541] 'process raft request' (duration: 271.242411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:40.767862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.323277ms","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-11T18:19:40.767894Z","caller":"traceutil/trace.go:171","msg":"trace[1603689142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:771; }","duration":"267.378309ms","start":"2026-02-11T18:19:40.500508Z","end":"2026-02-11T18:19:40.767886Z","steps":["trace[1603689142] 'agreement among raft nodes before linearized reading' (duration: 267.240124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:40.768045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.799849ms","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-11T18:19:40.768067Z","caller":"traceutil/trace.go:171","msg":"trace[1996006036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"271.83501ms","start":"2026-02-11T18:19:40.496225Z","end":"2026-02-11T18:19:40.76806Z","steps":["trace[1996006036] 'agreement among raft nodes before linearized reading' (duration: 271.772638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:42.94172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.878014ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-11T18:19:42.941916Z","caller":"traceutil/trace.go:171","msg":"trace[2093570542] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"313.862369ms","start":"2026-02-11T18:19:42.628038Z","end":"2026-02-11T18:19:42.9419Z","steps":["trace[2093570542] 'process raft request' (duration: 313.767446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:42.941999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:42.628012Z","time spent":"313.934532ms","remote":"127.0.0.1:41084","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-11T18:19:42.942201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:42.204132Z","time spent":"738.06663ms","remote":"127.0.0.1:40844","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T18:19:42.942482Z","caller":"traceutil/trace.go:171","msg":"trace[1774958627] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"600.570239ms","start":"2026-02-11T18:19:42.341899Z","end":"2026-02-11T18:19:42.94247Z","steps":["trace[1774958627] 'read index received' (duration: 290.151469ms)","trace[1774958627] 'applied index is now lower than readState.Index' (duration: 310.4173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:42.942913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.007253ms","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-11T18:19:42.942962Z","caller":"traceutil/trace.go:171","msg":"trace[396865969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"601.090197ms","start":"2026-02-11T18:19:42.341856Z","end":"2026-02-11T18:19:42.942946Z","steps":["trace[396865969] 'agreement among raft nodes before linearized reading' (duration: 600.966353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:42.94304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:42.341837Z","time spent":"601.190599ms","remote":"127.0.0.1:41084","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-11T18:19:42.943245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.047933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T18:19:42.943274Z","caller":"traceutil/trace.go:171","msg":"trace[1364703855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"166.315212ms","start":"2026-02-11T18:19:42.776949Z","end":"2026-02-11T18:19:42.943264Z","steps":["trace[1364703855] 'agreement among raft nodes before linearized reading' (duration: 166.235669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:42.943604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.668929ms","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-11T18:19:42.943651Z","caller":"traceutil/trace.go:171","msg":"trace[1755824945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"121.734831ms","start":"2026-02-11T18:19:42.821905Z","end":"2026-02-11T18:19:42.94364Z","steps":["trace[1755824945] 'agreement among raft nodes before linearized reading' (duration: 121.632227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:42.94377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.062127ms","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-11T18:19:42.943792Z","caller":"traceutil/trace.go:171","msg":"trace[1746791984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"144.084768ms","start":"2026-02-11T18:19:42.799701Z","end":"2026-02-11T18:19:42.943785Z","steps":["trace[1746791984] 'agreement among raft nodes before linearized reading' (duration: 144.019886ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:43.232815Z","caller":"traceutil/trace.go:171","msg":"trace[1363728896] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"262.4091ms","start":"2026-02-11T18:19:42.970387Z","end":"2026-02-11T18:19:43.232796Z","steps":["trace[1363728896] 'process raft request' (duration: 262.37979ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:43.233129Z","caller":"traceutil/trace.go:171","msg":"trace[1803176406] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"280.885166ms","start":"2026-02-11T18:19:42.952235Z","end":"2026-02-11T18:19:43.23312Z","steps":["trace[1803176406] 'process raft request' (duration: 280.37678ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:43.233211Z","caller":"traceutil/trace.go:171","msg":"trace[985983499] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"262.960017ms","start":"2026-02-11T18:19:42.970246Z","end":"2026-02-11T18:19:43.233206Z","steps":["trace[985983499] 'process raft request' (duration: 262.474592ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:43.233306Z","caller":"traceutil/trace.go:171","msg":"trace[138788103] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"262.958837ms","start":"2026-02-11T18:19:42.970341Z","end":"2026-02-11T18:19:43.2333Z","steps":["trace[138788103] 'process raft request' (duration: 262.40431ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:43.235254Z","caller":"traceutil/trace.go:171","msg":"trace[2137291473] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:808; }","duration":"205.203942ms","start":"2026-02-11T18:19:43.030016Z","end":"2026-02-11T18:19:43.23522Z","steps":["trace[2137291473] 'read index received' (duration: 205.197282ms)","trace[2137291473] 'applied index is now lower than readState.Index' (duration: 5.33µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:43.235764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.734519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-11T18:19:43.236306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.91112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:43.236345Z","caller":"traceutil/trace.go:171","msg":"trace[1742813463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"178.956141ms","start":"2026-02-11T18:19:43.057378Z","end":"2026-02-11T18:19:43.236334Z","steps":["trace[1742813463] 'agreement among raft nodes before linearized reading' (duration: 178.897449ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:43.236614Z","caller":"traceutil/trace.go:171","msg":"trace[1728425933] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:780; }","duration":"206.319608ms","start":"2026-02-11T18:19:43.029992Z","end":"2026-02-11T18:19:43.236312Z","steps":["trace[1728425933] 'agreement among raft nodes before linearized reading' (duration: 205.728569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:55.010856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338660075960117,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T18:19:55.051351Z","caller":"traceutil/trace.go:171","msg":"trace[340893552] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:891; }","duration":"540.884942ms","start":"2026-02-11T18:19:54.510449Z","end":"2026-02-11T18:19:55.051334Z","steps":["trace[340893552] 'read index received' (duration: 540.746597ms)","trace[340893552] 'applied index is now lower than readState.Index' (duration: 137.825µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:55.051541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.069387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T18:19:55.051564Z","caller":"traceutil/trace.go:171","msg":"trace[1371655636] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:861; }","duration":"541.13552ms","start":"2026-02-11T18:19:54.510421Z","end":"2026-02-11T18:19:55.051556Z","steps":["trace[1371655636] 'agreement among raft nodes before linearized reading' (duration: 540.983665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:55.051587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:54.510406Z","time spent":"541.1747ms","remote":"127.0.0.1:41368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":105,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-11T18:19:55.051815Z","caller":"traceutil/trace.go:171","msg":"trace[279759014] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"743.922986ms","start":"2026-02-11T18:19:54.307884Z","end":"2026-02-11T18:19:55.051807Z","steps":["trace[279759014] 'process raft request' (duration: 743.358378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:55.051869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:54.307836Z","time spent":"743.996747ms","remote":"127.0.0.1:41084","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-11T18:19:55.497674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.102863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:19:55.49774Z","caller":"traceutil/trace.go:171","msg":"trace[1168761838] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"171.134233ms","start":"2026-02-11T18:19:55.326595Z","end":"2026-02-11T18:19:55.497729Z","steps":["trace[1168761838] 'process raft request' (duration: 66.919966ms)","trace[1168761838] 'compare' (duration: 104.029301ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:57.004919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30167721s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:57.005037Z","caller":"traceutil/trace.go:171","msg":"trace[308383404] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:863; }","duration":"1.301806334s","start":"2026-02-11T18:19:55.703212Z","end":"2026-02-11T18:19:57.005018Z","steps":["trace[308383404] 'range keys from in-memory index tree' (duration: 1.30166145s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:57.005109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241045562s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:19:57.005208Z","caller":"traceutil/trace.go:171","msg":"trace[1627012934] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:894; }","duration":"955.896031ms","start":"2026-02-11T18:19:56.049302Z","end":"2026-02-11T18:19:57.005198Z","steps":["trace[1627012934] 'read index received' (duration: 23.61µs)","trace[1627012934] 'applied index is now lower than readState.Index' (duration: 955.871881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:57.005268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.962243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T18:19:57.005281Z","caller":"traceutil/trace.go:171","msg":"trace[747393068] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:865; }","duration":"955.994884ms","start":"2026-02-11T18:19:56.049281Z","end":"2026-02-11T18:19:57.005276Z","steps":["trace[747393068] 'agreement among raft nodes before linearized reading' (duration: 955.944243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:57.005299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:56.049268Z","time spent":"956.026265ms","remote":"127.0.0.1:41068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-11T18:19:57.00541Z","caller":"traceutil/trace.go:171","msg":"trace[1587455942] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"1.676698334s","start":"2026-02-11T18:19:55.328697Z","end":"2026-02-11T18:19:57.005395Z","steps":["trace[1587455942] 'process raft request' (duration: 1.676464857s)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:57.005466Z","caller":"traceutil/trace.go:171","msg":"trace[935331701] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"1.677614453s","start":"2026-02-11T18:19:55.327843Z","end":"2026-02-11T18:19:57.005458Z","steps":["trace[935331701] 'process raft request' (duration: 436.169818ms)","trace[935331701] 'compare' (duration: 1.24098471s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:57.005498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:55.327837Z","time spent":"1.677645544s","remote":"127.0.0.1:41084","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-11T18:19:57.005503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:55.32869Z","time spent":"1.676751865s","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:19:57.640057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.892322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:19:57.640151Z","caller":"traceutil/trace.go:171","msg":"trace[806429391] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:896; }","duration":"313.391935ms","start":"2026-02-11T18:19:57.326742Z","end":"2026-02-11T18:19:57.640134Z","steps":["trace[806429391] 'read index received' (duration: 29.361µs)","trace[806429391] 'applied index is now lower than readState.Index' (duration: 313.361064ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:19:57.640185Z","caller":"traceutil/trace.go:171","msg":"trace[1523548265] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"576.185358ms","start":"2026-02-11T18:19:57.063974Z","end":"2026-02-11T18:19:57.64016Z","steps":["trace[1523548265] 'process raft request' (duration: 90.09382ms)","trace[1523548265] 'compare' (duration: 485.697775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:57.640294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:57.063966Z","time spent":"576.266831ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:19:57.64034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.592771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T18:19:57.640383Z","caller":"traceutil/trace.go:171","msg":"trace[1726444466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:866; }","duration":"313.648673ms","start":"2026-02-11T18:19:57.326712Z","end":"2026-02-11T18:19:57.64036Z","steps":["trace[1726444466] 'agreement among raft nodes before linearized reading' (duration: 313.500278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:57.640417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:57.326697Z","time spent":"313.711944ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T18:19:57.640542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.921885ms","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-11T18:19:57.640566Z","caller":"traceutil/trace.go:171","msg":"trace[1109262950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:866; }","duration":"138.981487ms","start":"2026-02-11T18:19:57.501577Z","end":"2026-02-11T18:19:57.640559Z","steps":["trace[1109262950] 'agreement among raft nodes before linearized reading' (duration: 138.895394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:57.640689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.629989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T18:19:57.64073Z","caller":"traceutil/trace.go:171","msg":"trace[429879727] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:866; }","duration":"134.692431ms","start":"2026-02-11T18:19:57.506027Z","end":"2026-02-11T18:19:57.640719Z","steps":["trace[429879727] 'agreement among raft nodes before linearized reading' (duration: 134.621139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:58.115347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.644771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:19:58.115557Z","caller":"traceutil/trace.go:171","msg":"trace[850419063] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"461.901533ms","start":"2026-02-11T18:19:57.653644Z","end":"2026-02-11T18:19:58.115545Z","steps":["trace[850419063] 'process raft request' (duration: 301.007813ms)","trace[850419063] 'compare' (duration: 160.507547ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:58.115594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:57.653627Z","time spent":"461.949915ms","remote":"127.0.0.1:41084","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-11T18:19:58.115954Z","caller":"traceutil/trace.go:171","msg":"trace[775003267] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"462.173201ms","start":"2026-02-11T18:19:57.653776Z","end":"2026-02-11T18:19:58.115949Z","steps":["trace[775003267] 'process raft request' (duration: 461.629594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:58.115989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:57.653769Z","time spent":"462.198992ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:19:58.833255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.807059ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T18:19:58.833321Z","caller":"traceutil/trace.go:171","msg":"trace[804034170] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:897; }","duration":"812.568407ms","start":"2026-02-11T18:19:58.020741Z","end":"2026-02-11T18:19:58.833309Z","steps":["trace[804034170] 'read index received' (duration: 94.478069ms)","trace[804034170] 'applied index is now lower than readState.Index' (duration: 718.089398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:58.833369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.621719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T18:19:58.833381Z","caller":"traceutil/trace.go:171","msg":"trace[1850358686] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:868; }","duration":"812.641319ms","start":"2026-02-11T18:19:58.020735Z","end":"2026-02-11T18:19:58.833377Z","steps":["trace[1850358686] 'agreement among raft nodes before linearized reading' (duration: 812.599918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:58.8334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:58.020692Z","time spent":"812.703881ms","remote":"127.0.0.1:40976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-02-11T18:19:59.207104Z","caller":"traceutil/trace.go:171","msg":"trace[729055931] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"195.0408ms","start":"2026-02-11T18:19:59.012037Z","end":"2026-02-11T18:19:59.207077Z","steps":["trace[729055931] 'process raft request' (duration: 194.872785ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:19:59.60249Z","caller":"traceutil/trace.go:171","msg":"trace[400757481] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:900; }","duration":"551.880189ms","start":"2026-02-11T18:19:59.050596Z","end":"2026-02-11T18:19:59.602477Z","steps":["trace[400757481] 'read index received' (duration: 156.316365ms)","trace[400757481] 'applied index is now lower than readState.Index' (duration: 395.563154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:19:59.602604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.015693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:19:59.602621Z","caller":"traceutil/trace.go:171","msg":"trace[669549872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"552.080275ms","start":"2026-02-11T18:19:59.050535Z","end":"2026-02-11T18:19:59.602616Z","steps":["trace[669549872] 'agreement among raft nodes before linearized reading' (duration: 552.011753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:59.60264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:59.050493Z","time spent":"552.142638ms","remote":"127.0.0.1:40796","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-11T18:19:59.602794Z","caller":"traceutil/trace.go:171","msg":"trace[291832630] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"588.688474ms","start":"2026-02-11T18:19:59.0141Z","end":"2026-02-11T18:19:59.602788Z","steps":["trace[291832630] 'process raft request' (duration: 588.26241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:19:59.602841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:19:59.014072Z","time spent":"588.737345ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T18:20:00.498941Z","caller":"traceutil/trace.go:171","msg":"trace[1148828739] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"377.049088ms","start":"2026-02-11T18:20:00.12187Z","end":"2026-02-11T18:20:00.498919Z","steps":["trace[1148828739] 'process raft request' (duration: 377.004636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:00.499075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:00.121858Z","time spent":"377.147512ms","remote":"127.0.0.1:41084","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-11T18:20:00.499767Z","caller":"traceutil/trace.go:171","msg":"trace[1058832822] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"377.866665ms","start":"2026-02-11T18:20:00.121867Z","end":"2026-02-11T18:20:00.499733Z","steps":["trace[1058832822] 'process raft request' (duration: 376.890044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:00.499882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:00.121855Z","time spent":"377.963127ms","remote":"127.0.0.1:41084","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-11T18:20:00.810415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.344891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:20:00.810474Z","caller":"traceutil/trace.go:171","msg":"trace[1718663522] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"491.381145ms","start":"2026-02-11T18:20:00.319083Z","end":"2026-02-11T18:20:00.810464Z","steps":["trace[1718663522] 'read index received' (duration: 180.630754ms)","trace[1718663522] 'applied index is now lower than readState.Index' (duration: 310.749771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:20:00.810544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.468168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T18:20:00.810559Z","caller":"traceutil/trace.go:171","msg":"trace[1367531302] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:874; }","duration":"491.51957ms","start":"2026-02-11T18:20:00.319035Z","end":"2026-02-11T18:20:00.810554Z","steps":["trace[1367531302] 'agreement among raft nodes before linearized reading' (duration: 491.454188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:00.810581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:00.31901Z","time spent":"491.56667ms","remote":"127.0.0.1:41278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-11T18:20:00.810732Z","caller":"traceutil/trace.go:171","msg":"trace[2141632335] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"543.267006ms","start":"2026-02-11T18:20:00.267429Z","end":"2026-02-11T18:20:00.810696Z","steps":["trace[2141632335] 'process raft request' (duration: 297.593985ms)","trace[2141632335] 'compare' (duration: 245.160175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:20:00.810823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:00.267407Z","time spent":"543.36523ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T18:20:01.491457Z","caller":"traceutil/trace.go:171","msg":"trace[813029339] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"270.313141ms","start":"2026-02-11T18:20:01.221121Z","end":"2026-02-11T18:20:01.491434Z","steps":["trace[813029339] 'process raft request' (duration: 270.179357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:01.872561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.891362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T18:20:01.872608Z","caller":"traceutil/trace.go:171","msg":"trace[720169083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"265.974655ms","start":"2026-02-11T18:20:01.606622Z","end":"2026-02-11T18:20:01.872596Z","steps":["trace[720169083] 'range keys from in-memory index tree' (duration: 265.761078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:01.872732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.605385ms","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-11T18:20:01.872744Z","caller":"traceutil/trace.go:171","msg":"trace[1324167603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:875; }","duration":"193.650946ms","start":"2026-02-11T18:20:01.679089Z","end":"2026-02-11T18:20:01.87274Z","steps":["trace[1324167603] 'range keys from in-memory index tree' (duration: 193.542743ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:02.022992Z","caller":"traceutil/trace.go:171","msg":"trace[2079310780] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"143.606033ms","start":"2026-02-11T18:20:01.87937Z","end":"2026-02-11T18:20:02.022976Z","steps":["trace[2079310780] 'process raft request' (duration: 143.51611ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:02.064836Z","caller":"traceutil/trace.go:171","msg":"trace[1405604792] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"184.323881ms","start":"2026-02-11T18:20:01.880494Z","end":"2026-02-11T18:20:02.064818Z","steps":["trace[1405604792] 'process raft request' (duration: 184.196077ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:09.41462Z","caller":"traceutil/trace.go:171","msg":"trace[741412356] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"206.158592ms","start":"2026-02-11T18:20:09.208444Z","end":"2026-02-11T18:20:09.414603Z","steps":["trace[741412356] 'read index received' (duration: 198.773278ms)","trace[741412356] 'applied index is now lower than readState.Index' (duration: 7.384494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:20:09.414706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.249484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:20:09.414722Z","caller":"traceutil/trace.go:171","msg":"trace[1491745173] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:896; }","duration":"206.304336ms","start":"2026-02-11T18:20:09.208412Z","end":"2026-02-11T18:20:09.414717Z","steps":["trace[1491745173] 'agreement among raft nodes before linearized reading' (duration: 206.244834ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:09.414845Z","caller":"traceutil/trace.go:171","msg":"trace[1752505394] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"848.253045ms","start":"2026-02-11T18:20:08.566585Z","end":"2026-02-11T18:20:09.414838Z","steps":["trace[1752505394] 'process raft request' (duration: 840.687776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:09.414888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:08.566571Z","time spent":"848.285377ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:20:10.084585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.377012ms","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-11T18:20:10.084637Z","caller":"traceutil/trace.go:171","msg":"trace[1216671766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"563.458965ms","start":"2026-02-11T18:20:09.521165Z","end":"2026-02-11T18:20:10.084624Z","steps":["trace[1216671766] 'range keys from in-memory index tree' (duration: 563.267569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:10.08466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:09.521149Z","time spent":"563.506066ms","remote":"127.0.0.1:41084","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-11T18:20:10.084732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.141286ms","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-11T18:20:10.084774Z","caller":"traceutil/trace.go:171","msg":"trace[169598695] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:896; }","duration":"417.210669ms","start":"2026-02-11T18:20:09.667552Z","end":"2026-02-11T18:20:10.084763Z","steps":["trace[169598695] 'count revisions from in-memory index tree' (duration: 417.102565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:10.084794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:09.667543Z","time spent":"417.24573ms","remote":"127.0.0.1:40846","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-11T18:20:11.177448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.192577ms","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-11T18:20:11.177496Z","caller":"traceutil/trace.go:171","msg":"trace[422758397] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:900; }","duration":"276.28251ms","start":"2026-02-11T18:20:10.901202Z","end":"2026-02-11T18:20:11.177484Z","steps":["trace[422758397] 'count revisions from in-memory index tree' (duration: 276.135086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:11.177725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.370331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:20:11.177757Z","caller":"traceutil/trace.go:171","msg":"trace[1691751626] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"200.637717ms","start":"2026-02-11T18:20:10.977111Z","end":"2026-02-11T18:20:11.177749Z","steps":["trace[1691751626] 'read index received' (duration: 23.180273ms)","trace[1691751626] 'applied index is now lower than readState.Index' (duration: 177.456994ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:20:11.177799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.687229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T18:20:11.17781Z","caller":"traceutil/trace.go:171","msg":"trace[1807151326] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:901; }","duration":"200.7232ms","start":"2026-02-11T18:20:10.977083Z","end":"2026-02-11T18:20:11.177806Z","steps":["trace[1807151326] 'agreement among raft nodes before linearized reading' (duration: 200.684839ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:11.177886Z","caller":"traceutil/trace.go:171","msg":"trace[1971945477] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"256.35003ms","start":"2026-02-11T18:20:10.92153Z","end":"2026-02-11T18:20:11.17788Z","steps":["trace[1971945477] 'process raft request' (duration: 78.788592ms)","trace[1971945477] 'compare' (duration: 177.160485ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:20:11.9051Z","caller":"traceutil/trace.go:171","msg":"trace[444014691] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"238.140675ms","start":"2026-02-11T18:20:11.666938Z","end":"2026-02-11T18:20:11.905079Z","steps":["trace[444014691] 'process raft request' (duration: 238.016151ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:12.476058Z","caller":"traceutil/trace.go:171","msg":"trace[753528709] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"237.774613ms","start":"2026-02-11T18:20:12.238266Z","end":"2026-02-11T18:20:12.476041Z","steps":["trace[753528709] 'process raft request' (duration: 237.666919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:12.784869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.703243ms","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-11T18:20:12.784927Z","caller":"traceutil/trace.go:171","msg":"trace[977119968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:906; }","duration":"141.791065ms","start":"2026-02-11T18:20:12.643124Z","end":"2026-02-11T18:20:12.784915Z","steps":["trace[977119968] 'range keys from in-memory index tree' (duration: 141.577189ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:12.903988Z","caller":"traceutil/trace.go:171","msg":"trace[416019812] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"112.921382ms","start":"2026-02-11T18:20:12.79105Z","end":"2026-02-11T18:20:12.903972Z","steps":["trace[416019812] 'process raft request' (duration: 112.802608ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:14.134719Z","caller":"traceutil/trace.go:171","msg":"trace[803578565] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"223.54471ms","start":"2026-02-11T18:20:13.911161Z","end":"2026-02-11T18:20:14.134705Z","steps":["trace[803578565] 'process raft request' (duration: 223.340283ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:14.560693Z","caller":"traceutil/trace.go:171","msg":"trace[1226859171] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"410.018025ms","start":"2026-02-11T18:20:14.150654Z","end":"2026-02-11T18:20:14.560672Z","steps":["trace[1226859171] 'read index received' (duration: 406.478183ms)","trace[1226859171] 'applied index is now lower than readState.Index' (duration: 3.538902ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:20:14.560908Z","caller":"traceutil/trace.go:171","msg":"trace[823115890] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"415.023493ms","start":"2026-02-11T18:20:14.145877Z","end":"2026-02-11T18:20:14.5609Z","steps":["trace[823115890] 'process raft request' (duration: 411.301075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:14.560981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:14.145867Z","time spent":"415.059665ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:20:14.561145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.49019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T18:20:14.561165Z","caller":"traceutil/trace.go:171","msg":"trace[2069276164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"410.522551ms","start":"2026-02-11T18:20:14.150636Z","end":"2026-02-11T18:20:14.561159Z","steps":["trace[2069276164] 'agreement among raft nodes before linearized reading' (duration: 410.445289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:14.561183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:14.150629Z","time spent":"410.549882ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T18:20:14.882865Z","caller":"traceutil/trace.go:171","msg":"trace[19610481] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"317.782204ms","start":"2026-02-11T18:20:14.565043Z","end":"2026-02-11T18:20:14.882825Z","steps":["trace[19610481] 'process raft request' (duration: 231.096759ms)","trace[19610481] 'compare' (duration: 86.53147ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:20:14.883012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:14.565027Z","time spent":"317.906478ms","remote":"127.0.0.1:41084","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-11T18:20:14.992497Z","caller":"traceutil/trace.go:171","msg":"trace[1294185619] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"426.57619ms","start":"2026-02-11T18:20:14.565905Z","end":"2026-02-11T18:20:14.992481Z","steps":["trace[1294185619] 'process raft request' (duration: 426.033513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:14.992591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:14.565891Z","time spent":"426.646622ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T18:20:17.053032Z","caller":"traceutil/trace.go:171","msg":"trace[2073554576] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"163.24756ms","start":"2026-02-11T18:20:16.889765Z","end":"2026-02-11T18:20:17.053012Z","steps":["trace[2073554576] 'process raft request' (duration: 163.092945ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:17.268442Z","caller":"traceutil/trace.go:171","msg":"trace[1410869141] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"206.875722ms","start":"2026-02-11T18:20:17.061541Z","end":"2026-02-11T18:20:17.268417Z","steps":["trace[1410869141] 'process raft request' (duration: 144.555429ms)","trace[1410869141] 'compare' (duration: 62.235011ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:20:17.562835Z","caller":"traceutil/trace.go:171","msg":"trace[2092548166] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"499.139628ms","start":"2026-02-11T18:20:17.063653Z","end":"2026-02-11T18:20:17.562793Z","steps":["trace[2092548166] 'process raft request' (duration: 499.005034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:17.562975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:17.063647Z","time spent":"499.265452ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:20:18.524347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.24543ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T18:20:18.524424Z","caller":"traceutil/trace.go:171","msg":"trace[643792048] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"786.005263ms","start":"2026-02-11T18:20:17.738405Z","end":"2026-02-11T18:20:18.52441Z","steps":["trace[643792048] 'read index received' (duration: 23.645439ms)","trace[643792048] 'applied index is now lower than readState.Index' (duration: 762.358704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:20:18.524688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.286503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T18:20:18.524709Z","caller":"traceutil/trace.go:171","msg":"trace[302641909] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:918; }","duration":"786.315293ms","start":"2026-02-11T18:20:17.738387Z","end":"2026-02-11T18:20:18.524703Z","steps":["trace[302641909] 'agreement among raft nodes before linearized reading' (duration: 786.259952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:18.524731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:17.738334Z","time spent":"786.390905ms","remote":"127.0.0.1:40882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:20:18.524857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.029536ms","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-11T18:20:18.524926Z","caller":"traceutil/trace.go:171","msg":"trace[615140817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"374.14352ms","start":"2026-02-11T18:20:18.150763Z","end":"2026-02-11T18:20:18.524907Z","steps":["trace[615140817] 'agreement among raft nodes before linearized reading' (duration: 373.924993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:18.524965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:18.150749Z","time spent":"374.207881ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T18:20:18.833909Z","caller":"traceutil/trace.go:171","msg":"trace[314116081] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"127.982553ms","start":"2026-02-11T18:20:18.705902Z","end":"2026-02-11T18:20:18.833885Z","steps":["trace[314116081] 'read index received' (duration: 81.401218ms)","trace[314116081] 'applied index is now lower than readState.Index' (duration: 46.580045ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:20:18.83404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.113648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:20:18.834074Z","caller":"traceutil/trace.go:171","msg":"trace[734786853] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:920; }","duration":"128.19164ms","start":"2026-02-11T18:20:18.705871Z","end":"2026-02-11T18:20:18.834062Z","steps":["trace[734786853] 'agreement among raft nodes before linearized reading' (duration: 128.100717ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:18.835318Z","caller":"traceutil/trace.go:171","msg":"trace[449020495] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"258.774066ms","start":"2026-02-11T18:20:18.575569Z","end":"2026-02-11T18:20:18.834343Z","steps":["trace[449020495] 'process raft request' (duration: 211.756117ms)","trace[449020495] 'compare' (duration: 45.8018ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:20:25.002069Z","caller":"traceutil/trace.go:171","msg":"trace[163443961] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"132.328371ms","start":"2026-02-11T18:20:24.869717Z","end":"2026-02-11T18:20:25.002045Z","steps":["trace[163443961] 'process raft request' (duration: 132.208257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:25.639276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.775569ms","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-11T18:20:25.639342Z","caller":"traceutil/trace.go:171","msg":"trace[632366010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"409.880972ms","start":"2026-02-11T18:20:25.229445Z","end":"2026-02-11T18:20:25.639326Z","steps":["trace[632366010] 'range keys from in-memory index tree' (duration: 409.664325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:25.639387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:25.229427Z","time spent":"409.952543ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T18:20:25.63929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.364318ms","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-11T18:20:25.639634Z","caller":"traceutil/trace.go:171","msg":"trace[1376548459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"303.7425ms","start":"2026-02-11T18:20:25.335877Z","end":"2026-02-11T18:20:25.639619Z","steps":["trace[1376548459] 'range keys from in-memory index tree' (duration: 303.236694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:25.639671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:25.335856Z","time spent":"303.804601ms","remote":"127.0.0.1:41084","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-11T18:20:25.77076Z","caller":"traceutil/trace.go:171","msg":"trace[571274664] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"125.477644ms","start":"2026-02-11T18:20:25.645266Z","end":"2026-02-11T18:20:25.770743Z","steps":["trace[571274664] 'process raft request' (duration: 125.389851ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:25.772437Z","caller":"traceutil/trace.go:171","msg":"trace[1917493785] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"126.870928ms","start":"2026-02-11T18:20:25.645549Z","end":"2026-02-11T18:20:25.77242Z","steps":["trace[1917493785] 'process raft request' (duration: 126.452335ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:25.772818Z","caller":"traceutil/trace.go:171","msg":"trace[530341127] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"125.65714ms","start":"2026-02-11T18:20:25.647148Z","end":"2026-02-11T18:20:25.772805Z","steps":["trace[530341127] 'process raft request' (duration: 125.234767ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:29.90243Z","caller":"traceutil/trace.go:171","msg":"trace[2043148849] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"109.58883ms","start":"2026-02-11T18:20:29.792806Z","end":"2026-02-11T18:20:29.902395Z","steps":["trace[2043148849] 'read index received' (duration: 109.415865ms)","trace[2043148849] 'applied index is now lower than readState.Index' (duration: 172.085µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:20:29.902703Z","caller":"traceutil/trace.go:171","msg":"trace[420237559] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"110.625484ms","start":"2026-02-11T18:20:29.792068Z","end":"2026-02-11T18:20:29.902694Z","steps":["trace[420237559] 'process raft request' (duration: 110.203001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:29.902889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.071466ms","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-11T18:20:29.90291Z","caller":"traceutil/trace.go:171","msg":"trace[1479260076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"110.131318ms","start":"2026-02-11T18:20:29.792773Z","end":"2026-02-11T18:20:29.902904Z","steps":["trace[1479260076] 'agreement among raft nodes before linearized reading' (duration: 110.042965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:29.903026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.244189ms","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-11T18:20:29.903043Z","caller":"traceutil/trace.go:171","msg":"trace[1983631258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"109.282951ms","start":"2026-02-11T18:20:29.793755Z","end":"2026-02-11T18:20:29.903038Z","steps":["trace[1983631258] 'agreement among raft nodes before linearized reading' (duration: 109.229929ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:32.144273Z","caller":"traceutil/trace.go:171","msg":"trace[1447382047] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"236.980186ms","start":"2026-02-11T18:20:31.907269Z","end":"2026-02-11T18:20:32.14425Z","steps":["trace[1447382047] 'read index received' (duration: 236.81767ms)","trace[1447382047] 'applied index is now lower than readState.Index' (duration: 161.536µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:20:32.144614Z","caller":"traceutil/trace.go:171","msg":"trace[1865274467] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"465.867725ms","start":"2026-02-11T18:20:31.678733Z","end":"2026-02-11T18:20:32.1446Z","steps":["trace[1865274467] 'process raft request' (duration: 465.40592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:32.144709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:20:31.678714Z","time spent":"465.928297ms","remote":"127.0.0.1:41084","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-11T18:20:32.144953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.691898ms","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-11T18:20:32.144977Z","caller":"traceutil/trace.go:171","msg":"trace[1843300893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"237.75635ms","start":"2026-02-11T18:20:31.907214Z","end":"2026-02-11T18:20:32.14497Z","steps":["trace[1843300893] 'agreement among raft nodes before linearized reading' (duration: 237.657037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:32.146252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.590452ms","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-11T18:20:32.146279Z","caller":"traceutil/trace.go:171","msg":"trace[2005357227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"217.638243ms","start":"2026-02-11T18:20:31.928633Z","end":"2026-02-11T18:20:32.146271Z","steps":["trace[2005357227] 'agreement among raft nodes before linearized reading' (duration: 216.426324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:32.146647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.181573ms","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-11T18:20:32.146667Z","caller":"traceutil/trace.go:171","msg":"trace[1055982030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:989; }","duration":"196.222335ms","start":"2026-02-11T18:20:31.950439Z","end":"2026-02-11T18:20:32.146662Z","steps":["trace[1055982030] 'agreement among raft nodes before linearized reading' (duration: 196.173654ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:32.416019Z","caller":"traceutil/trace.go:171","msg":"trace[1003973209] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"260.788009ms","start":"2026-02-11T18:20:32.155212Z","end":"2026-02-11T18:20:32.416Z","steps":["trace[1003973209] 'process raft request' (duration: 260.015974ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:32.416562Z","caller":"traceutil/trace.go:171","msg":"trace[797012559] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"259.066265ms","start":"2026-02-11T18:20:32.157427Z","end":"2026-02-11T18:20:32.416493Z","steps":["trace[797012559] 'process raft request' (duration: 258.884659ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:32.416689Z","caller":"traceutil/trace.go:171","msg":"trace[164742332] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"259.322243ms","start":"2026-02-11T18:20:32.157358Z","end":"2026-02-11T18:20:32.416681Z","steps":["trace[164742332] 'process raft request' (duration: 258.587229ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:52.619336Z","caller":"traceutil/trace.go:171","msg":"trace[1498527638] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"101.251916ms","start":"2026-02-11T18:20:52.518056Z","end":"2026-02-11T18:20:52.619308Z","steps":["trace[1498527638] 'process raft request' (duration: 34.874644ms)","trace[1498527638] 'compare' (duration: 66.01441ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:20:52.619757Z","caller":"traceutil/trace.go:171","msg":"trace[888214860] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"101.278886ms","start":"2026-02-11T18:20:52.518466Z","end":"2026-02-11T18:20:52.619745Z","steps":["trace[888214860] 'read index received' (duration: 34.535083ms)","trace[888214860] 'applied index is now lower than readState.Index' (duration: 66.742893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:20:52.619893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.40686ms","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-11T18:20:52.619954Z","caller":"traceutil/trace.go:171","msg":"trace[1276270407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"101.469623ms","start":"2026-02-11T18:20:52.518449Z","end":"2026-02-11T18:20:52.619919Z","steps":["trace[1276270407] 'agreement among raft nodes before linearized reading' (duration: 101.342679ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:52.621617Z","caller":"traceutil/trace.go:171","msg":"trace[2028468818] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"101.570845ms","start":"2026-02-11T18:20:52.520032Z","end":"2026-02-11T18:20:52.621603Z","steps":["trace[2028468818] 'process raft request' (duration: 99.663085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:20:58.846128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.154494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:20:58.846305Z","caller":"traceutil/trace.go:171","msg":"trace[1419160027] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1159; }","duration":"155.247757ms","start":"2026-02-11T18:20:58.69104Z","end":"2026-02-11T18:20:58.846288Z","steps":["trace[1419160027] 'read index received' (duration: 41.848386ms)","trace[1419160027] 'applied index is now lower than readState.Index' (duration: 113.398361ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:20:58.846369Z","caller":"traceutil/trace.go:171","msg":"trace[332976058] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"176.794699ms","start":"2026-02-11T18:20:58.669555Z","end":"2026-02-11T18:20:58.84635Z","steps":["trace[332976058] 'process raft request' (duration: 63.324565ms)","trace[332976058] 'compare' (duration: 113.012529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:20:58.846455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.404872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-11T18:20:58.84649Z","caller":"traceutil/trace.go:171","msg":"trace[2108021271] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1116; }","duration":"155.466454ms","start":"2026-02-11T18:20:58.691011Z","end":"2026-02-11T18:20:58.846478Z","steps":["trace[2108021271] 'agreement among raft nodes before linearized reading' (duration: 155.324419ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:20:58.84659Z","caller":"traceutil/trace.go:171","msg":"trace[970898807] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"174.60895ms","start":"2026-02-11T18:20:58.671973Z","end":"2026-02-11T18:20:58.846582Z","steps":["trace[970898807] 'process raft request' (duration: 174.254789ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:21:18.975896Z","caller":"traceutil/trace.go:171","msg":"trace[1484352730] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"132.799709ms","start":"2026-02-11T18:21:18.843024Z","end":"2026-02-11T18:21:18.975824Z","steps":["trace[1484352730] 'process raft request' (duration: 122.392377ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:22:11.442066Z","caller":"traceutil/trace.go:171","msg":"trace[1725965573] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"143.936653ms","start":"2026-02-11T18:22:11.298113Z","end":"2026-02-11T18:22:11.442049Z","steps":["trace[1725965573] 'process raft request' (duration: 143.552091ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:22:43.89826Z","caller":"traceutil/trace.go:171","msg":"trace[236472729] linearizableReadLoop","detail":"{readStateIndex:1609; appliedIndex:1608; }","duration":"113.159004ms","start":"2026-02-11T18:22:43.785083Z","end":"2026-02-11T18:22:43.898242Z","steps":["trace[236472729] 'read index received' (duration: 66.317999ms)","trace[236472729] 'applied index is now lower than readState.Index' (duration: 46.840435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:22:43.898377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.281058ms","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-11T18:22:43.898396Z","caller":"traceutil/trace.go:171","msg":"trace[1202655425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1543; }","duration":"113.33452ms","start":"2026-02-11T18:22:43.785056Z","end":"2026-02-11T18:22:43.89839Z","steps":["trace[1202655425] 'agreement among raft nodes before linearized reading' (duration: 113.247657ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:22:43.898433Z","caller":"traceutil/trace.go:171","msg":"trace[834670781] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"140.186414ms","start":"2026-02-11T18:22:43.758214Z","end":"2026-02-11T18:22:43.8984Z","steps":["trace[834670781] 'process raft request' (duration: 93.208254ms)","trace[834670781] 'compare' (duration: 46.555186ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:23:41.389523Z","caller":"traceutil/trace.go:171","msg":"trace[849223653] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"151.933991ms","start":"2026-02-11T18:23:41.237567Z","end":"2026-02-11T18:23:41.389501Z","steps":["trace[849223653] 'process raft request' (duration: 151.815997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:23:41.713032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.778327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T18:23:41.713112Z","caller":"traceutil/trace.go:171","msg":"trace[271452872] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1886; }","duration":"380.102383ms","start":"2026-02-11T18:23:41.332997Z","end":"2026-02-11T18:23:41.713099Z","steps":["trace[271452872] 'read index received' (duration: 56.904629ms)","trace[271452872] 'applied index is now lower than readState.Index' (duration: 323.197024ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T18:23:41.713272Z","caller":"traceutil/trace.go:171","msg":"trace[307031621] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"475.365293ms","start":"2026-02-11T18:23:41.237896Z","end":"2026-02-11T18:23:41.713261Z","steps":["trace[307031621] 'process raft request' (duration: 373.298787ms)","trace[307031621] 'compare' (duration: 101.638113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T18:23:41.713323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:23:41.237876Z","time spent":"475.421655ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T18:23:41.713478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.476475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T18:23:41.713509Z","caller":"traceutil/trace.go:171","msg":"trace[268312318] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1809; }","duration":"380.530386ms","start":"2026-02-11T18:23:41.332969Z","end":"2026-02-11T18:23:41.7135Z","steps":["trace[268312318] 'agreement among raft nodes before linearized reading' (duration: 380.481104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:23:41.713531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T18:23:41.332957Z","time spent":"380.568317ms","remote":"127.0.0.1:53888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-11T18:23:41.713631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.384319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T18:23:41.713661Z","caller":"traceutil/trace.go:171","msg":"trace[525317885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1809; }","duration":"241.466701ms","start":"2026-02-11T18:23:41.472185Z","end":"2026-02-11T18:23:41.713651Z","steps":["trace[525317885] 'agreement among raft nodes before linearized reading' (duration: 241.4309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T18:23:41.71393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.394576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-11T18:23:41.713957Z","caller":"traceutil/trace.go:171","msg":"trace[1033915579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1809; }","duration":"108.453938ms","start":"2026-02-11T18:23:41.605497Z","end":"2026-02-11T18:23:41.713951Z","steps":["trace[1033915579] 'agreement among raft nodes before linearized reading' (duration: 108.375825ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T18:23:41.822374Z","caller":"traceutil/trace.go:171","msg":"trace[45036787] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"101.57357ms","start":"2026-02-11T18:23:41.72078Z","end":"2026-02-11T18:23:41.822353Z","steps":["trace[45036787] 'process raft request' (duration: 99.660589ms)"],"step_count":1}