{"level":"warn","ts":"2026-04-02T14:00:39.899899Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T14:00:39.899984Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6: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.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6: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-04-02T14:00:39.900054Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T14:00:39.90007Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-02T14:00:39.90009Z","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-04-02T14:00:39.901249Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]} {"level":"info","ts":"2026-04-02T14:00:39.901324Z","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":8,"max-cpu-available":8,"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.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6: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-04-02T14:00:39.907253Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.732805ms"} {"level":"info","ts":"2026-04-02T14:00:39.918315Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"} {"level":"info","ts":"2026-04-02T14:00:39.918391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T14:00:39.91841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"} {"level":"info","ts":"2026-04-02T14:00:39.918425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T14:00:39.918438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"} {"level":"info","ts":"2026-04-02T14:00:39.918474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"warn","ts":"2026-04-02T14:00:39.924865Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T14:00:39.926235Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T14:00:39.928221Z","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-04-02T14:00:39.930837Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T14:00:39.931193Z","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-04-02T14:00:39.931689Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T14:00:39.931847Z","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-04-02T14:00:39.931875Z","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-04-02T14:00:39.932194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"info","ts":"2026-04-02T14:00:39.934091Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-02T14:00:39.936216Z","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-04-02T14:00:39.936366Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-02T14:00:39.936418Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-02T14:00:39.93667Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T14:00:39.936708Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T14:00:40.91918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T14:00:40.91925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T14:00:40.919333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"} {"level":"info","ts":"2026-04-02T14:00:40.919353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"} {"level":"info","ts":"2026-04-02T14:00:40.919371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-02T14:00:40.919391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"} {"level":"info","ts":"2026-04-02T14:00:40.919409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-02T14:00:40.922741Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T14:00:40.922786Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T14:00:40.922825Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T14:00:40.923014Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T14:00:40.923136Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T14:00:40.923229Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T14:00:40.924387Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T14:00:40.924615Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T14:00:40.924671Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T14:00:40.925513Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"} {"level":"info","ts":"2026-04-02T14:00:40.928153Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T14:01:12.862155Z","caller":"traceutil/trace.go:171","msg":"trace[1929579907] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"234.598479ms","start":"2026-04-02T14:01:12.627533Z","end":"2026-04-02T14:01:12.862132Z","steps":["trace[1929579907] 'read index received' (duration: 234.451096ms)","trace[1929579907] 'applied index is now lower than readState.Index' (duration: 146.683µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:01:12.862231Z","caller":"traceutil/trace.go:171","msg":"trace[1656846841] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"266.832377ms","start":"2026-04-02T14:01:12.595364Z","end":"2026-04-02T14:01:12.862197Z","steps":["trace[1656846841] 'process raft request' (duration: 266.605492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:12.862281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.735353ms","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-04-02T14:01:12.862324Z","caller":"traceutil/trace.go:171","msg":"trace[541227593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:480; }","duration":"234.832555ms","start":"2026-04-02T14:01:12.627485Z","end":"2026-04-02T14:01:12.862318Z","steps":["trace[541227593] 'agreement among raft nodes before linearized reading' (duration: 234.751593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:12.864299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.734772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:01:12.864378Z","caller":"traceutil/trace.go:171","msg":"trace[1984530177] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:481; }","duration":"185.892276ms","start":"2026-04-02T14:01:12.678469Z","end":"2026-04-02T14:01:12.864361Z","steps":["trace[1984530177] 'agreement among raft nodes before linearized reading' (duration: 185.404634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.693527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.039342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-02T14:01:15.693823Z","caller":"traceutil/trace.go:171","msg":"trace[833062230] transaction","detail":"{read_only:false; number_of_response:0; response_revision:534; }","duration":"975.799107ms","start":"2026-04-02T14:01:14.717958Z","end":"2026-04-02T14:01:15.693758Z","steps":["trace[833062230] 'process raft request' (duration: 200.014237ms)","trace[833062230] 'compare' (duration: 774.966031ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:01:15.693857Z","caller":"traceutil/trace.go:171","msg":"trace[1309461777] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"971.831044ms","start":"2026-04-02T14:01:14.722009Z","end":"2026-04-02T14:01:15.69384Z","steps":["trace[1309461777] 'process raft request' (duration: 971.64975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.694098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.721999Z","time spent":"972.057859ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T14:01:15.694125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.71795Z","time spent":"976.048803ms","remote":"127.0.0.1:51142","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T14:01:15.708286Z","caller":"traceutil/trace.go:171","msg":"trace[805253449] transaction","detail":"{read_only:false; number_of_response:1; response_revision:535; }","duration":"982.421633ms","start":"2026-04-02T14:01:14.725842Z","end":"2026-04-02T14:01:15.708264Z","steps":["trace[805253449] 'process raft request' (duration: 982.249619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.708572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.725836Z","time spent":"982.513105ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7375,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:15.708608Z","caller":"traceutil/trace.go:171","msg":"trace[2124822365] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"950.253748ms","start":"2026-04-02T14:01:14.758344Z","end":"2026-04-02T14:01:15.708598Z","steps":["trace[2124822365] 'process raft request' (duration: 950.155145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.70869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.758323Z","time spent":"950.336979ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:15.708795Z","caller":"traceutil/trace.go:171","msg":"trace[1077470718] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"982.856733ms","start":"2026-04-02T14:01:14.72593Z","end":"2026-04-02T14:01:15.708787Z","steps":["trace[1077470718] 'process raft request' (duration: 982.2778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.708829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.725926Z","time spent":"982.888974ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T14:01:15.708924Z","caller":"traceutil/trace.go:171","msg":"trace[1256136674] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"982.957235ms","start":"2026-04-02T14:01:14.72596Z","end":"2026-04-02T14:01:15.708917Z","steps":["trace[1256136674] 'process raft request' (duration: 982.29751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.708983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.725957Z","time spent":"982.985806ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:15.709004Z","caller":"traceutil/trace.go:171","msg":"trace[830593288] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"971.106437ms","start":"2026-04-02T14:01:14.73789Z","end":"2026-04-02T14:01:15.708997Z","steps":["trace[830593288] 'process raft request' (duration: 970.427301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.709041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.737883Z","time spent":"971.140998ms","remote":"127.0.0.1:51142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T14:01:15.70908Z","caller":"traceutil/trace.go:171","msg":"trace[1450374561] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"963.003057ms","start":"2026-04-02T14:01:14.746071Z","end":"2026-04-02T14:01:15.709074Z","steps":["trace[1450374561] 'process raft request' (duration: 962.27232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.709138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.74606Z","time spent":"963.056738ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:15.709141Z","caller":"traceutil/trace.go:171","msg":"trace[148282367] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:551; }","duration":"839.220229ms","start":"2026-04-02T14:01:14.869904Z","end":"2026-04-02T14:01:15.709124Z","steps":["trace[148282367] 'read index received' (duration: 48.064558ms)","trace[148282367] 'applied index is now lower than readState.Index' (duration: 791.155041ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:01:15.709166Z","caller":"traceutil/trace.go:171","msg":"trace[722410935] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"971.679501ms","start":"2026-04-02T14:01:14.73748Z","end":"2026-04-02T14:01:15.70916Z","steps":["trace[722410935] 'process raft request' (duration: 970.819071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.709196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.737465Z","time spent":"971.717861ms","remote":"127.0.0.1:51142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T14:01:15.709224Z","caller":"traceutil/trace.go:171","msg":"trace[1223084432] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"960.030097ms","start":"2026-04-02T14:01:14.749187Z","end":"2026-04-02T14:01:15.709217Z","steps":["trace[1223084432] 'process raft request' (duration: 959.242469ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:15.70928Z","caller":"traceutil/trace.go:171","msg":"trace[467622484] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"943.212643ms","start":"2026-04-02T14:01:14.766061Z","end":"2026-04-02T14:01:15.709273Z","steps":["trace[467622484] 'process raft request' (duration: 942.505316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.709288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.74918Z","time spent":"960.066758ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:01:15.709352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.766053Z","time spent":"943.248073ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T14:01:15.709437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.018878ms","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-04-02T14:01:15.709466Z","caller":"traceutil/trace.go:171","msg":"trace[254762452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"837.086489ms","start":"2026-04-02T14:01:14.872372Z","end":"2026-04-02T14:01:15.709459Z","steps":["trace[254762452] 'agreement among raft nodes before linearized reading' (duration: 836.998187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.709508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.872313Z","time spent":"837.187472ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T14:01:15.714154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.248578ms","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-04-02T14:01:15.71445Z","caller":"traceutil/trace.go:171","msg":"trace[1010415815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"844.573745ms","start":"2026-04-02T14:01:14.86986Z","end":"2026-04-02T14:01:15.714433Z","steps":["trace[1010415815] 'agreement among raft nodes before linearized reading' (duration: 844.223006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.714501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.869833Z","time spent":"844.657047ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T14:01:15.71459Z","caller":"traceutil/trace.go:171","msg":"trace[1083301206] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"844.122264ms","start":"2026-04-02T14:01:14.870456Z","end":"2026-04-02T14:01:15.714578Z","steps":["trace[1083301206] 'process raft request' (duration: 838.292237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.714673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:14.870433Z","time spent":"844.204226ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T14:01:15.7152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.429496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T14:01:15.715248Z","caller":"traceutil/trace.go:171","msg":"trace[147197541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"119.477237ms","start":"2026-04-02T14:01:15.59576Z","end":"2026-04-02T14:01:15.715238Z","steps":["trace[147197541] 'agreement among raft nodes before linearized reading' (duration: 119.370304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.715557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.462579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:01:15.71561Z","caller":"traceutil/trace.go:171","msg":"trace[216001287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"490.528661ms","start":"2026-04-02T14:01:15.225062Z","end":"2026-04-02T14:01:15.715591Z","steps":["trace[216001287] 'agreement among raft nodes before linearized reading' (duration: 490.458259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:15.715642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:15.225046Z","time spent":"490.588872ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:01:16.183062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.816782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T14:01:16.183302Z","caller":"traceutil/trace.go:171","msg":"trace[575165662] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"340.238421ms","start":"2026-04-02T14:01:15.843044Z","end":"2026-04-02T14:01:16.183283Z","steps":["trace[575165662] 'process raft request' (duration: 212.099401ms)","trace[575165662] 'compare' (duration: 127.572107ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:01:16.183395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:15.843025Z","time spent":"340.321052ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:16.183507Z","caller":"traceutil/trace.go:171","msg":"trace[1710236913] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"339.660037ms","start":"2026-04-02T14:01:15.843814Z","end":"2026-04-02T14:01:16.183474Z","steps":["trace[1710236913] 'process raft request' (duration: 339.364541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:16.183628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:15.843806Z","time spent":"339.748909ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:16.232956Z","caller":"traceutil/trace.go:171","msg":"trace[1031841399] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"338.722035ms","start":"2026-04-02T14:01:15.894219Z","end":"2026-04-02T14:01:16.232941Z","steps":["trace[1031841399] 'process raft request' (duration: 338.476889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:16.233059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:15.894203Z","time spent":"338.806447ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T14:01:16.232954Z","caller":"traceutil/trace.go:171","msg":"trace[136133145] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"246.822897ms","start":"2026-04-02T14:01:15.986105Z","end":"2026-04-02T14:01:16.232928Z","steps":["trace[136133145] 'process raft request' (duration: 246.760255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:18.225382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000029961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T14:01:18.225453Z","caller":"traceutil/trace.go:171","msg":"trace[1386010119] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000163204s","start":"2026-04-02T14:01:16.225275Z","end":"2026-04-02T14:01:18.225438Z","steps":["trace[1386010119] 'agreement among raft nodes before linearized reading' (duration: 2.000058932s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:18.225484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:16.22526Z","time spent":"2.000217775s","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:01:18.894008Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.499585993s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T14:01:18.89991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.128961339s","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-04-02T14:01:18.900047Z","caller":"traceutil/trace.go:171","msg":"trace[1059513666] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:566; }","duration":"2.129118312s","start":"2026-04-02T14:01:16.770907Z","end":"2026-04-02T14:01:18.900025Z","steps":["trace[1059513666] 'range keys from in-memory index tree' (duration: 2.128943138s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:18.900081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:18.126809Z","time spent":"773.266331ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T14:01:18.900318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.505823399s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T14:01:18.900636Z","caller":"traceutil/trace.go:171","msg":"trace[1836764228] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"2.606369871s","start":"2026-04-02T14:01:16.294249Z","end":"2026-04-02T14:01:18.900619Z","steps":["trace[1836764228] 'process raft request' (duration: 2.60632344s)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:18.900679Z","caller":"traceutil/trace.go:171","msg":"trace[1671500843] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"2.698277879s","start":"2026-04-02T14:01:16.202386Z","end":"2026-04-02T14:01:18.900664Z","steps":["trace[1671500843] 'process raft request' (duration: 2.698099105s)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:18.900689Z","caller":"traceutil/trace.go:171","msg":"trace[1295827375] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"2.842054657s","start":"2026-04-02T14:01:16.058622Z","end":"2026-04-02T14:01:18.900676Z","steps":["trace[1295827375] 'process raft request' (duration: 335.801317ms)","trace[1295827375] 'compare' (duration: 2.50539789s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:01:18.900707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:16.29422Z","time spent":"2.606461344s","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T14:01:18.900789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:16.202372Z","time spent":"2.698342792s","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:18.900912Z","caller":"traceutil/trace.go:171","msg":"trace[1134558475] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:585; }","duration":"2.675557536s","start":"2026-04-02T14:01:16.225322Z","end":"2026-04-02T14:01:18.900879Z","steps":["trace[1134558475] 'read index received' (duration: 7.371943ms)","trace[1134558475] 'applied index is now lower than readState.Index' (duration: 2.668184193s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:01:18.900804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:16.058604Z","time spent":"2.842115818s","remote":"127.0.0.1:50784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4359,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:18.900643Z","caller":"traceutil/trace.go:171","msg":"trace[910467580] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"2.703621115s","start":"2026-04-02T14:01:16.197002Z","end":"2026-04-02T14:01:18.900623Z","steps":["trace[910467580] 'process raft request' (duration: 2.70339498s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:18.901017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:16.196977Z","time spent":"2.704005014s","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:01:18.901276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162012121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T14:01:18.901337Z","caller":"traceutil/trace.go:171","msg":"trace[1384327050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"1.162117963s","start":"2026-04-02T14:01:17.739204Z","end":"2026-04-02T14:01:18.901322Z","steps":["trace[1384327050] 'agreement among raft nodes before linearized reading' (duration: 1.16197902s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:18.901397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:17.73918Z","time spent":"1.162207105s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T14:01:18.90204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.893253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:01:18.902086Z","caller":"traceutil/trace.go:171","msg":"trace[1369049678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"675.980256ms","start":"2026-04-02T14:01:18.226095Z","end":"2026-04-02T14:01:18.902075Z","steps":["trace[1369049678] 'agreement among raft nodes before linearized reading' (duration: 675.913664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:18.902116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:18.226075Z","time spent":"676.034807ms","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:01:18.902175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162886282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T14:01:18.902221Z","caller":"traceutil/trace.go:171","msg":"trace[1105910950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"1.162964873s","start":"2026-04-02T14:01:17.739246Z","end":"2026-04-02T14:01:18.902211Z","steps":["trace[1105910950] 'agreement among raft nodes before linearized reading' (duration: 1.162881681s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:18.902257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:17.739228Z","time spent":"1.163022315s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T14:01:18.902414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161064178s","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-04-02T14:01:18.902445Z","caller":"traceutil/trace.go:171","msg":"trace[840065470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"1.161109809s","start":"2026-04-02T14:01:17.741328Z","end":"2026-04-02T14:01:18.902438Z","steps":["trace[840065470] 'agreement among raft nodes before linearized reading' (duration: 1.161011897s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:18.90247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:17.741318Z","time spent":"1.161146931s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T14:01:18.902614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161937409s","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-04-02T14:01:18.902645Z","caller":"traceutil/trace.go:171","msg":"trace[945809901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"1.162013571s","start":"2026-04-02T14:01:17.740624Z","end":"2026-04-02T14:01:18.902637Z","steps":["trace[945809901] 'agreement among raft nodes before linearized reading' (duration: 1.161907718s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:18.902674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:17.740599Z","time spent":"1.162069662s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T14:01:23.128114Z","caller":"traceutil/trace.go:171","msg":"trace[1912828718] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"193.024313ms","start":"2026-04-02T14:01:22.935066Z","end":"2026-04-02T14:01:23.12809Z","steps":["trace[1912828718] 'process raft request' (duration: 192.89759ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:23.128653Z","caller":"traceutil/trace.go:171","msg":"trace[1980972362] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:633; }","duration":"192.583583ms","start":"2026-04-02T14:01:22.936059Z","end":"2026-04-02T14:01:23.128643Z","steps":["trace[1980972362] 'read index received' (duration: 192.579193ms)","trace[1980972362] 'applied index is now lower than readState.Index' (duration: 3.73µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:01:23.128802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.734366ms","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-04-02T14:01:23.128838Z","caller":"traceutil/trace.go:171","msg":"trace[1208241985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"192.813088ms","start":"2026-04-02T14:01:22.936016Z","end":"2026-04-02T14:01:23.128829Z","steps":["trace[1208241985] 'agreement among raft nodes before linearized reading' (duration: 192.693466ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:23.360569Z","caller":"traceutil/trace.go:171","msg":"trace[2108151724] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"350.817249ms","start":"2026-04-02T14:01:23.00972Z","end":"2026-04-02T14:01:23.360538Z","steps":["trace[2108151724] 'process raft request' (duration: 350.692876ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:23.360641Z","caller":"traceutil/trace.go:171","msg":"trace[938447336] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:634; }","duration":"231.927227ms","start":"2026-04-02T14:01:23.128688Z","end":"2026-04-02T14:01:23.360615Z","steps":["trace[938447336] 'read index received' (duration: 231.914247ms)","trace[938447336] 'applied index is now lower than readState.Index' (duration: 11.34µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:01:23.360707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:23.009698Z","time spent":"350.935071ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:01:23.360872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.199986ms","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-04-02T14:01:23.36091Z","caller":"traceutil/trace.go:171","msg":"trace[1136636214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"405.267698ms","start":"2026-04-02T14:01:22.955634Z","end":"2026-04-02T14:01:23.360902Z","steps":["trace[1136636214] 'agreement among raft nodes before linearized reading' (duration: 405.086063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:23.360934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:22.95563Z","time spent":"405.298778ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T14:01:23.360983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.00512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T14:01:23.36109Z","caller":"traceutil/trace.go:171","msg":"trace[66968728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"410.140653ms","start":"2026-04-02T14:01:22.950927Z","end":"2026-04-02T14:01:23.361068Z","steps":["trace[66968728] 'agreement among raft nodes before linearized reading' (duration: 409.851616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:23.361145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:22.95092Z","time spent":"410.212094ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T14:01:23.365016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.846171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:01:23.365066Z","caller":"traceutil/trace.go:171","msg":"trace[630938469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"138.932652ms","start":"2026-04-02T14:01:23.226122Z","end":"2026-04-02T14:01:23.365054Z","steps":["trace[630938469] 'agreement among raft nodes before linearized reading' (duration: 138.85156ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:23.365066Z","caller":"traceutil/trace.go:171","msg":"trace[906510418] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"233.719369ms","start":"2026-04-02T14:01:23.131332Z","end":"2026-04-02T14:01:23.365051Z","steps":["trace[906510418] 'process raft request' (duration: 233.556955ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:24.183248Z","caller":"traceutil/trace.go:171","msg":"trace[1007561990] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"283.933968ms","start":"2026-04-02T14:01:23.89832Z","end":"2026-04-02T14:01:24.182254Z","steps":["trace[1007561990] 'process raft request' (duration: 281.744496ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:25.419803Z","caller":"traceutil/trace.go:171","msg":"trace[597904789] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"851.794205ms","start":"2026-04-02T14:01:24.567981Z","end":"2026-04-02T14:01:25.419776Z","steps":["trace[597904789] 'process raft request' (duration: 851.612561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:25.419956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:24.567916Z","time spent":"851.962569ms","remote":"127.0.0.1:50798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:01:26.942185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.018237ms","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-04-02T14:01:26.942285Z","caller":"traceutil/trace.go:171","msg":"trace[1349409353] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:624; }","duration":"171.1334ms","start":"2026-04-02T14:01:26.771138Z","end":"2026-04-02T14:01:26.942271Z","steps":["trace[1349409353] 'range keys from in-memory index tree' (duration: 171.002516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:26.943712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351745907s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T14:01:26.943761Z","caller":"traceutil/trace.go:171","msg":"trace[474910300] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"1.810681535s","start":"2026-04-02T14:01:25.133071Z","end":"2026-04-02T14:01:26.943752Z","steps":["trace[474910300] 'read index received' (duration: 286.571631ms)","trace[474910300] 'applied index is now lower than readState.Index' (duration: 1.524109214s)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:01:26.943804Z","caller":"traceutil/trace.go:171","msg":"trace[2022278555] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"2.324774329s","start":"2026-04-02T14:01:24.619023Z","end":"2026-04-02T14:01:26.943797Z","steps":["trace[2022278555] 'process raft request' (duration: 972.886819ms)","trace[2022278555] 'compare' (duration: 1.351177914s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:01:26.943843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:24.618989Z","time spent":"2.32483559s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:01:26.944021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.810915871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T14:01:26.944114Z","caller":"traceutil/trace.go:171","msg":"trace[1518664848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.811061264s","start":"2026-04-02T14:01:25.133029Z","end":"2026-04-02T14:01:26.94409Z","steps":["trace[1518664848] 'agreement among raft nodes before linearized reading' (duration: 1.810786698s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:26.944161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:25.133014Z","time spent":"1.811136846s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T14:01:26.947418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722508144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:01:26.947488Z","caller":"traceutil/trace.go:171","msg":"trace[1515081289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"1.722613187s","start":"2026-04-02T14:01:25.224856Z","end":"2026-04-02T14:01:26.947469Z","steps":["trace[1515081289] 'agreement among raft nodes before linearized reading' (duration: 1.722483404s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:26.947522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:25.224844Z","time spent":"1.722671068s","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:01:26.947541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577777766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T14:01:26.947619Z","caller":"traceutil/trace.go:171","msg":"trace[1336210091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.577883828s","start":"2026-04-02T14:01:25.369716Z","end":"2026-04-02T14:01:26.9476Z","steps":["trace[1336210091] 'agreement among raft nodes before linearized reading' (duration: 1.577618392s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:26.947663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:25.369706Z","time spent":"1.577948209s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T14:01:26.94782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578109574s","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-04-02T14:01:26.947884Z","caller":"traceutil/trace.go:171","msg":"trace[1032259782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"1.578184015s","start":"2026-04-02T14:01:25.369686Z","end":"2026-04-02T14:01:26.94787Z","steps":["trace[1032259782] 'agreement among raft nodes before linearized reading' (duration: 1.578013431s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:26.947916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:25.369681Z","time spent":"1.578226786s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T14:01:26.947896Z","caller":"traceutil/trace.go:171","msg":"trace[657924658] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"661.535157ms","start":"2026-04-02T14:01:26.286334Z","end":"2026-04-02T14:01:26.94787Z","steps":["trace[657924658] 'process raft request' (duration: 660.846211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:26.948048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:26.286301Z","time spent":"661.659979ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:01:26.948123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.579728431s","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-04-02T14:01:26.948153Z","caller":"traceutil/trace.go:171","msg":"trace[763924024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"1.579787933s","start":"2026-04-02T14:01:25.368356Z","end":"2026-04-02T14:01:26.948144Z","steps":["trace[763924024] 'agreement among raft nodes before linearized reading' (duration: 1.57969821s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:26.948177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:25.368336Z","time spent":"1.579836194s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T14:01:27.83796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.479799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T14:01:27.838356Z","caller":"traceutil/trace.go:171","msg":"trace[662649632] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"879.763811ms","start":"2026-04-02T14:01:26.958571Z","end":"2026-04-02T14:01:27.838335Z","steps":["trace[662649632] 'read index received' (duration: 491.808031ms)","trace[662649632] 'applied index is now lower than readState.Index' (duration: 387.95469ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:01:27.838481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.901454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:01:27.838503Z","caller":"traceutil/trace.go:171","msg":"trace[86241603] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"886.190741ms","start":"2026-04-02T14:01:26.952289Z","end":"2026-04-02T14:01:27.83848Z","steps":["trace[86241603] 'process raft request' (duration: 885.883014ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:27.838518Z","caller":"traceutil/trace.go:171","msg":"trace[1045546635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"879.965175ms","start":"2026-04-02T14:01:26.958541Z","end":"2026-04-02T14:01:27.838506Z","steps":["trace[1045546635] 'agreement among raft nodes before linearized reading' (duration: 879.861283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:27.838564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:26.958523Z","time spent":"880.028317ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:01:27.838597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:26.952281Z","time spent":"886.267993ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:27.838739Z","caller":"traceutil/trace.go:171","msg":"trace[152897887] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"887.970824ms","start":"2026-04-02T14:01:26.950739Z","end":"2026-04-02T14:01:27.838709Z","steps":["trace[152897887] 'process raft request' (duration: 499.628675ms)","trace[152897887] 'compare' (duration: 387.249423ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:01:27.838762Z","caller":"traceutil/trace.go:171","msg":"trace[1487426430] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"886.56053ms","start":"2026-04-02T14:01:26.952173Z","end":"2026-04-02T14:01:27.838734Z","steps":["trace[1487426430] 'process raft request' (duration: 885.922085ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:27.838758Z","caller":"traceutil/trace.go:171","msg":"trace[67062573] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"883.331874ms","start":"2026-04-02T14:01:26.955392Z","end":"2026-04-02T14:01:27.838724Z","steps":["trace[67062573] 'process raft request' (duration: 882.827532ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:27.838797Z","caller":"traceutil/trace.go:171","msg":"trace[570893718] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"840.050467ms","start":"2026-04-02T14:01:26.998731Z","end":"2026-04-02T14:01:27.838782Z","steps":["trace[570893718] 'process raft request' (duration: 839.530535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:27.83887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:26.998707Z","time spent":"840.12796ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T14:01:27.838873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:26.952155Z","time spent":"886.660032ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:01:27.8389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:26.950714Z","time spent":"888.089946ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:01:27.838933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:26.955374Z","time spent":"883.459428ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:01:29.801992Z","caller":"traceutil/trace.go:171","msg":"trace[1889884044] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"109.387079ms","start":"2026-04-02T14:01:29.69258Z","end":"2026-04-02T14:01:29.801967Z","steps":["trace[1889884044] 'process raft request' (duration: 109.261956ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:41.510527Z","caller":"traceutil/trace.go:171","msg":"trace[770934606] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"155.220115ms","start":"2026-04-02T14:01:41.35526Z","end":"2026-04-02T14:01:41.51048Z","steps":["trace[770934606] 'process raft request' (duration: 155.033501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:44.429985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.574415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:01:44.430082Z","caller":"traceutil/trace.go:171","msg":"trace[586997217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"204.722689ms","start":"2026-04-02T14:01:44.22534Z","end":"2026-04-02T14:01:44.430062Z","steps":["trace[586997217] 'range keys from in-memory index tree' (duration: 204.492763ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:01:45.237624Z","caller":"traceutil/trace.go:171","msg":"trace[1552790152] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"565.682016ms","start":"2026-04-02T14:01:44.671919Z","end":"2026-04-02T14:01:45.237601Z","steps":["trace[1552790152] 'process raft request' (duration: 565.540153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:01:45.237859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:01:44.671895Z","time spent":"565.86346ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:02:02.158049Z","caller":"traceutil/trace.go:171","msg":"trace[745684240] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"135.83514ms","start":"2026-04-02T14:02:02.022172Z","end":"2026-04-02T14:02:02.158007Z","steps":["trace[745684240] 'process raft request' (duration: 135.581264ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:02:04.322542Z","caller":"traceutil/trace.go:171","msg":"trace[799858828] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"242.444424ms","start":"2026-04-02T14:02:04.080073Z","end":"2026-04-02T14:02:04.322517Z","steps":["trace[799858828] 'read index received' (duration: 227.630366ms)","trace[799858828] 'applied index is now lower than readState.Index' (duration: 14.812868ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:02:04.322676Z","caller":"traceutil/trace.go:171","msg":"trace[1018377861] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"289.257694ms","start":"2026-04-02T14:02:04.033386Z","end":"2026-04-02T14:02:04.322643Z","steps":["trace[1018377861] 'process raft request' (duration: 274.306863ms)","trace[1018377861] 'compare' (duration: 14.713575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:02:04.322887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.775722ms","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-04-02T14:02:04.322983Z","caller":"traceutil/trace.go:171","msg":"trace[2059790338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"242.917225ms","start":"2026-04-02T14:02:04.080043Z","end":"2026-04-02T14:02:04.322961Z","steps":["trace[2059790338] 'agreement among raft nodes before linearized reading' (duration: 242.646679ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:02:04.340259Z","caller":"traceutil/trace.go:171","msg":"trace[1696282408] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"236.255039ms","start":"2026-04-02T14:02:04.103967Z","end":"2026-04-02T14:02:04.340222Z","steps":["trace[1696282408] 'process raft request' (duration: 236.141466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:04.340341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.324531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:02:04.340517Z","caller":"traceutil/trace.go:171","msg":"trace[1997337839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"113.482305ms","start":"2026-04-02T14:02:04.226981Z","end":"2026-04-02T14:02:04.340463Z","steps":["trace[1997337839] 'agreement among raft nodes before linearized reading' (duration: 113.348012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:04.340298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.470901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T14:02:04.340578Z","caller":"traceutil/trace.go:171","msg":"trace[1539852324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"178.809439ms","start":"2026-04-02T14:02:04.161745Z","end":"2026-04-02T14:02:04.340554Z","steps":["trace[1539852324] 'agreement among raft nodes before linearized reading' (duration: 178.442651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:19.820164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.597908ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T14:02:19.820285Z","caller":"traceutil/trace.go:171","msg":"trace[198494669] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"647.906995ms","start":"2026-04-02T14:02:19.172356Z","end":"2026-04-02T14:02:19.820263Z","steps":["trace[198494669] 'read index received' (duration: 91.158714ms)","trace[198494669] 'applied index is now lower than readState.Index' (duration: 556.746171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:02:19.82062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.243763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:02:19.821812Z","caller":"traceutil/trace.go:171","msg":"trace[215537177] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1017; }","duration":"649.478832ms","start":"2026-04-02T14:02:19.172315Z","end":"2026-04-02T14:02:19.821794Z","steps":["trace[215537177] 'agreement among raft nodes before linearized reading' (duration: 648.206562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:19.821873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:19.172295Z","time spent":"649.558024ms","remote":"127.0.0.1:50936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-02T14:02:19.821966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.284312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:02:19.822114Z","caller":"traceutil/trace.go:171","msg":"trace[433795117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"596.560839ms","start":"2026-04-02T14:02:19.225509Z","end":"2026-04-02T14:02:19.822069Z","steps":["trace[433795117] 'agreement among raft nodes before linearized reading' (duration: 595.432513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:19.822169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:19.225491Z","time spent":"596.666081ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T14:02:46.190105Z","caller":"traceutil/trace.go:171","msg":"trace[1366458807] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"225.190022ms","start":"2026-04-02T14:02:45.964887Z","end":"2026-04-02T14:02:46.190077Z","steps":["trace[1366458807] 'process raft request' (duration: 225.045668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:46.727733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807350126737263,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T14:02:46.851779Z","caller":"traceutil/trace.go:171","msg":"trace[900045320] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"625.068777ms","start":"2026-04-02T14:02:46.226691Z","end":"2026-04-02T14:02:46.85176Z","steps":["trace[900045320] 'read index received' (duration: 570.022546ms)","trace[900045320] 'applied index is now lower than readState.Index' (duration: 55.045571ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:02:46.852006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.026847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T14:02:46.852008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.309252ms","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-04-02T14:02:46.852031Z","caller":"traceutil/trace.go:171","msg":"trace[1561726945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"338.088649ms","start":"2026-04-02T14:02:46.513934Z","end":"2026-04-02T14:02:46.852023Z","steps":["trace[1561726945] 'agreement among raft nodes before linearized reading' (duration: 337.986907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:46.852057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.513918Z","time spent":"338.13321ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T14:02:46.852055Z","caller":"traceutil/trace.go:171","msg":"trace[1759057290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1105; }","duration":"625.387604ms","start":"2026-04-02T14:02:46.226653Z","end":"2026-04-02T14:02:46.85204Z","steps":["trace[1759057290] 'agreement among raft nodes before linearized reading' (duration: 625.259301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:46.852086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.226636Z","time spent":"625.442205ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T14:02:46.852167Z","caller":"traceutil/trace.go:171","msg":"trace[990435176] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"818.197222ms","start":"2026-04-02T14:02:46.033959Z","end":"2026-04-02T14:02:46.852156Z","steps":["trace[990435176] 'process raft request' (duration: 762.745552ms)","trace[990435176] 'compare' (duration: 54.975449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:02:46.852229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.808334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T14:02:46.852249Z","caller":"traceutil/trace.go:171","msg":"trace[1431193311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"200.866675ms","start":"2026-04-02T14:02:46.651377Z","end":"2026-04-02T14:02:46.852243Z","steps":["trace[1431193311] 'agreement among raft nodes before linearized reading' (duration: 200.812624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:46.85224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.033933Z","time spent":"818.254973ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:02:46.852364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.962514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:02:46.852385Z","caller":"traceutil/trace.go:171","msg":"trace[531154183] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1105; }","duration":"307.011025ms","start":"2026-04-02T14:02:46.545367Z","end":"2026-04-02T14:02:46.852378Z","steps":["trace[531154183] 'agreement among raft nodes before linearized reading' (duration: 306.978614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:46.852404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.545348Z","time spent":"307.050626ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T14:02:46.852548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.711387ms","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-04-02T14:02:46.852579Z","caller":"traceutil/trace.go:171","msg":"trace[100509090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"324.752499ms","start":"2026-04-02T14:02:46.527817Z","end":"2026-04-02T14:02:46.852569Z","steps":["trace[100509090] 'agreement among raft nodes before linearized reading' (duration: 324.665887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:46.852607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.52781Z","time spent":"324.788959ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T14:02:47.427864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807350126737267,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T14:02:47.640566Z","caller":"traceutil/trace.go:171","msg":"trace[1503714634] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"784.519169ms","start":"2026-04-02T14:02:46.856017Z","end":"2026-04-02T14:02:47.640536Z","steps":["trace[1503714634] 'process raft request' (duration: 784.341065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:47.640723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.856001Z","time spent":"784.636611ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:02:48.265669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.278833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T14:02:48.265935Z","caller":"traceutil/trace.go:171","msg":"trace[1573345074] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1148; }","duration":"1.338920822s","start":"2026-04-02T14:02:46.926997Z","end":"2026-04-02T14:02:48.265918Z","steps":["trace[1573345074] 'read index received' (duration: 713.952607ms)","trace[1573345074] 'applied index is now lower than readState.Index' (duration: 624.967125ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:02:48.266025Z","caller":"traceutil/trace.go:171","msg":"trace[213417365] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"1.409888363s","start":"2026-04-02T14:02:46.856124Z","end":"2026-04-02T14:02:48.266012Z","steps":["trace[213417365] 'process raft request' (duration: 1.07116131s)","trace[213417365] 'compare' (duration: 337.986956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:02:48.266081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.856102Z","time spent":"1.409946105s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:02:48.266191Z","caller":"traceutil/trace.go:171","msg":"trace[828306664] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"1.408823469s","start":"2026-04-02T14:02:46.857341Z","end":"2026-04-02T14:02:48.266164Z","steps":["trace[828306664] 'process raft request' (duration: 1.408540672s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:48.266304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339306211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T14:02:48.266316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.857334Z","time spent":"1.408913541s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:02:48.266336Z","caller":"traceutil/trace.go:171","msg":"trace[209634351] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1109; }","duration":"1.339340512s","start":"2026-04-02T14:02:46.926987Z","end":"2026-04-02T14:02:48.266327Z","steps":["trace[209634351] 'agreement among raft nodes before linearized reading' (duration: 1.3392659s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:48.266368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.926956Z","time spent":"1.339404844s","remote":"127.0.0.1:51008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-04-02T14:02:48.266474Z","caller":"traceutil/trace.go:171","msg":"trace[979536935] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"1.410239642s","start":"2026-04-02T14:02:46.856224Z","end":"2026-04-02T14:02:48.266464Z","steps":["trace[979536935] 'process raft request' (duration: 1.409535116s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:48.266514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042689858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T14:02:48.266524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:46.856219Z","time spent":"1.410277743s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:02:48.266544Z","caller":"traceutil/trace.go:171","msg":"trace[1329397603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"1.042747279s","start":"2026-04-02T14:02:47.223787Z","end":"2026-04-02T14:02:48.266534Z","steps":["trace[1329397603] 'agreement among raft nodes before linearized reading' (duration: 1.042705618s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:48.266568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:47.223767Z","time spent":"1.0427962s","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:02:49.550265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.760547ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T14:02:49.689283Z","caller":"traceutil/trace.go:171","msg":"trace[2109899469] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1153; }","duration":"206.211299ms","start":"2026-04-02T14:02:49.483051Z","end":"2026-04-02T14:02:49.689263Z","steps":["trace[2109899469] 'read index received' (duration: 206.003815ms)","trace[2109899469] 'applied index is now lower than readState.Index' (duration: 206.864µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:02:49.689369Z","caller":"traceutil/trace.go:171","msg":"trace[916341360] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"740.938855ms","start":"2026-04-02T14:02:48.948392Z","end":"2026-04-02T14:02:49.68933Z","steps":["trace[916341360] 'process raft request' (duration: 740.835793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:49.689412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.336692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:02:49.689442Z","caller":"traceutil/trace.go:171","msg":"trace[1173855460] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1112; }","duration":"206.423254ms","start":"2026-04-02T14:02:49.48301Z","end":"2026-04-02T14:02:49.689433Z","steps":["trace[1173855460] 'agreement among raft nodes before linearized reading' (duration: 206.354883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:49.689495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:48.948363Z","time spent":"741.063748ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:02:49.689734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.601909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:02:49.689989Z","caller":"traceutil/trace.go:171","msg":"trace[1031675257] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1112; }","duration":"142.931496ms","start":"2026-04-02T14:02:49.54701Z","end":"2026-04-02T14:02:49.689941Z","steps":["trace[1031675257] 'agreement among raft nodes before linearized reading' (duration: 142.611039ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:02:49.690104Z","caller":"traceutil/trace.go:171","msg":"trace[1938835036] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"832.973447ms","start":"2026-04-02T14:02:48.856351Z","end":"2026-04-02T14:02:49.689324Z","steps":["trace[1938835036] 'process raft request' (duration: 832.758902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:49.690262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:48.856333Z","time spent":"833.866567ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:02:50.310391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.916313ms","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-04-02T14:02:50.310642Z","caller":"traceutil/trace.go:171","msg":"trace[259716399] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1113; }","duration":"350.20009ms","start":"2026-04-02T14:02:49.960405Z","end":"2026-04-02T14:02:50.310605Z","steps":["trace[259716399] 'count revisions from in-memory index tree' (duration: 349.78527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:02:50.311328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:49.960386Z","time spent":"350.855434ms","remote":"127.0.0.1:55258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-02T14:02:50.70853Z","caller":"traceutil/trace.go:171","msg":"trace[197634664] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"392.365523ms","start":"2026-04-02T14:02:50.316144Z","end":"2026-04-02T14:02:50.70851Z","steps":["trace[197634664] 'process raft request' (duration: 392.23344ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:02:50.708529Z","caller":"traceutil/trace.go:171","msg":"trace[302422858] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"391.643095ms","start":"2026-04-02T14:02:50.316861Z","end":"2026-04-02T14:02:50.708504Z","steps":["trace[302422858] 'process raft request' (duration: 391.589224ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:02:50.708532Z","caller":"traceutil/trace.go:171","msg":"trace[677205804] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"392.907744ms","start":"2026-04-02T14:02:50.315594Z","end":"2026-04-02T14:02:50.708501Z","steps":["trace[677205804] 'process raft request' (duration: 333.308887ms)","trace[677205804] 'compare' (duration: 59.183408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:02:50.708685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:50.315577Z","time spent":"393.078899ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:02:50.708735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:50.316137Z","time spent":"392.474545ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:02:50.708684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:02:50.316843Z","time spent":"391.774248ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:02:52.876685Z","caller":"traceutil/trace.go:171","msg":"trace[1820713853] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"160.540317ms","start":"2026-04-02T14:02:52.71612Z","end":"2026-04-02T14:02:52.876661Z","steps":["trace[1820713853] 'process raft request' (duration: 134.881099ms)","trace[1820713853] 'compare' (duration: 25.510844ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:02:52.876784Z","caller":"traceutil/trace.go:171","msg":"trace[1404272678] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"160.649149ms","start":"2026-04-02T14:02:52.71612Z","end":"2026-04-02T14:02:52.876769Z","steps":["trace[1404272678] 'process raft request' (duration: 160.500796ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:02:56.943096Z","caller":"traceutil/trace.go:171","msg":"trace[1442717054] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"107.459101ms","start":"2026-04-02T14:02:56.835609Z","end":"2026-04-02T14:02:56.943068Z","steps":["trace[1442717054] 'process raft request' (duration: 107.255816ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:09.157999Z","caller":"traceutil/trace.go:171","msg":"trace[1369069729] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"101.545113ms","start":"2026-04-02T14:03:09.056438Z","end":"2026-04-02T14:03:09.157984Z","steps":["trace[1369069729] 'process raft request' (duration: 101.464351ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:09.158023Z","caller":"traceutil/trace.go:171","msg":"trace[648954013] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1273; }","duration":"100.064629ms","start":"2026-04-02T14:03:09.05794Z","end":"2026-04-02T14:03:09.158005Z","steps":["trace[648954013] 'read index received' (duration: 100.050928ms)","trace[648954013] 'applied index is now lower than readState.Index' (duration: 7.401µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:03:09.158186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.229733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T14:03:09.158223Z","caller":"traceutil/trace.go:171","msg":"trace[555293536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1225; }","duration":"100.300775ms","start":"2026-04-02T14:03:09.057913Z","end":"2026-04-02T14:03:09.158214Z","steps":["trace[555293536] 'agreement among raft nodes before linearized reading' (duration: 100.168262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:09.168252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.915325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T14:03:09.16833Z","caller":"traceutil/trace.go:171","msg":"trace[1335626336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1226; }","duration":"109.024048ms","start":"2026-04-02T14:03:09.059294Z","end":"2026-04-02T14:03:09.168318Z","steps":["trace[1335626336] 'agreement among raft nodes before linearized reading' (duration: 108.838744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:11.671931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807350126737546,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T14:03:11.862395Z","caller":"traceutil/trace.go:171","msg":"trace[156750097] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"691.279898ms","start":"2026-04-02T14:03:11.171094Z","end":"2026-04-02T14:03:11.862374Z","steps":["trace[156750097] 'read index received' (duration: 691.108354ms)","trace[156750097] 'applied index is now lower than readState.Index' (duration: 170.614µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:03:11.862886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.260501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T14:03:11.862929Z","caller":"traceutil/trace.go:171","msg":"trace[552754211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"677.323143ms","start":"2026-04-02T14:03:11.185593Z","end":"2026-04-02T14:03:11.862916Z","steps":["trace[552754211] 'agreement among raft nodes before linearized reading' (duration: 677.138179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:11.862961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:11.185588Z","time spent":"677.364404ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T14:03:11.863055Z","caller":"traceutil/trace.go:171","msg":"trace[1999164150] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"696.761715ms","start":"2026-04-02T14:03:11.166283Z","end":"2026-04-02T14:03:11.863045Z","steps":["trace[1999164150] 'process raft request' (duration: 695.946436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:11.863122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:11.166265Z","time spent":"696.809526ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:03:11.863259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.161698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T14:03:11.863284Z","caller":"traceutil/trace.go:171","msg":"trace[1122084026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"692.194699ms","start":"2026-04-02T14:03:11.171081Z","end":"2026-04-02T14:03:11.863276Z","steps":["trace[1122084026] 'agreement among raft nodes before linearized reading' (duration: 692.110547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:11.863308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:11.171076Z","time spent":"692.22745ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T14:03:11.863375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.247773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:03:11.863399Z","caller":"traceutil/trace.go:171","msg":"trace[1473864087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"638.289985ms","start":"2026-04-02T14:03:11.225102Z","end":"2026-04-02T14:03:11.863392Z","steps":["trace[1473864087] 'agreement among raft nodes before linearized reading' (duration: 638.255014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:11.863421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:11.225093Z","time spent":"638.323515ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:03:13.065347Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.028974749s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T14:03:13.072852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036414981s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T14:03:13.073028Z","caller":"traceutil/trace.go:171","msg":"trace[1240313135] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"1.202507458s","start":"2026-04-02T14:03:11.870506Z","end":"2026-04-02T14:03:13.073014Z","steps":["trace[1240313135] 'process raft request' (duration: 1.202428286s)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:13.07304Z","caller":"traceutil/trace.go:171","msg":"trace[584148453] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"1.202603559s","start":"2026-04-02T14:03:11.870392Z","end":"2026-04-02T14:03:13.072996Z","steps":["trace[584148453] 'process raft request' (duration: 165.983403ms)","trace[584148453] 'compare' (duration: 1.035180693s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:03:13.073094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:11.870493Z","time spent":"1.202574209s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:03:13.073147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:11.870371Z","time spent":"1.202715612s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:03:13.073318Z","caller":"traceutil/trace.go:171","msg":"trace[1863816835] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1281; }","duration":"1.045556305s","start":"2026-04-02T14:03:12.027754Z","end":"2026-04-02T14:03:13.07331Z","steps":["trace[1863816835] 'read index received' (duration: 8.631981ms)","trace[1863816835] 'applied index is now lower than readState.Index' (duration: 1.036923404s)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:03:13.073411Z","caller":"traceutil/trace.go:171","msg":"trace[1611303978] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"1.086145139s","start":"2026-04-02T14:03:11.98726Z","end":"2026-04-02T14:03:13.073405Z","steps":["trace[1611303978] 'process raft request' (duration: 1.085724009s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:13.073475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:11.987242Z","time spent":"1.086206671s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:03:13.073503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.580448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:03:13.073534Z","caller":"traceutil/trace.go:171","msg":"trace[1726528008] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1235; }","duration":"713.64773ms","start":"2026-04-02T14:03:12.359875Z","end":"2026-04-02T14:03:13.073523Z","steps":["trace[1726528008] 'agreement among raft nodes before linearized reading' (duration: 713.599888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:13.073558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:12.359855Z","time spent":"713.693891ms","remote":"127.0.0.1:53038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T14:03:13.073719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045973864s","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-04-02T14:03:13.073741Z","caller":"traceutil/trace.go:171","msg":"trace[599652625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"1.046034085s","start":"2026-04-02T14:03:12.0277Z","end":"2026-04-02T14:03:13.073734Z","steps":["trace[599652625] 'agreement among raft nodes before linearized reading' (duration: 1.045951824s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:13.073759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:12.027669Z","time spent":"1.046085047s","remote":"127.0.0.1:50880","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-04-02T14:03:13.073799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.551778ms","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-04-02T14:03:13.073827Z","caller":"traceutil/trace.go:171","msg":"trace[403624566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"478.616959ms","start":"2026-04-02T14:03:12.595203Z","end":"2026-04-02T14:03:13.07382Z","steps":["trace[403624566] 'agreement among raft nodes before linearized reading' (duration: 478.488536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:13.075137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:12.595179Z","time spent":"479.931099ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T14:03:17.288939Z","caller":"traceutil/trace.go:171","msg":"trace[664426011] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1298; }","duration":"134.585922ms","start":"2026-04-02T14:03:17.154338Z","end":"2026-04-02T14:03:17.288924Z","steps":["trace[664426011] 'read index received' (duration: 115.123979ms)","trace[664426011] 'applied index is now lower than readState.Index' (duration: 19.461363ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:03:17.288965Z","caller":"traceutil/trace.go:171","msg":"trace[599343503] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"168.217665ms","start":"2026-04-02T14:03:17.120722Z","end":"2026-04-02T14:03:17.288939Z","steps":["trace[599343503] 'process raft request' (duration: 148.764702ms)","trace[599343503] 'compare' (duration: 19.3311ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:03:17.289025Z","caller":"traceutil/trace.go:171","msg":"trace[362003277] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"168.113442ms","start":"2026-04-02T14:03:17.120904Z","end":"2026-04-02T14:03:17.289018Z","steps":["trace[362003277] 'process raft request' (duration: 167.99726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:17.28927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.907449ms","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-04-02T14:03:17.289354Z","caller":"traceutil/trace.go:171","msg":"trace[389481095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"135.051863ms","start":"2026-04-02T14:03:17.154286Z","end":"2026-04-02T14:03:17.289338Z","steps":["trace[389481095] 'agreement among raft nodes before linearized reading' (duration: 134.812107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:24.093856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.806928ms","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-04-02T14:03:24.093932Z","caller":"traceutil/trace.go:171","msg":"trace[591237619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"122.922791ms","start":"2026-04-02T14:03:23.97099Z","end":"2026-04-02T14:03:24.093913Z","steps":["trace[591237619] 'range keys from in-memory index tree' (duration: 122.634114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:29.776568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807350126737722,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T14:03:29.840933Z","caller":"traceutil/trace.go:171","msg":"trace[18746495] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"565.140402ms","start":"2026-04-02T14:03:29.275767Z","end":"2026-04-02T14:03:29.840907Z","steps":["trace[18746495] 'read index received' (duration: 564.988969ms)","trace[18746495] 'applied index is now lower than readState.Index' (duration: 150.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:03:29.840984Z","caller":"traceutil/trace.go:171","msg":"trace[360784830] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"636.644846ms","start":"2026-04-02T14:03:29.204309Z","end":"2026-04-02T14:03:29.840954Z","steps":["trace[360784830] 'process raft request' (duration: 636.448332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:29.841109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.323547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T14:03:29.841157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:29.204294Z","time spent":"636.76907ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:03:29.841195Z","caller":"traceutil/trace.go:171","msg":"trace[1656880014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"565.442189ms","start":"2026-04-02T14:03:29.275729Z","end":"2026-04-02T14:03:29.841172Z","steps":["trace[1656880014] 'agreement among raft nodes before linearized reading' (duration: 565.301096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:29.841214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.788979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T14:03:29.841237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:29.275714Z","time spent":"565.515751ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T14:03:29.841247Z","caller":"traceutil/trace.go:171","msg":"trace[2083585497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"428.83458ms","start":"2026-04-02T14:03:29.412402Z","end":"2026-04-02T14:03:29.841236Z","steps":["trace[2083585497] 'agreement among raft nodes before linearized reading' (duration: 428.763998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:29.841283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:29.412395Z","time spent":"428.878731ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T14:03:29.841143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.420967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-02T14:03:29.84141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.369293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T14:03:29.841441Z","caller":"traceutil/trace.go:171","msg":"trace[273861916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"429.438594ms","start":"2026-04-02T14:03:29.411994Z","end":"2026-04-02T14:03:29.841433Z","steps":["trace[273861916] 'agreement among raft nodes before linearized reading' (duration: 429.363273ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:29.841398Z","caller":"traceutil/trace.go:171","msg":"trace[677217361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"433.679853ms","start":"2026-04-02T14:03:29.4077Z","end":"2026-04-02T14:03:29.841379Z","steps":["trace[677217361] 'agreement among raft nodes before linearized reading' (duration: 433.336385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:29.841465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:29.411973Z","time spent":"429.486875ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T14:03:29.841749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:29.407658Z","time spent":"433.813366ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T14:03:30.025268Z","caller":"traceutil/trace.go:171","msg":"trace[1132151026] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"180.720026ms","start":"2026-04-02T14:03:29.844519Z","end":"2026-04-02T14:03:30.025239Z","steps":["trace[1132151026] 'process raft request' (duration: 163.321091ms)","trace[1132151026] 'compare' (duration: 17.288133ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:03:30.05561Z","caller":"traceutil/trace.go:171","msg":"trace[1474773001] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"210.460709ms","start":"2026-04-02T14:03:29.845131Z","end":"2026-04-02T14:03:30.055592Z","steps":["trace[1474773001] 'process raft request' (duration: 210.431898ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:30.055839Z","caller":"traceutil/trace.go:171","msg":"trace[877819965] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"210.627702ms","start":"2026-04-02T14:03:29.845122Z","end":"2026-04-02T14:03:30.05575Z","steps":["trace[877819965] 'process raft request' (duration: 210.337616ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:31.600463Z","caller":"traceutil/trace.go:171","msg":"trace[1372518097] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"375.751856ms","start":"2026-04-02T14:03:31.224689Z","end":"2026-04-02T14:03:31.600441Z","steps":["trace[1372518097] 'read index received' (duration: 375.546901ms)","trace[1372518097] 'applied index is now lower than readState.Index' (duration: 204.085µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:03:31.600724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.012642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:03:31.600753Z","caller":"traceutil/trace.go:171","msg":"trace[1496359784] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"410.700908ms","start":"2026-04-02T14:03:31.190023Z","end":"2026-04-02T14:03:31.600724Z","steps":["trace[1496359784] 'process raft request' (duration: 410.279959ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:31.600784Z","caller":"traceutil/trace.go:171","msg":"trace[442703694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"376.103024ms","start":"2026-04-02T14:03:31.224668Z","end":"2026-04-02T14:03:31.600771Z","steps":["trace[442703694] 'agreement among raft nodes before linearized reading' (duration: 375.95238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:31.600787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.365963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T14:03:31.600835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:31.224659Z","time spent":"376.166575ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T14:03:31.60086Z","caller":"traceutil/trace.go:171","msg":"trace[1529029736] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1320; }","duration":"154.463405ms","start":"2026-04-02T14:03:31.446382Z","end":"2026-04-02T14:03:31.600845Z","steps":["trace[1529029736] 'agreement among raft nodes before linearized reading' (duration: 154.307662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:31.600891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:31.190005Z","time spent":"410.815682ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:03:32.170365Z","caller":"traceutil/trace.go:171","msg":"trace[677268740] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"141.876242ms","start":"2026-04-02T14:03:32.028462Z","end":"2026-04-02T14:03:32.170338Z","steps":["trace[677268740] 'read index received' (duration: 141.667387ms)","trace[677268740] 'applied index is now lower than readState.Index' (duration: 207.695µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:03:32.170406Z","caller":"traceutil/trace.go:171","msg":"trace[243058574] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"169.147107ms","start":"2026-04-02T14:03:32.00123Z","end":"2026-04-02T14:03:32.170377Z","steps":["trace[243058574] 'process raft request' (duration: 168.969592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:32.170603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.127478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T14:03:32.170603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.335031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T14:03:32.170604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.440843ms","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-04-02T14:03:32.170635Z","caller":"traceutil/trace.go:171","msg":"trace[822113709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"142.212469ms","start":"2026-04-02T14:03:32.028413Z","end":"2026-04-02T14:03:32.170625Z","steps":["trace[822113709] 'agreement among raft nodes before linearized reading' (duration: 142.042595ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:32.170652Z","caller":"traceutil/trace.go:171","msg":"trace[389947293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"111.428784ms","start":"2026-04-02T14:03:32.059208Z","end":"2026-04-02T14:03:32.170637Z","steps":["trace[389947293] 'agreement among raft nodes before linearized reading' (duration: 111.297861ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:32.170661Z","caller":"traceutil/trace.go:171","msg":"trace[186480867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"111.541176ms","start":"2026-04-02T14:03:32.059105Z","end":"2026-04-02T14:03:32.170647Z","steps":["trace[186480867] 'agreement among raft nodes before linearized reading' (duration: 111.382032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:32.632181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.890931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T14:03:32.632298Z","caller":"traceutil/trace.go:171","msg":"trace[174774190] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"457.422006ms","start":"2026-04-02T14:03:32.174856Z","end":"2026-04-02T14:03:32.632278Z","steps":["trace[174774190] 'process raft request' (duration: 245.34386ms)","trace[174774190] 'compare' (duration: 211.685817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:03:32.632361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:32.174831Z","time spent":"457.501258ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:03:32.721122Z","caller":"traceutil/trace.go:171","msg":"trace[1456350018] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1375; }","duration":"495.077493ms","start":"2026-04-02T14:03:32.226021Z","end":"2026-04-02T14:03:32.721098Z","steps":["trace[1456350018] 'read index received' (duration: 194.19372ms)","trace[1456350018] 'applied index is now lower than readState.Index' (duration: 300.882893ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:03:32.721275Z","caller":"traceutil/trace.go:171","msg":"trace[1124563670] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"545.865074ms","start":"2026-04-02T14:03:32.175371Z","end":"2026-04-02T14:03:32.721236Z","steps":["trace[1124563670] 'process raft request' (duration: 545.69199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:32.721403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:32.175333Z","time spent":"545.979577ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:03:32.721569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.549264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T14:03:32.721621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.707714ms","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-04-02T14:03:32.721625Z","caller":"traceutil/trace.go:171","msg":"trace[1070600536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"495.650426ms","start":"2026-04-02T14:03:32.225961Z","end":"2026-04-02T14:03:32.721612Z","steps":["trace[1070600536] 'agreement among raft nodes before linearized reading' (duration: 495.579394ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:32.721656Z","caller":"traceutil/trace.go:171","msg":"trace[1213826177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1325; }","duration":"427.781916ms","start":"2026-04-02T14:03:32.293866Z","end":"2026-04-02T14:03:32.721648Z","steps":["trace[1213826177] 'agreement among raft nodes before linearized reading' (duration: 427.643942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:32.721664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:32.225942Z","time spent":"495.714237ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:03:32.721676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:32.29384Z","time spent":"427.831787ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T14:03:32.72139Z","caller":"traceutil/trace.go:171","msg":"trace[27336812] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"546.030989ms","start":"2026-04-02T14:03:32.175319Z","end":"2026-04-02T14:03:32.72135Z","steps":["trace[27336812] 'process raft request' (duration: 545.624979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:32.723923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:32.175309Z","time spent":"548.313912ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:03:38.965658Z","caller":"traceutil/trace.go:171","msg":"trace[1771620748] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"217.99276ms","start":"2026-04-02T14:03:38.747621Z","end":"2026-04-02T14:03:38.965614Z","steps":["trace[1771620748] 'read index received' (duration: 217.626981ms)","trace[1771620748] 'applied index is now lower than readState.Index' (duration: 363.819µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:03:38.965918Z","caller":"traceutil/trace.go:171","msg":"trace[645698488] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"306.025068ms","start":"2026-04-02T14:03:38.659878Z","end":"2026-04-02T14:03:38.965903Z","steps":["trace[645698488] 'process raft request' (duration: 305.503616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:38.966138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.334666ms","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":"warn","ts":"2026-04-02T14:03:38.966187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.895981ms","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-04-02T14:03:38.966241Z","caller":"traceutil/trace.go:171","msg":"trace[1500617744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1378; }","duration":"199.988703ms","start":"2026-04-02T14:03:38.766238Z","end":"2026-04-02T14:03:38.966227Z","steps":["trace[1500617744] 'agreement among raft nodes before linearized reading' (duration: 199.87232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:38.966231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:38.659852Z","time spent":"306.199953ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:03:38.966258Z","caller":"traceutil/trace.go:171","msg":"trace[2033829254] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1378; }","duration":"205.440779ms","start":"2026-04-02T14:03:38.760773Z","end":"2026-04-02T14:03:38.966214Z","steps":["trace[2033829254] 'agreement among raft nodes before linearized reading' (duration: 205.257674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:38.966342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.377218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-02T14:03:38.966424Z","caller":"traceutil/trace.go:171","msg":"trace[1330338728] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1378; }","duration":"215.517832ms","start":"2026-04-02T14:03:38.750888Z","end":"2026-04-02T14:03:38.966406Z","steps":["trace[1330338728] 'agreement among raft nodes before linearized reading' (duration: 215.234835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:38.966268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.45129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T14:03:38.966486Z","caller":"traceutil/trace.go:171","msg":"trace[1489926823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1378; }","duration":"215.690186ms","start":"2026-04-02T14:03:38.750788Z","end":"2026-04-02T14:03:38.966478Z","steps":["trace[1489926823] 'agreement among raft nodes before linearized reading' (duration: 215.242275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:38.966446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.830149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T14:03:38.966605Z","caller":"traceutil/trace.go:171","msg":"trace[649343440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1378; }","duration":"219.033303ms","start":"2026-04-02T14:03:38.747562Z","end":"2026-04-02T14:03:38.966595Z","steps":["trace[649343440] 'agreement among raft nodes before linearized reading' (duration: 218.44936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:39.653412Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807350126737849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T14:03:40.910291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:40.574037Z","time spent":"336.247019ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T14:03:40.949404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.726927ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T14:03:40.949652Z","caller":"traceutil/trace.go:171","msg":"trace[477261540] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1436; }","duration":"1.796897843s","start":"2026-04-02T14:03:39.152737Z","end":"2026-04-02T14:03:40.949635Z","steps":["trace[477261540] 'read index received' (duration: 837.94426ms)","trace[477261540] 'applied index is now lower than readState.Index' (duration: 958.952503ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:03:40.949794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.797022045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:03:40.949832Z","caller":"traceutil/trace.go:171","msg":"trace[873649821] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1383; }","duration":"1.797137068s","start":"2026-04-02T14:03:39.152683Z","end":"2026-04-02T14:03:40.94982Z","steps":["trace[873649821] 'agreement among raft nodes before linearized reading' (duration: 1.797038756s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:40.949873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:39.152656Z","time spent":"1.79720613s","remote":"127.0.0.1:35336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-02T14:03:40.950027Z","caller":"traceutil/trace.go:171","msg":"trace[672644373] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"1.319822932s","start":"2026-04-02T14:03:39.630083Z","end":"2026-04-02T14:03:40.949906Z","steps":["trace[672644373] 'process raft request' (duration: 1.319435413s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:40.950069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.462574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-02T14:03:40.950184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195759658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-02T14:03:40.95021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697807027s","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:533"} {"level":"info","ts":"2026-04-02T14:03:40.950217Z","caller":"traceutil/trace.go:171","msg":"trace[1037078930] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1383; }","duration":"298.670399ms","start":"2026-04-02T14:03:40.651522Z","end":"2026-04-02T14:03:40.950192Z","steps":["trace[1037078930] 'agreement among raft nodes before linearized reading' (duration: 298.406042ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:40.95023Z","caller":"traceutil/trace.go:171","msg":"trace[1661507371] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1383; }","duration":"1.195839199s","start":"2026-04-02T14:03:39.75438Z","end":"2026-04-02T14:03:40.950219Z","steps":["trace[1661507371] 'agreement among raft nodes before linearized reading' (duration: 1.195727337s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:40.950267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:39.754361Z","time spent":"1.195899891s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-02T14:03:40.950045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029343183s","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-04-02T14:03:40.950372Z","caller":"traceutil/trace.go:171","msg":"trace[2071940033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1383; }","duration":"1.029697051s","start":"2026-04-02T14:03:39.920662Z","end":"2026-04-02T14:03:40.950359Z","steps":["trace[2071940033] 'agreement among raft nodes before linearized reading' (duration: 1.029261191s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:40.950088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158534486s","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":"warn","ts":"2026-04-02T14:03:40.950428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:39.920646Z","time spent":"1.029765703s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T14:03:40.950429Z","caller":"traceutil/trace.go:171","msg":"trace[766417103] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1383; }","duration":"1.158879964s","start":"2026-04-02T14:03:39.791533Z","end":"2026-04-02T14:03:40.950413Z","steps":["trace[766417103] 'agreement among raft nodes before linearized reading' (duration: 1.158495555s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:40.950053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.892951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:03:40.950616Z","caller":"traceutil/trace.go:171","msg":"trace[1746354748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1383; }","duration":"725.491955ms","start":"2026-04-02T14:03:40.225113Z","end":"2026-04-02T14:03:40.950605Z","steps":["trace[1746354748] 'agreement among raft nodes before linearized reading' (duration: 724.896281ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:03:40.950298Z","caller":"traceutil/trace.go:171","msg":"trace[989351381] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1383; }","duration":"1.69795526s","start":"2026-04-02T14:03:39.252322Z","end":"2026-04-02T14:03:40.950277Z","steps":["trace[989351381] 'agreement among raft nodes before linearized reading' (duration: 1.697541751s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:03:40.950653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:40.225093Z","time spent":"725.548217ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:03:40.950977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:39.630056Z","time spent":"1.320045826s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:03:40.950998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:39.2523Z","time spent":"1.69837378s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-02T14:03:40.950598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:03:39.791475Z","time spent":"1.159084798s","remote":"127.0.0.1:55332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-04-02T14:04:03.310313Z","caller":"traceutil/trace.go:171","msg":"trace[1644163700] linearizableReadLoop","detail":"{readStateIndex:1550; appliedIndex:1549; }","duration":"105.200917ms","start":"2026-04-02T14:04:03.205085Z","end":"2026-04-02T14:04:03.310286Z","steps":["trace[1644163700] 'read index received' (duration: 104.88902ms)","trace[1644163700] 'applied index is now lower than readState.Index' (duration: 310.167µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:04:03.310509Z","caller":"traceutil/trace.go:171","msg":"trace[1770991891] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"106.805784ms","start":"2026-04-02T14:04:03.203683Z","end":"2026-04-02T14:04:03.310489Z","steps":["trace[1770991891] 'process raft request' (duration: 106.419155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:04:03.310856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.991703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T14:04:03.310895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.773501ms","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-04-02T14:04:03.310935Z","caller":"traceutil/trace.go:171","msg":"trace[369243047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1491; }","duration":"105.110465ms","start":"2026-04-02T14:04:03.205805Z","end":"2026-04-02T14:04:03.310915Z","steps":["trace[369243047] 'agreement among raft nodes before linearized reading' (duration: 104.851729ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:04:03.310985Z","caller":"traceutil/trace.go:171","msg":"trace[1317399374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1491; }","duration":"105.954235ms","start":"2026-04-02T14:04:03.205005Z","end":"2026-04-02T14:04:03.310959Z","steps":["trace[1317399374] 'agreement among raft nodes before linearized reading' (duration: 105.680879ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:04:27.553268Z","caller":"traceutil/trace.go:171","msg":"trace[181775065] linearizableReadLoop","detail":"{readStateIndex:1666; appliedIndex:1664; }","duration":"114.212377ms","start":"2026-04-02T14:04:27.439031Z","end":"2026-04-02T14:04:27.553244Z","steps":["trace[181775065] 'read index received' (duration: 83.247089ms)","trace[181775065] 'applied index is now lower than readState.Index' (duration: 30.964348ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:04:27.553394Z","caller":"traceutil/trace.go:171","msg":"trace[326976406] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"128.709623ms","start":"2026-04-02T14:04:27.424663Z","end":"2026-04-02T14:04:27.553372Z","steps":["trace[326976406] 'process raft request' (duration: 97.620252ms)","trace[326976406] 'compare' (duration: 30.737443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:04:27.553471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.416771ms","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-04-02T14:04:27.553505Z","caller":"traceutil/trace.go:171","msg":"trace[1566194710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1602; }","duration":"114.479003ms","start":"2026-04-02T14:04:27.439015Z","end":"2026-04-02T14:04:27.553494Z","steps":["trace[1566194710] 'agreement among raft nodes before linearized reading' (duration: 114.338499ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:04:27.553593Z","caller":"traceutil/trace.go:171","msg":"trace[1341294803] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"126.120993ms","start":"2026-04-02T14:04:27.427463Z","end":"2026-04-02T14:04:27.553584Z","steps":["trace[1341294803] 'process raft request' (duration: 125.737584ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:04:31.516304Z","caller":"traceutil/trace.go:171","msg":"trace[397927714] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"148.577771ms","start":"2026-04-02T14:04:31.36769Z","end":"2026-04-02T14:04:31.516268Z","steps":["trace[397927714] 'process raft request' (duration: 66.412158ms)","trace[397927714] 'compare' (duration: 82.01564ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:05:00.118175Z","caller":"traceutil/trace.go:171","msg":"trace[2025215141] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"331.186901ms","start":"2026-04-02T14:04:59.786961Z","end":"2026-04-02T14:05:00.118148Z","steps":["trace[2025215141] 'process raft request' (duration: 328.717443ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:05:00.118218Z","caller":"traceutil/trace.go:171","msg":"trace[12175021] linearizableReadLoop","detail":"{readStateIndex:1821; appliedIndex:1820; }","duration":"311.657516ms","start":"2026-04-02T14:04:59.806431Z","end":"2026-04-02T14:05:00.118088Z","steps":["trace[12175021] 'read index received' (duration: 309.315162ms)","trace[12175021] 'applied index is now lower than readState.Index' (duration: 2.340704ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:05:00.118339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:04:59.786937Z","time spent":"331.331273ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:05:00.118392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.947593ms","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":"warn","ts":"2026-04-02T14:05:00.118449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.567196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:05:00.118462Z","caller":"traceutil/trace.go:171","msg":"trace[1606606571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1750; }","duration":"312.060106ms","start":"2026-04-02T14:04:59.806384Z","end":"2026-04-02T14:05:00.118444Z","steps":["trace[1606606571] 'agreement among raft nodes before linearized reading' (duration: 311.855091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:00.118509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:04:59.806372Z","time spent":"312.126717ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T14:05:00.118511Z","caller":"traceutil/trace.go:171","msg":"trace[1983562282] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1750; }","duration":"269.685749ms","start":"2026-04-02T14:04:59.8488Z","end":"2026-04-02T14:05:00.118486Z","steps":["trace[1983562282] 'agreement among raft nodes before linearized reading' (duration: 269.566266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:02.186863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807350126738774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T14:05:02.686998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807350126738774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T14:05:03.187798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807350126738774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T14:05:03.688474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807350126738774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T14:05:03.97354Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.341472701s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T14:05:04.035965Z","caller":"traceutil/trace.go:171","msg":"trace[1232434242] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"2.403954506s","start":"2026-04-02T14:05:01.631987Z","end":"2026-04-02T14:05:04.035941Z","steps":["trace[1232434242] 'process raft request' (duration: 2.341704647s)","trace[1232434242] 'compare' (duration: 61.933072ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:05:04.037268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:01.631966Z","time spent":"2.40408784s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:05:04.062262Z","caller":"traceutil/trace.go:171","msg":"trace[436679032] linearizableReadLoop","detail":"{readStateIndex:1829; appliedIndex:1826; }","duration":"2.376276133s","start":"2026-04-02T14:05:01.685962Z","end":"2026-04-02T14:05:04.062238Z","steps":["trace[436679032] 'read index received' (duration: 2.287737861s)","trace[436679032] 'applied index is now lower than readState.Index' (duration: 88.537442ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:05:04.062328Z","caller":"traceutil/trace.go:171","msg":"trace[484881439] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"1.564145146s","start":"2026-04-02T14:05:02.498146Z","end":"2026-04-02T14:05:04.062291Z","steps":["trace[484881439] 'process raft request' (duration: 1.563666715s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:04.062487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.341307858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T14:05:04.062497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:02.498128Z","time spent":"1.564286109s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:05:04.062487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.376509308s","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-04-02T14:05:04.062572Z","caller":"traceutil/trace.go:171","msg":"trace[1139148514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1758; }","duration":"2.37660132s","start":"2026-04-02T14:05:01.685956Z","end":"2026-04-02T14:05:04.062558Z","steps":["trace[1139148514] 'agreement among raft nodes before linearized reading' (duration: 2.376393915s)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:05:04.062671Z","caller":"traceutil/trace.go:171","msg":"trace[721018370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1758; }","duration":"2.341537813s","start":"2026-04-02T14:05:01.721119Z","end":"2026-04-02T14:05:04.062657Z","steps":["trace[721018370] 'agreement among raft nodes before linearized reading' (duration: 2.341245587s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:04.06268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.498562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T14:05:04.062695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.12524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-04-02T14:05:04.062695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:01.685906Z","time spent":"2.376776414s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T14:05:04.062733Z","caller":"traceutil/trace.go:171","msg":"trace[1676415544] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1758; }","duration":"369.595885ms","start":"2026-04-02T14:05:03.693123Z","end":"2026-04-02T14:05:04.062719Z","steps":["trace[1676415544] 'agreement among raft nodes before linearized reading' (duration: 369.503123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:04.062717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:01.7211Z","time spent":"2.341604965s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T14:05:04.062723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.179663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T14:05:04.062768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.903354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-02T14:05:04.062765Z","caller":"traceutil/trace.go:171","msg":"trace[1853578115] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:1758; }","duration":"123.255022ms","start":"2026-04-02T14:05:03.939486Z","end":"2026-04-02T14:05:04.062741Z","steps":["trace[1853578115] 'agreement among raft nodes before linearized reading' (duration: 122.921895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:04.062776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:03.693105Z","time spent":"369.661597ms","remote":"127.0.0.1:52970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-02T14:05:04.062796Z","caller":"traceutil/trace.go:171","msg":"trace[555016942] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1758; }","duration":"417.286835ms","start":"2026-04-02T14:05:03.645498Z","end":"2026-04-02T14:05:04.062785Z","steps":["trace[555016942] 'agreement among raft nodes before linearized reading' (duration: 417.197623ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:05:04.062811Z","caller":"traceutil/trace.go:171","msg":"trace[1843565590] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1758; }","duration":"988.977105ms","start":"2026-04-02T14:05:03.073817Z","end":"2026-04-02T14:05:04.062794Z","steps":["trace[1843565590] 'agreement among raft nodes before linearized reading' (duration: 988.836001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:04.062819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:03.645477Z","time spent":"417.335256ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-02T14:05:04.062674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.238458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-02T14:05:04.062842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:03.073797Z","time spent":"989.038697ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-02T14:05:04.062852Z","caller":"traceutil/trace.go:171","msg":"trace[1614068366] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1758; }","duration":"528.488074ms","start":"2026-04-02T14:05:03.534353Z","end":"2026-04-02T14:05:04.062841Z","steps":["trace[1614068366] 'agreement among raft nodes before linearized reading' (duration: 528.040433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:04.062857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.933218278s","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":"warn","ts":"2026-04-02T14:05:04.062891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:03.534346Z","time spent":"528.532686ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-02T14:05:04.062905Z","caller":"traceutil/trace.go:171","msg":"trace[611460342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1758; }","duration":"1.933306999s","start":"2026-04-02T14:05:02.129584Z","end":"2026-04-02T14:05:04.062891Z","steps":["trace[611460342] 'agreement among raft nodes before linearized reading' (duration: 1.933174116s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:04.062923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.356035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T14:05:04.062929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.940353093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T14:05:04.062937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:02.129563Z","time spent":"1.933366171s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T14:05:04.062949Z","caller":"traceutil/trace.go:171","msg":"trace[1569376508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1758; }","duration":"837.413216ms","start":"2026-04-02T14:05:03.225528Z","end":"2026-04-02T14:05:04.062941Z","steps":["trace[1569376508] 'agreement among raft nodes before linearized reading' (duration: 837.377755ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:05:04.06296Z","caller":"traceutil/trace.go:171","msg":"trace[269778200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1758; }","duration":"1.940420335s","start":"2026-04-02T14:05:02.122531Z","end":"2026-04-02T14:05:04.062951Z","steps":["trace[269778200] 'agreement among raft nodes before linearized reading' (duration: 1.940295312s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:04.062973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:03.225508Z","time spent":"837.460248ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T14:05:04.062989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:02.122511Z","time spent":"1.940470606s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T14:05:04.06234Z","caller":"traceutil/trace.go:171","msg":"trace[1868740017] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"619.233177ms","start":"2026-04-02T14:05:03.443071Z","end":"2026-04-02T14:05:04.062305Z","steps":["trace[1868740017] 'process raft request' (duration: 619.121115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:05:04.063072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:05:03.443051Z","time spent":"619.964564ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T14:05:06.538914Z","caller":"traceutil/trace.go:171","msg":"trace[1217038752] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"250.43552ms","start":"2026-04-02T14:05:06.287825Z","end":"2026-04-02T14:05:06.538261Z","steps":["trace[1217038752] 'process raft request' (duration: 163.020395ms)","trace[1217038752] 'compare' (duration: 86.900063ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:05:10.210484Z","caller":"traceutil/trace.go:171","msg":"trace[767319985] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"105.744952ms","start":"2026-04-02T14:05:10.104712Z","end":"2026-04-02T14:05:10.210457Z","steps":["trace[767319985] 'process raft request' (duration: 105.598288ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:05:10.517929Z","caller":"traceutil/trace.go:171","msg":"trace[57249397] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"174.371319ms","start":"2026-04-02T14:05:10.343521Z","end":"2026-04-02T14:05:10.517892Z","steps":["trace[57249397] 'process raft request' (duration: 144.79362ms)","trace[57249397] 'compare' (duration: 29.288502ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T14:05:10.518593Z","caller":"traceutil/trace.go:171","msg":"trace[1250787498] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"174.208366ms","start":"2026-04-02T14:05:10.344345Z","end":"2026-04-02T14:05:10.518554Z","steps":["trace[1250787498] 'process raft request' (duration: 173.496509ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:05:10.518648Z","caller":"traceutil/trace.go:171","msg":"trace[1824993637] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"173.689444ms","start":"2026-04-02T14:05:10.344926Z","end":"2026-04-02T14:05:10.518615Z","steps":["trace[1824993637] 'process raft request' (duration: 173.619302ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:05:10.519049Z","caller":"traceutil/trace.go:171","msg":"trace[579452999] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"174.41725ms","start":"2026-04-02T14:05:10.344599Z","end":"2026-04-02T14:05:10.519016Z","steps":["trace[579452999] 'process raft request' (duration: 173.792576ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:05:12.529758Z","caller":"traceutil/trace.go:171","msg":"trace[1875958035] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"142.738704ms","start":"2026-04-02T14:05:12.386996Z","end":"2026-04-02T14:05:12.529735Z","steps":["trace[1875958035] 'process raft request' (duration: 142.57796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:06:54.316551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.05737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T14:06:54.316584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.96891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-02T14:06:54.316707Z","caller":"traceutil/trace.go:171","msg":"trace[1243886797] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:2292; }","duration":"370.136723ms","start":"2026-04-02T14:06:53.946545Z","end":"2026-04-02T14:06:54.316681Z","steps":["trace[1243886797] 'range keys from in-memory index tree' (duration: 369.793355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:06:54.316768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:06:53.946517Z","time spent":"370.237026ms","remote":"127.0.0.1:50614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.6\" "} {"level":"info","ts":"2026-04-02T14:06:54.316647Z","caller":"traceutil/trace.go:171","msg":"trace[1339707219] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2292; }","duration":"312.197094ms","start":"2026-04-02T14:06:54.004432Z","end":"2026-04-02T14:06:54.316629Z","steps":["trace[1339707219] 'count revisions from in-memory index tree' (duration: 311.915567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:06:54.316915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:06:54.004416Z","time spent":"312.47059ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-04-02T14:06:55.416953Z","caller":"traceutil/trace.go:171","msg":"trace[1119283028] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"488.181984ms","start":"2026-04-02T14:06:54.928738Z","end":"2026-04-02T14:06:55.41692Z","steps":["trace[1119283028] 'process raft request' (duration: 447.785203ms)","trace[1119283028] 'compare' (duration: 40.288959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:06:55.417097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:06:54.928716Z","time spent":"488.307097ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T14:06:55.417269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.389945ms","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-04-02T14:06:55.417331Z","caller":"traceutil/trace.go:171","msg":"trace[1352610008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2296; }","duration":"390.494758ms","start":"2026-04-02T14:06:55.026821Z","end":"2026-04-02T14:06:55.417316Z","steps":["trace[1352610008] 'agreement among raft nodes before linearized reading' (duration: 390.336574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:06:55.41737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T14:06:55.026803Z","time spent":"390.55928ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T14:06:55.416941Z","caller":"traceutil/trace.go:171","msg":"trace[205258929] linearizableReadLoop","detail":"{readStateIndex:2391; appliedIndex:2390; }","duration":"390.030617ms","start":"2026-04-02T14:06:55.02687Z","end":"2026-04-02T14:06:55.416901Z","steps":["trace[205258929] 'read index received' (duration: 349.521433ms)","trace[205258929] 'applied index is now lower than readState.Index' (duration: 40.508284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T14:06:55.417926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.974318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-02T14:06:55.417971Z","caller":"traceutil/trace.go:171","msg":"trace[1658199999] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2296; }","duration":"209.05672ms","start":"2026-04-02T14:06:55.2089Z","end":"2026-04-02T14:06:55.417957Z","steps":["trace[1658199999] 'agreement among raft nodes before linearized reading' (duration: 208.945778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:06:55.417995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.619954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-02T14:06:55.418049Z","caller":"traceutil/trace.go:171","msg":"trace[932785651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2296; }","duration":"109.700045ms","start":"2026-04-02T14:06:55.308336Z","end":"2026-04-02T14:06:55.418036Z","steps":["trace[932785651] 'agreement among raft nodes before linearized reading' (duration: 109.518441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:06:55.418167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.809828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-02T14:06:55.418204Z","caller":"traceutil/trace.go:171","msg":"trace[985115500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2296; }","duration":"109.89003ms","start":"2026-04-02T14:06:55.308305Z","end":"2026-04-02T14:06:55.418195Z","steps":["trace[985115500] 'agreement among raft nodes before linearized reading' (duration: 109.795898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:06:55.418272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.85649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-02T14:06:55.418309Z","caller":"traceutil/trace.go:171","msg":"trace[31123648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2296; }","duration":"109.913091ms","start":"2026-04-02T14:06:55.308387Z","end":"2026-04-02T14:06:55.4183Z","steps":["trace[31123648] 'agreement among raft nodes before linearized reading' (duration: 109.813588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T14:06:55.418381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.68176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-02T14:06:55.41848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.834296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T14:06:55.418481Z","caller":"traceutil/trace.go:171","msg":"trace[1453881226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2296; }","duration":"153.820694ms","start":"2026-04-02T14:06:55.264651Z","end":"2026-04-02T14:06:55.418472Z","steps":["trace[1453881226] 'agreement among raft nodes before linearized reading' (duration: 153.66804ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:06:55.418845Z","caller":"traceutil/trace.go:171","msg":"trace[1083584086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2296; }","duration":"193.903697ms","start":"2026-04-02T14:06:55.2246Z","end":"2026-04-02T14:06:55.418503Z","steps":["trace[1083584086] 'agreement among raft nodes before linearized reading' (duration: 193.851466ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:06:57.666062Z","caller":"traceutil/trace.go:171","msg":"trace[2130807446] transaction","detail":"{read_only:false; response_revision:2312; number_of_response:1; }","duration":"168.787943ms","start":"2026-04-02T14:06:57.497249Z","end":"2026-04-02T14:06:57.666037Z","steps":["trace[2130807446] 'process raft request' (duration: 168.612339ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:06:57.666364Z","caller":"traceutil/trace.go:171","msg":"trace[286373413] transaction","detail":"{read_only:false; response_revision:2313; number_of_response:1; }","duration":"169.08016ms","start":"2026-04-02T14:06:57.497256Z","end":"2026-04-02T14:06:57.666336Z","steps":["trace[286373413] 'process raft request' (duration: 168.737752ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:06:57.666831Z","caller":"traceutil/trace.go:171","msg":"trace[1646854410] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"169.551611ms","start":"2026-04-02T14:06:57.497267Z","end":"2026-04-02T14:06:57.666819Z","steps":["trace[1646854410] 'process raft request' (duration: 168.938727ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:06:57.667181Z","caller":"traceutil/trace.go:171","msg":"trace[25142657] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"169.870347ms","start":"2026-04-02T14:06:57.49728Z","end":"2026-04-02T14:06:57.66715Z","steps":["trace[25142657] 'process raft request' (duration: 169.050958ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T14:06:57.667957Z","caller":"traceutil/trace.go:171","msg":"trace[691081595] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"170.592725ms","start":"2026-04-02T14:06:57.497347Z","end":"2026-04-02T14:06:57.66794Z","steps":["trace[691081595] 'process raft request' (duration: 170.477852ms)"],"step_count":1}