{"level":"warn","ts":"2026-04-23T07:44:03.615139Z","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-23T07:44:03.615368Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-23T07:44:03.61548Z","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-23T07:44:03.615502Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-23T07:44:03.61553Z","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-23T07:44:03.616449Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-04-23T07:44:03.616562Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-23T07:44:03.620711Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.774352ms"} {"level":"info","ts":"2026-04-23T07:44:03.632864Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-04-23T07:44:03.632941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T07:44:03.632961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-04-23T07:44:03.632969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T07:44:03.632978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-04-23T07:44:03.633009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-04-23T07:44:03.654528Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T07:44:03.657111Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T07:44:03.659086Z","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-23T07:44:03.663521Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T07:44:03.663886Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T07:44:03.664068Z","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-23T07:44:03.664166Z","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-23T07:44:03.664175Z","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-23T07:44:03.669578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-04-23T07:44:03.670353Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-23T07:44:03.672355Z","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-23T07:44:03.672491Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-23T07:44:03.672511Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-23T07:44:03.672722Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T07:44:03.672767Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T07:44:04.533861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T07:44:04.533924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T07:44:04.53396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-04-23T07:44:04.533982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-04-23T07:44:04.533994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-23T07:44:04.53401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-04-23T07:44:04.534024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-23T07:44:04.535259Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:44:04.536029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T07:44:04.536084Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T07:44:04.536032Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T07:44:04.536524Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T07:44:04.536546Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T07:44:04.539168Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T07:44:04.539535Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-04-23T07:44:04.545255Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:44:04.545428Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:44:04.545477Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-23T07:44:07.312863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828721684,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:07.81366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828721684,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:08.314014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828721684,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:08.342064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.531108948s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T07:44:08.342492Z","caller":"traceutil/trace.go:171","msg":"trace[2003081866] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"1.530524053s","start":"2026-04-23T07:44:06.811944Z","end":"2026-04-23T07:44:08.342468Z","steps":["trace[2003081866] 'read index received' (duration: 1.530277647s)","trace[2003081866] 'applied index is now lower than readState.Index' (duration: 245.386µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:08.342536Z","caller":"traceutil/trace.go:171","msg":"trace[702955753] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"1.531655721s","start":"2026-04-23T07:44:06.810853Z","end":"2026-04-23T07:44:08.342508Z","steps":["trace[702955753] 'process raft request' (duration: 1.531437056s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.342759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430144539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-23T07:44:08.342725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530755449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T07:44:08.342824Z","caller":"traceutil/trace.go:171","msg":"trace[2080635620] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:112; }","duration":"1.530907742s","start":"2026-04-23T07:44:06.811895Z","end":"2026-04-23T07:44:08.342803Z","steps":["trace[2080635620] 'agreement among raft nodes before linearized reading' (duration: 1.530731898s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.342865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:06.811871Z","time spent":"1.530985544s","remote":"127.0.0.1:35892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"info","ts":"2026-04-23T07:44:08.342807Z","caller":"traceutil/trace.go:171","msg":"trace[877154162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"1.430243131s","start":"2026-04-23T07:44:06.912551Z","end":"2026-04-23T07:44:08.342795Z","steps":["trace[877154162] 'agreement among raft nodes before linearized reading' (duration: 1.430089767s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.343195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:06.912537Z","time spent":"1.430643812s","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:44:08.343269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:06.810838Z","time spent":"1.531737763s","remote":"127.0.0.1:35906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T07:44:08.342946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.196586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T07:44:08.343788Z","caller":"traceutil/trace.go:171","msg":"trace[416423624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"186.157398ms","start":"2026-04-23T07:44:08.157591Z","end":"2026-04-23T07:44:08.343748Z","steps":["trace[416423624] 'agreement among raft nodes before linearized reading' (duration: 185.196755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:12.134398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828722037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:12.635373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828722037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:13.136431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828722037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:13.63696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828722037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:13.959319Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.334202237s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T07:44:13.978656Z","caller":"traceutil/trace.go:171","msg":"trace[175198179] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"2.353639023s","start":"2026-04-23T07:44:11.624984Z","end":"2026-04-23T07:44:13.978623Z","steps":["trace[175198179] 'process raft request' (duration: 2.334789591s)","trace[175198179] 'compare' (duration: 18.681858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:44:13.978805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:11.624967Z","time spent":"2.353765046s","remote":"127.0.0.1:35644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:44:13.990731Z","caller":"traceutil/trace.go:171","msg":"trace[48891418] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:296; }","duration":"2.357472168s","start":"2026-04-23T07:44:11.633237Z","end":"2026-04-23T07:44:13.990709Z","steps":["trace[48891418] 'read index received' (duration: 2.326481677s)","trace[48891418] 'applied index is now lower than readState.Index' (duration: 30.989811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:44:13.990858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.357596092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:44:13.990894Z","caller":"traceutil/trace.go:171","msg":"trace[1955454028] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:291; }","duration":"2.357664663s","start":"2026-04-23T07:44:11.633222Z","end":"2026-04-23T07:44:13.990887Z","steps":["trace[1955454028] 'agreement among raft nodes before linearized reading' (duration: 2.357591251s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.990923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:11.633209Z","time spent":"2.357708014s","remote":"127.0.0.1:35762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-23T07:44:13.990919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260234541s","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-23T07:44:13.990965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458804943s","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-23T07:44:13.991023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.250723748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-23T07:44:13.991042Z","caller":"traceutil/trace.go:171","msg":"trace[1692573850] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:291; }","duration":"2.250753839s","start":"2026-04-23T07:44:11.740285Z","end":"2026-04-23T07:44:13.991039Z","steps":["trace[1692573850] 'agreement among raft nodes before linearized reading' (duration: 2.250711088s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.991061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:11.740275Z","time spent":"2.250782509s","remote":"127.0.0.1:35756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-23T07:44:13.991016Z","caller":"traceutil/trace.go:171","msg":"trace[1921712606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:291; }","duration":"1.458888665s","start":"2026-04-23T07:44:12.532113Z","end":"2026-04-23T07:44:13.991001Z","steps":["trace[1921712606] 'agreement among raft nodes before linearized reading' (duration: 1.458808203s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.991209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.532092Z","time spent":"1.459077929s","remote":"127.0.0.1:35808","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-23T07:44:13.990982Z","caller":"traceutil/trace.go:171","msg":"trace[519899016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:291; }","duration":"2.260333135s","start":"2026-04-23T07:44:11.730629Z","end":"2026-04-23T07:44:13.990962Z","steps":["trace[519899016] 'agreement among raft nodes before linearized reading' (duration: 2.260222552s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.991267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:11.730615Z","time spent":"2.260638202s","remote":"127.0.0.1:35808","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":"info","ts":"2026-04-23T07:44:13.991405Z","caller":"traceutil/trace.go:171","msg":"trace[1272931642] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"2.248147056s","start":"2026-04-23T07:44:11.742608Z","end":"2026-04-23T07:44:13.990755Z","steps":["trace[1272931642] 'process raft request' (duration: 2.248080373s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.991497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:11.742593Z","time spent":"2.248852042s","remote":"127.0.0.1:35756","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-23T07:44:13.991505Z","caller":"traceutil/trace.go:171","msg":"trace[121300498] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"2.363553876s","start":"2026-04-23T07:44:11.627937Z","end":"2026-04-23T07:44:13.991491Z","steps":["trace[121300498] 'process raft request' (duration: 2.362645534s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.991707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:11.627915Z","time spent":"2.363614978s","remote":"127.0.0.1:35808","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-23T07:44:13.992267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.060567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:44:13.99231Z","caller":"traceutil/trace.go:171","msg":"trace[1898372676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:291; }","duration":"840.144879ms","start":"2026-04-23T07:44:13.152154Z","end":"2026-04-23T07:44:13.992298Z","steps":["trace[1898372676] 'agreement among raft nodes before linearized reading' (duration: 838.831667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.992338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:13.152128Z","time spent":"840.204ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T07:44:14.3764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.806723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:44:14.376502Z","caller":"traceutil/trace.go:171","msg":"trace[1910086220] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"189.104711ms","start":"2026-04-23T07:44:14.187385Z","end":"2026-04-23T07:44:14.37649Z","steps":["trace[1910086220] 'read index received' (duration: 39.991881ms)","trace[1910086220] 'applied index is now lower than readState.Index' (duration: 149.112ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:44:14.376571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.180173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-23T07:44:14.376589Z","caller":"traceutil/trace.go:171","msg":"trace[1280547096] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:303; }","duration":"189.233124ms","start":"2026-04-23T07:44:14.187351Z","end":"2026-04-23T07:44:14.376584Z","steps":["trace[1280547096] 'agreement among raft nodes before linearized reading' (duration: 189.169793ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:14.37663Z","caller":"traceutil/trace.go:171","msg":"trace[382164179] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"247.048103ms","start":"2026-04-23T07:44:14.12948Z","end":"2026-04-23T07:44:14.376528Z","steps":["trace[382164179] 'process raft request' (duration: 97.940243ms)","trace[382164179] 'compare' (duration: 148.66703ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:44:14.88532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828722065,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:15.653853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.660254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:44:15.653962Z","caller":"traceutil/trace.go:171","msg":"trace[727699799] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"1.269182479s","start":"2026-04-23T07:44:14.384762Z","end":"2026-04-23T07:44:15.653945Z","steps":["trace[727699799] 'read index received' (duration: 511.260928ms)","trace[727699799] 'applied index is now lower than readState.Index' (duration: 757.920161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:44:15.654044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269278461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:44:15.654071Z","caller":"traceutil/trace.go:171","msg":"trace[1588017694] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:304; }","duration":"1.269340253s","start":"2026-04-23T07:44:14.38472Z","end":"2026-04-23T07:44:15.654061Z","steps":["trace[1588017694] 'agreement among raft nodes before linearized reading' (duration: 1.269269951s)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:15.654034Z","caller":"traceutil/trace.go:171","msg":"trace[524471855] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"1.269767453s","start":"2026-04-23T07:44:14.384231Z","end":"2026-04-23T07:44:15.653998Z","steps":["trace[524471855] 'process raft request' (duration: 511.857673ms)","trace[524471855] 'compare' (duration: 757.50434ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:44:15.654104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:14.3847Z","time spent":"1.269396704s","remote":"127.0.0.1:35762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-04-23T07:44:15.654224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:14.38421Z","time spent":"1.269906007s","remote":"127.0.0.1:35756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:44:15.82894Z","caller":"traceutil/trace.go:171","msg":"trace[1686049207] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"161.76366ms","start":"2026-04-23T07:44:15.667115Z","end":"2026-04-23T07:44:15.828878Z","steps":["trace[1686049207] 'process raft request' (duration: 138.604382ms)","trace[1686049207] 'compare' (duration: 20.951464ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:16.04617Z","caller":"traceutil/trace.go:171","msg":"trace[46263014] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"196.78709ms","start":"2026-04-23T07:44:15.849358Z","end":"2026-04-23T07:44:16.046145Z","steps":["trace[46263014] 'read index received' (duration: 189.673166ms)","trace[46263014] 'applied index is now lower than readState.Index' (duration: 7.113094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:44:16.04643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.045767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:44:16.046512Z","caller":"traceutil/trace.go:171","msg":"trace[527134502] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:307; }","duration":"197.20233ms","start":"2026-04-23T07:44:15.849286Z","end":"2026-04-23T07:44:16.046488Z","steps":["trace[527134502] 'agreement among raft nodes before linearized reading' (duration: 197.073467ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:16.0467Z","caller":"traceutil/trace.go:171","msg":"trace[932981450] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"206.899088ms","start":"2026-04-23T07:44:15.839751Z","end":"2026-04-23T07:44:16.04665Z","steps":["trace[932981450] 'process raft request' (duration: 199.385994ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:37.370222Z","caller":"traceutil/trace.go:171","msg":"trace[1658142525] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"673.412007ms","start":"2026-04-23T07:44:36.696787Z","end":"2026-04-23T07:44:37.370199Z","steps":["trace[1658142525] 'process raft request' (duration: 672.960016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:37.370362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:36.69677Z","time spent":"673.520249ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:37.74763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.586418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:44:37.747744Z","caller":"traceutil/trace.go:171","msg":"trace[1878386171] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:519; }","duration":"265.768172ms","start":"2026-04-23T07:44:37.481953Z","end":"2026-04-23T07:44:37.747721Z","steps":["trace[1878386171] 'range keys from in-memory index tree' (duration: 265.459164ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.166952Z","caller":"traceutil/trace.go:171","msg":"trace[2103460136] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"230.447836ms","start":"2026-04-23T07:44:37.93648Z","end":"2026-04-23T07:44:38.166928Z","steps":["trace[2103460136] 'process raft request' (duration: 230.260462ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.174115Z","caller":"traceutil/trace.go:171","msg":"trace[1688705085] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"231.300847ms","start":"2026-04-23T07:44:37.942762Z","end":"2026-04-23T07:44:38.174063Z","steps":["trace[1688705085] 'process raft request' (duration: 231.127413ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.174184Z","caller":"traceutil/trace.go:171","msg":"trace[2063488377] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"221.284841ms","start":"2026-04-23T07:44:37.952892Z","end":"2026-04-23T07:44:38.174177Z","steps":["trace[2063488377] 'process raft request' (duration: 221.039745ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.1743Z","caller":"traceutil/trace.go:171","msg":"trace[265772951] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"215.385676ms","start":"2026-04-23T07:44:37.958908Z","end":"2026-04-23T07:44:38.174294Z","steps":["trace[265772951] 'process raft request' (duration: 215.13058ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.174375Z","caller":"traceutil/trace.go:171","msg":"trace[318692825] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"231.662015ms","start":"2026-04-23T07:44:37.942707Z","end":"2026-04-23T07:44:38.174369Z","steps":["trace[318692825] 'process raft request' (duration: 231.081071ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.174499Z","caller":"traceutil/trace.go:171","msg":"trace[2116173792] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"185.322678ms","start":"2026-04-23T07:44:37.98917Z","end":"2026-04-23T07:44:38.174493Z","steps":["trace[2116173792] 'process raft request' (duration: 185.149614ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.174505Z","caller":"traceutil/trace.go:171","msg":"trace[1271677394] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"170.231787ms","start":"2026-04-23T07:44:38.004263Z","end":"2026-04-23T07:44:38.174495Z","steps":["trace[1271677394] 'process raft request' (duration: 170.112105ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.174604Z","caller":"traceutil/trace.go:171","msg":"trace[38417406] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"210.474535ms","start":"2026-04-23T07:44:37.964083Z","end":"2026-04-23T07:44:38.174558Z","steps":["trace[38417406] 'process raft request' (duration: 210.048215ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.174656Z","caller":"traceutil/trace.go:171","msg":"trace[26611209] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"197.994509ms","start":"2026-04-23T07:44:37.976656Z","end":"2026-04-23T07:44:38.174651Z","steps":["trace[26611209] 'process raft request' (duration: 197.549449ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.174139Z","caller":"traceutil/trace.go:171","msg":"trace[1932544836] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"215.259893ms","start":"2026-04-23T07:44:37.958865Z","end":"2026-04-23T07:44:38.174124Z","steps":["trace[1932544836] 'process raft request' (duration: 215.14266ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.174126Z","caller":"traceutil/trace.go:171","msg":"trace[1533353582] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"217.214711ms","start":"2026-04-23T07:44:37.95688Z","end":"2026-04-23T07:44:38.174095Z","steps":["trace[1533353582] 'process raft request' (duration: 217.090808ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.319862Z","caller":"traceutil/trace.go:171","msg":"trace[980401274] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"121.725987ms","start":"2026-04-23T07:44:38.198111Z","end":"2026-04-23T07:44:38.319837Z","steps":["trace[980401274] 'process raft request' (duration: 121.506682ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.42058Z","caller":"traceutil/trace.go:171","msg":"trace[1344863588] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"222.439699ms","start":"2026-04-23T07:44:38.198102Z","end":"2026-04-23T07:44:38.420542Z","steps":["trace[1344863588] 'process raft request' (duration: 222.271245ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.420673Z","caller":"traceutil/trace.go:171","msg":"trace[1273876718] transaction","detail":"{read_only:false; number_of_response:0; response_revision:544; }","duration":"221.792133ms","start":"2026-04-23T07:44:38.198854Z","end":"2026-04-23T07:44:38.420646Z","steps":["trace[1273876718] 'process raft request' (duration: 221.645689ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.420817Z","caller":"traceutil/trace.go:171","msg":"trace[1305579461] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"219.822705ms","start":"2026-04-23T07:44:38.200963Z","end":"2026-04-23T07:44:38.420786Z","steps":["trace[1305579461] 'process raft request' (duration: 219.558259ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.420856Z","caller":"traceutil/trace.go:171","msg":"trace[236874572] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"168.832452ms","start":"2026-04-23T07:44:38.252013Z","end":"2026-04-23T07:44:38.420845Z","steps":["trace[236874572] 'process raft request' (duration: 168.804532ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.420966Z","caller":"traceutil/trace.go:171","msg":"trace[1676545398] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"214.545215ms","start":"2026-04-23T07:44:38.206407Z","end":"2026-04-23T07:44:38.420952Z","steps":["trace[1676545398] 'process raft request' (duration: 214.161976ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.420953Z","caller":"traceutil/trace.go:171","msg":"trace[1149788627] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"190.381972ms","start":"2026-04-23T07:44:38.230525Z","end":"2026-04-23T07:44:38.420907Z","steps":["trace[1149788627] 'process raft request' (duration: 190.133316ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.421005Z","caller":"traceutil/trace.go:171","msg":"trace[1734649370] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"177.458236ms","start":"2026-04-23T07:44:38.243539Z","end":"2026-04-23T07:44:38.420997Z","steps":["trace[1734649370] 'process raft request' (duration: 177.24325ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:38.420972Z","caller":"traceutil/trace.go:171","msg":"trace[1618011924] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"190.3141ms","start":"2026-04-23T07:44:38.230649Z","end":"2026-04-23T07:44:38.420963Z","steps":["trace[1618011924] 'process raft request' (duration: 190.079284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:38.922928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828722538,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:44:39.287005Z","caller":"traceutil/trace.go:171","msg":"trace[1930447411] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"945.745531ms","start":"2026-04-23T07:44:38.341193Z","end":"2026-04-23T07:44:39.286939Z","steps":["trace[1930447411] 'process raft request' (duration: 927.175725ms)","trace[1930447411] 'compare' (duration: 18.229387ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:39.28712Z","caller":"traceutil/trace.go:171","msg":"trace[1659149577] transaction","detail":"{read_only:false; number_of_response:1; response_revision:551; }","duration":"855.105286ms","start":"2026-04-23T07:44:38.43198Z","end":"2026-04-23T07:44:39.287086Z","steps":["trace[1659149577] 'process raft request' (duration: 854.907832ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:39.287137Z","caller":"traceutil/trace.go:171","msg":"trace[1953368987] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"850.076263ms","start":"2026-04-23T07:44:38.437042Z","end":"2026-04-23T07:44:39.287118Z","steps":["trace[1953368987] 'process raft request' (duration: 849.93692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:39.28716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:38.341164Z","time spent":"945.906995ms","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:39.287277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:38.437028Z","time spent":"850.173005ms","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:39.28731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:38.431959Z","time spent":"855.24469ms","remote":"127.0.0.1:35616","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-23T07:44:39.287387Z","caller":"traceutil/trace.go:171","msg":"trace[45351151] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"850.276008ms","start":"2026-04-23T07:44:38.437097Z","end":"2026-04-23T07:44:39.287373Z","steps":["trace[45351151] 'process raft request' (duration: 849.968191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:39.28751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:38.437093Z","time spent":"850.379991ms","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:44:39.287557Z","caller":"traceutil/trace.go:171","msg":"trace[1679635231] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"848.455003ms","start":"2026-04-23T07:44:38.439063Z","end":"2026-04-23T07:44:39.287518Z","steps":["trace[1679635231] 'process raft request' (duration: 848.226627ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:39.287608Z","caller":"traceutil/trace.go:171","msg":"trace[862423588] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:566; }","duration":"865.760257ms","start":"2026-04-23T07:44:38.421835Z","end":"2026-04-23T07:44:39.287595Z","steps":["trace[862423588] 'read index received' (duration: 846.547897ms)","trace[862423588] 'applied index is now lower than readState.Index' (duration: 19.21186ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:39.287615Z","caller":"traceutil/trace.go:171","msg":"trace[1899582204] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"807.424296ms","start":"2026-04-23T07:44:38.480181Z","end":"2026-04-23T07:44:39.287606Z","steps":["trace[1899582204] 'process raft request' (duration: 807.18813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:39.287654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:38.439044Z","time spent":"848.562796ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:44:39.287682Z","caller":"traceutil/trace.go:171","msg":"trace[10136415] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"796.486027ms","start":"2026-04-23T07:44:38.49119Z","end":"2026-04-23T07:44:39.287676Z","steps":["trace[10136415] 'process raft request' (duration: 796.367135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:39.287684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:38.48016Z","time spent":"807.497238ms","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:39.287725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:38.491165Z","time spent":"796.533448ms","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T07:44:39.287834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.997895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-23T07:44:39.287857Z","caller":"traceutil/trace.go:171","msg":"trace[907777222] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:556; }","duration":"866.019405ms","start":"2026-04-23T07:44:38.421829Z","end":"2026-04-23T07:44:39.287849Z","steps":["trace[907777222] 'agreement among raft nodes before linearized reading' (duration: 865.960673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:39.287879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:38.421813Z","time spent":"866.061456ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1036,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-23T07:44:39.287867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.439948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T07:44:39.287919Z","caller":"traceutil/trace.go:171","msg":"trace[361283317] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:556; }","duration":"173.52971ms","start":"2026-04-23T07:44:39.114375Z","end":"2026-04-23T07:44:39.287904Z","steps":["trace[361283317] 'agreement among raft nodes before linearized reading' (duration: 173.401406ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:40.87861Z","caller":"traceutil/trace.go:171","msg":"trace[914743738] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"341.759757ms","start":"2026-04-23T07:44:40.536823Z","end":"2026-04-23T07:44:40.878583Z","steps":["trace[914743738] 'process raft request' (duration: 341.369958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:40.878769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:40.536791Z","time spent":"341.89376ms","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:40.879073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.442098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:208485"} {"level":"info","ts":"2026-04-23T07:44:40.878427Z","caller":"traceutil/trace.go:171","msg":"trace[986069226] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"333.781642ms","start":"2026-04-23T07:44:40.544628Z","end":"2026-04-23T07:44:40.87841Z","steps":["trace[986069226] 'read index received' (duration: 333.425303ms)","trace[986069226] 'applied index is now lower than readState.Index' (duration: 355.829µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:40.879775Z","caller":"traceutil/trace.go:171","msg":"trace[137981342] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:603; }","duration":"334.52863ms","start":"2026-04-23T07:44:40.544577Z","end":"2026-04-23T07:44:40.879106Z","steps":["trace[137981342] 'agreement among raft nodes before linearized reading' (duration: 334.400917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:40.879819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:40.544562Z","time spent":"335.246317ms","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":208508,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-23T07:44:46.334754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828722712,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:44:46.465127Z","caller":"traceutil/trace.go:171","msg":"trace[7688732] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"630.934345ms","start":"2026-04-23T07:44:45.834165Z","end":"2026-04-23T07:44:46.465099Z","steps":["trace[7688732] 'read index received' (duration: 629.777936ms)","trace[7688732] 'applied index is now lower than readState.Index' (duration: 1.155028ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:46.465162Z","caller":"traceutil/trace.go:171","msg":"trace[777691634] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"685.990855ms","start":"2026-04-23T07:44:45.779133Z","end":"2026-04-23T07:44:46.465124Z","steps":["trace[777691634] 'process raft request' (duration: 685.208967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.465429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.231843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xt9lw\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-23T07:44:46.465524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.807628ms","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-23T07:44:46.465567Z","caller":"traceutil/trace.go:171","msg":"trace[1009690912] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xt9lw; range_end:; response_count:1; response_revision:632; }","duration":"631.391287ms","start":"2026-04-23T07:44:45.834136Z","end":"2026-04-23T07:44:46.465527Z","steps":["trace[1009690912] 'agreement among raft nodes before linearized reading' (duration: 631.084779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.465681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.016632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:44:46.465712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:45.834067Z","time spent":"631.633863ms","remote":"127.0.0.1:35756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-xt9lw\" "} {"level":"info","ts":"2026-04-23T07:44:46.465716Z","caller":"traceutil/trace.go:171","msg":"trace[1405125219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"106.073493ms","start":"2026-04-23T07:44:46.359633Z","end":"2026-04-23T07:44:46.465707Z","steps":["trace[1405125219] 'agreement among raft nodes before linearized reading' (duration: 106.004471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.46573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.878971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-23T07:44:46.4658Z","caller":"traceutil/trace.go:171","msg":"trace[224175072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"222.998434ms","start":"2026-04-23T07:44:46.242781Z","end":"2026-04-23T07:44:46.46578Z","steps":["trace[224175072] 'agreement among raft nodes before linearized reading' (duration: 222.825399ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:46.465601Z","caller":"traceutil/trace.go:171","msg":"trace[896424877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"224.966032ms","start":"2026-04-23T07:44:46.240619Z","end":"2026-04-23T07:44:46.465585Z","steps":["trace[896424877] 'agreement among raft nodes before linearized reading' (duration: 224.689255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.465812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:45.779099Z","time spent":"686.292833ms","remote":"127.0.0.1:35674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:46.465871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.564252ms","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-23T07:44:46.466Z","caller":"traceutil/trace.go:171","msg":"trace[903908531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"222.744836ms","start":"2026-04-23T07:44:46.243227Z","end":"2026-04-23T07:44:46.465972Z","steps":["trace[903908531] 'agreement among raft nodes before linearized reading' (duration: 222.345357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.465607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.172033ms","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-23T07:44:46.466116Z","caller":"traceutil/trace.go:171","msg":"trace[170433339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"222.670344ms","start":"2026-04-23T07:44:46.243401Z","end":"2026-04-23T07:44:46.466071Z","steps":["trace[170433339] 'agreement among raft nodes before linearized reading' (duration: 222.06908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.95944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.84831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:44:46.959664Z","caller":"traceutil/trace.go:171","msg":"trace[531282793] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"487.834232ms","start":"2026-04-23T07:44:46.471804Z","end":"2026-04-23T07:44:46.959638Z","steps":["trace[531282793] 'process raft request' (duration: 123.675545ms)","trace[531282793] 'compare' (duration: 363.509471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:44:46.959799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:46.471782Z","time spent":"487.942915ms","remote":"127.0.0.1:35808","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-23T07:44:46.959828Z","caller":"traceutil/trace.go:171","msg":"trace[1843107131] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"425.438032ms","start":"2026-04-23T07:44:46.534367Z","end":"2026-04-23T07:44:46.959805Z","steps":["trace[1843107131] 'process raft request' (duration: 425.395371ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:46.959816Z","caller":"traceutil/trace.go:171","msg":"trace[1132426558] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"487.396142ms","start":"2026-04-23T07:44:46.472361Z","end":"2026-04-23T07:44:46.959757Z","steps":["trace[1132426558] 'process raft request' (duration: 487.202077ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:46.959905Z","caller":"traceutil/trace.go:171","msg":"trace[1936690367] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"473.325616ms","start":"2026-04-23T07:44:46.486571Z","end":"2026-04-23T07:44:46.959897Z","steps":["trace[1936690367] 'process raft request' (duration: 473.132192ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:46.959852Z","caller":"traceutil/trace.go:171","msg":"trace[1204321481] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"486.620373ms","start":"2026-04-23T07:44:46.473163Z","end":"2026-04-23T07:44:46.959783Z","steps":["trace[1204321481] 'process raft request' (duration: 486.461819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.959945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:46.486533Z","time spent":"473.393118ms","remote":"127.0.0.1:35756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:46.960063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:46.472346Z","time spent":"487.581457ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:44:46.960075Z","caller":"traceutil/trace.go:171","msg":"trace[1745234006] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"486.775797ms","start":"2026-04-23T07:44:46.473262Z","end":"2026-04-23T07:44:46.960037Z","steps":["trace[1745234006] 'process raft request' (duration: 486.399808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.959901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:46.534333Z","time spent":"425.539155ms","remote":"127.0.0.1:35790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T07:44:46.960143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:46.473138Z","time spent":"486.839298ms","remote":"127.0.0.1:35808","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-23T07:44:46.961984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:46.473251Z","time spent":"486.895269ms","remote":"127.0.0.1:35808","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-23T07:44:48.858595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828722725,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:48.921031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.942472633s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-23T07:44:48.931319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:47.650172Z","time spent":"1.281141582s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T07:44:48.931731Z","caller":"traceutil/trace.go:171","msg":"trace[459529591] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.953251338s","start":"2026-04-23T07:44:46.978437Z","end":"2026-04-23T07:44:48.931688Z","steps":["trace[459529591] 'process raft request' (duration: 1.942863233s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.931882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:46.97842Z","time spent":"1.953384391s","remote":"127.0.0.1:35756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:44:48.95684Z","caller":"traceutil/trace.go:171","msg":"trace[1169730551] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"599.059592ms","start":"2026-04-23T07:44:48.357758Z","end":"2026-04-23T07:44:48.956817Z","steps":["trace[1169730551] 'read index received' (duration: 563.563291ms)","trace[1169730551] 'applied index is now lower than readState.Index' (duration: 35.495611ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:48.956979Z","caller":"traceutil/trace.go:171","msg":"trace[1679330240] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.322594799s","start":"2026-04-23T07:44:47.63436Z","end":"2026-04-23T07:44:48.956955Z","steps":["trace[1679330240] 'process raft request' (duration: 1.322352334s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.956986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.218697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:44:48.957037Z","caller":"traceutil/trace.go:171","msg":"trace[328463467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"599.317048ms","start":"2026-04-23T07:44:48.35771Z","end":"2026-04-23T07:44:48.957027Z","steps":["trace[328463467] 'agreement among raft nodes before linearized reading' (duration: 599.234296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.957059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:48.35769Z","time spent":"599.36407ms","remote":"127.0.0.1:35548","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-23T07:44:48.957105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:47.634335Z","time spent":"1.322696381s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:44:52.441472Z","caller":"traceutil/trace.go:171","msg":"trace[1347576229] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"117.243037ms","start":"2026-04-23T07:44:52.324197Z","end":"2026-04-23T07:44:52.44144Z","steps":["trace[1347576229] 'read index received' (duration: 117.047012ms)","trace[1347576229] 'applied index is now lower than readState.Index' (duration: 194.985µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:52.441564Z","caller":"traceutil/trace.go:171","msg":"trace[343985232] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"315.586856ms","start":"2026-04-23T07:44:52.125931Z","end":"2026-04-23T07:44:52.441518Z","steps":["trace[343985232] 'process raft request' (duration: 315.39036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:52.441691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.477353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:44:52.441988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:52.125899Z","time spent":"315.867802ms","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:44:52.442146Z","caller":"traceutil/trace.go:171","msg":"trace[698476546] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:685; }","duration":"117.814651ms","start":"2026-04-23T07:44:52.324124Z","end":"2026-04-23T07:44:52.441938Z","steps":["trace[698476546] 'agreement among raft nodes before linearized reading' (duration: 117.443273ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:45:02.34693Z","caller":"traceutil/trace.go:171","msg":"trace[1711288149] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"142.637391ms","start":"2026-04-23T07:45:02.204269Z","end":"2026-04-23T07:45:02.346906Z","steps":["trace[1711288149] 'process raft request' (duration: 141.871202ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:45:05.674802Z","caller":"traceutil/trace.go:171","msg":"trace[1476952311] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"500.375749ms","start":"2026-04-23T07:45:05.174398Z","end":"2026-04-23T07:45:05.674773Z","steps":["trace[1476952311] 'read index received' (duration: 499.23658ms)","trace[1476952311] 'applied index is now lower than readState.Index' (duration: 1.137599ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:45:05.674933Z","caller":"traceutil/trace.go:171","msg":"trace[1399664510] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"725.397628ms","start":"2026-04-23T07:45:04.949456Z","end":"2026-04-23T07:45:05.674853Z","steps":["trace[1399664510] 'process raft request' (duration: 725.107301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:45:05.67525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:45:04.949422Z","time spent":"725.663685ms","remote":"127.0.0.1:35756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:45:05.862946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.359883ms","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-23T07:45:05.863102Z","caller":"traceutil/trace.go:171","msg":"trace[1799503597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"672.526367ms","start":"2026-04-23T07:45:05.190537Z","end":"2026-04-23T07:45:05.863063Z","steps":["trace[1799503597] 'agreement among raft nodes before linearized reading' (duration: 484.482657ms)","trace[1799503597] 'range keys from in-memory index tree' (duration: 187.72029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:45:05.863087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.163897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-23T07:45:05.863156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:45:05.190502Z","time spent":"672.64193ms","remote":"127.0.0.1:35808","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-23T07:45:05.863164Z","caller":"traceutil/trace.go:171","msg":"trace[281022119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"676.28071ms","start":"2026-04-23T07:45:05.186861Z","end":"2026-04-23T07:45:05.863142Z","steps":["trace[281022119] 'agreement among raft nodes before linearized reading' (duration: 488.115467ms)","trace[281022119] 'range keys from in-memory index tree' (duration: 188.008988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:45:05.863203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:45:05.186837Z","time spent":"676.357372ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T07:45:05.863266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.473564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:45:05.863367Z","caller":"traceutil/trace.go:171","msg":"trace[1489695684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"503.645968ms","start":"2026-04-23T07:45:05.359691Z","end":"2026-04-23T07:45:05.863337Z","steps":["trace[1489695684] 'agreement among raft nodes before linearized reading' (duration: 315.307672ms)","trace[1489695684] 'range keys from in-memory index tree' (duration: 188.151582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:45:05.863345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.91157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T07:45:05.863421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:45:05.359668Z","time spent":"503.742712ms","remote":"127.0.0.1:35548","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-23T07:45:05.863484Z","caller":"traceutil/trace.go:171","msg":"trace[1973444866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"689.113825ms","start":"2026-04-23T07:45:05.174332Z","end":"2026-04-23T07:45:05.863445Z","steps":["trace[1973444866] 'agreement among raft nodes before linearized reading' (duration: 500.566214ms)","trace[1973444866] 'range keys from in-memory index tree' (duration: 188.208053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:45:05.863545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:45:05.174305Z","time spent":"689.229308ms","remote":"127.0.0.1:35808","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-23T07:45:05.863763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.69357ms","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-23T07:45:05.863884Z","caller":"traceutil/trace.go:171","msg":"trace[1440569137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"672.869214ms","start":"2026-04-23T07:45:05.190982Z","end":"2026-04-23T07:45:05.863852Z","steps":["trace[1440569137] 'agreement among raft nodes before linearized reading' (duration: 484.026076ms)","trace[1440569137] 'range keys from in-memory index tree' (duration: 187.844545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:45:05.863941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:45:05.190965Z","time spent":"672.964787ms","remote":"127.0.0.1:35808","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-23T07:45:49.494665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.986882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:45:49.494756Z","caller":"traceutil/trace.go:171","msg":"trace[8680777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"137.127306ms","start":"2026-04-23T07:45:49.35761Z","end":"2026-04-23T07:45:49.494738Z","steps":["trace[8680777] 'range keys from in-memory index tree' (duration: 136.90476ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:45:49.621196Z","caller":"traceutil/trace.go:171","msg":"trace[1826108091] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"105.559058ms","start":"2026-04-23T07:45:49.515606Z","end":"2026-04-23T07:45:49.621165Z","steps":["trace[1826108091] 'process raft request' (duration: 105.356104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:04.389743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828723772,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:46:04.769226Z","caller":"traceutil/trace.go:171","msg":"trace[1787148637] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"960.26115ms","start":"2026-04-23T07:46:03.808937Z","end":"2026-04-23T07:46:04.769198Z","steps":["trace[1787148637] 'process raft request' (duration: 960.103836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:04.769395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:03.808912Z","time spent":"960.388773ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:46:05.001086Z","caller":"traceutil/trace.go:171","msg":"trace[866714898] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"784.529664ms","start":"2026-04-23T07:46:04.216519Z","end":"2026-04-23T07:46:05.001048Z","steps":["trace[866714898] 'process raft request' (duration: 771.675797ms)","trace[866714898] 'compare' (duration: 12.550899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:05.001268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:04.216493Z","time spent":"784.680568ms","remote":"127.0.0.1:35808","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-23T07:46:05.087283Z","caller":"traceutil/trace.go:171","msg":"trace[1866793999] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1133; }","duration":"1.197989423s","start":"2026-04-23T07:46:03.889252Z","end":"2026-04-23T07:46:05.087242Z","steps":["trace[1866793999] 'read index received' (duration: 879.79806ms)","trace[1866793999] 'applied index is now lower than readState.Index' (duration: 318.188933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:05.087463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198171377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:05.087548Z","caller":"traceutil/trace.go:171","msg":"trace[156788332] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1098; }","duration":"1.198320071s","start":"2026-04-23T07:46:03.889203Z","end":"2026-04-23T07:46:05.087523Z","steps":["trace[156788332] 'agreement among raft nodes before linearized reading' (duration: 1.198147696s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:05.087858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.633739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-23T07:46:05.087918Z","caller":"traceutil/trace.go:171","msg":"trace[1941796756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"858.712461ms","start":"2026-04-23T07:46:04.229189Z","end":"2026-04-23T07:46:05.087902Z","steps":["trace[1941796756] 'agreement among raft nodes before linearized reading' (duration: 858.591408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:05.08793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:03.889177Z","time spent":"1.198685s","remote":"127.0.0.1:35738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:46:05.087968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:04.22916Z","time spent":"858.796993ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T07:46:05.087533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.971399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:46:05.088075Z","caller":"traceutil/trace.go:171","msg":"trace[1956220024] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1098; }","duration":"989.581033ms","start":"2026-04-23T07:46:04.098471Z","end":"2026-04-23T07:46:05.088052Z","steps":["trace[1956220024] 'agreement among raft nodes before linearized reading' (duration: 988.963267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:05.088134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:04.098445Z","time spent":"989.669034ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:46:05.087642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.753958ms","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-23T07:46:05.088249Z","caller":"traceutil/trace.go:171","msg":"trace[1307682656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"867.398144ms","start":"2026-04-23T07:46:04.220826Z","end":"2026-04-23T07:46:05.088224Z","steps":["trace[1307682656] 'agreement among raft nodes before linearized reading' (duration: 866.610734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:05.087689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.099617ms","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-23T07:46:05.088381Z","caller":"traceutil/trace.go:171","msg":"trace[808059053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"867.811914ms","start":"2026-04-23T07:46:04.220558Z","end":"2026-04-23T07:46:05.088369Z","steps":["trace[808059053] 'agreement among raft nodes before linearized reading' (duration: 867.030736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:05.088423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:04.220539Z","time spent":"867.869046ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T07:46:05.087756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.561259ms","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-23T07:46:05.088531Z","caller":"traceutil/trace.go:171","msg":"trace[213318112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"814.349429ms","start":"2026-04-23T07:46:04.274161Z","end":"2026-04-23T07:46:05.088511Z","steps":["trace[213318112] 'agreement among raft nodes before linearized reading' (duration: 813.516108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:05.08776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.193719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T07:46:05.088624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:04.274149Z","time spent":"814.460511ms","remote":"127.0.0.1:35808","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-23T07:46:05.088666Z","caller":"traceutil/trace.go:171","msg":"trace[461634462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"922.114962ms","start":"2026-04-23T07:46:04.166524Z","end":"2026-04-23T07:46:05.088639Z","steps":["trace[461634462] 'agreement among raft nodes before linearized reading' (duration: 921.183999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:05.088456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:04.220813Z","time spent":"867.479895ms","remote":"127.0.0.1:35808","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-23T07:46:05.088767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:04.166508Z","time spent":"922.228094ms","remote":"127.0.0.1:35808","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-23T07:46:09.172035Z","caller":"traceutil/trace.go:171","msg":"trace[2094441999] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"150.689785ms","start":"2026-04-23T07:46:09.02132Z","end":"2026-04-23T07:46:09.17201Z","steps":["trace[2094441999] 'process raft request' (duration: 150.275926ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:09.287491Z","caller":"traceutil/trace.go:171","msg":"trace[928383539] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"110.663543ms","start":"2026-04-23T07:46:09.176802Z","end":"2026-04-23T07:46:09.287466Z","steps":["trace[928383539] 'process raft request' (duration: 45.17729ms)","trace[928383539] 'compare' (duration: 65.304038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:14.467568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.748766ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T07:46:14.467702Z","caller":"traceutil/trace.go:171","msg":"trace[534165819] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"107.997536ms","start":"2026-04-23T07:46:14.359683Z","end":"2026-04-23T07:46:14.467681Z","steps":["trace[534165819] 'read index received' (duration: 41.051µs)","trace[534165819] 'applied index is now lower than readState.Index' (duration: 107.954805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:14.467794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.10654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:14.467836Z","caller":"traceutil/trace.go:171","msg":"trace[1515397159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1129; }","duration":"108.193082ms","start":"2026-04-23T07:46:14.359633Z","end":"2026-04-23T07:46:14.467826Z","steps":["trace[1515397159] 'agreement among raft nodes before linearized reading' (duration: 108.11325ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:14.975326Z","caller":"traceutil/trace.go:171","msg":"trace[1179156803] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"139.609341ms","start":"2026-04-23T07:46:14.835693Z","end":"2026-04-23T07:46:14.975302Z","steps":["trace[1179156803] 'process raft request' (duration: 139.401166ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:20.806902Z","caller":"traceutil/trace.go:171","msg":"trace[1371407903] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"448.156128ms","start":"2026-04-23T07:46:20.358727Z","end":"2026-04-23T07:46:20.806883Z","steps":["trace[1371407903] 'read index received' (duration: 447.823199ms)","trace[1371407903] 'applied index is now lower than readState.Index' (duration: 331.739µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:20.807024Z","caller":"traceutil/trace.go:171","msg":"trace[1922001162] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"626.016416ms","start":"2026-04-23T07:46:20.180975Z","end":"2026-04-23T07:46:20.806991Z","steps":["trace[1922001162] 'process raft request' (duration: 625.791151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:20.807097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.338372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:20.807162Z","caller":"traceutil/trace.go:171","msg":"trace[248971474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"448.469805ms","start":"2026-04-23T07:46:20.358683Z","end":"2026-04-23T07:46:20.807153Z","steps":["trace[248971474] 'agreement among raft nodes before linearized reading' (duration: 448.2763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:20.807176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:20.180942Z","time spent":"626.16098ms","remote":"127.0.0.1:35808","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-23T07:46:20.807189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:20.358663Z","time spent":"448.519106ms","remote":"127.0.0.1:35548","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-23T07:46:21.394629Z","caller":"traceutil/trace.go:171","msg":"trace[2051564175] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"181.345084ms","start":"2026-04-23T07:46:21.212718Z","end":"2026-04-23T07:46:21.394063Z","steps":["trace[2051564175] 'process raft request' (duration: 181.16289ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:23.849178Z","caller":"traceutil/trace.go:171","msg":"trace[962624074] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"445.8303ms","start":"2026-04-23T07:46:23.403329Z","end":"2026-04-23T07:46:23.849159Z","steps":["trace[962624074] 'read index received' (duration: 445.669396ms)","trace[962624074] 'applied index is now lower than readState.Index' (duration: 160.384µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:23.849293Z","caller":"traceutil/trace.go:171","msg":"trace[129275018] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"449.623623ms","start":"2026-04-23T07:46:23.399636Z","end":"2026-04-23T07:46:23.84926Z","steps":["trace[129275018] 'process raft request' (duration: 449.414228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:23.849383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.039345ms","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-23T07:46:23.849459Z","caller":"traceutil/trace.go:171","msg":"trace[253576789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1208; }","duration":"446.159838ms","start":"2026-04-23T07:46:23.403285Z","end":"2026-04-23T07:46:23.849445Z","steps":["trace[253576789] 'agreement among raft nodes before linearized reading' (duration: 445.976583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:23.849474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:23.399623Z","time spent":"449.748348ms","remote":"127.0.0.1:35808","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-23T07:46:23.849506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:23.403265Z","time spent":"446.22573ms","remote":"127.0.0.1:35808","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-23T07:46:23.849884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.303027ms","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-23T07:46:23.849884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.67687ms","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-23T07:46:23.849933Z","caller":"traceutil/trace.go:171","msg":"trace[844008847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1208; }","duration":"443.385239ms","start":"2026-04-23T07:46:23.406536Z","end":"2026-04-23T07:46:23.849922Z","steps":["trace[844008847] 'agreement among raft nodes before linearized reading' (duration: 443.251886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:23.849933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.377263ms","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-23T07:46:23.849945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.490606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T07:46:23.849951Z","caller":"traceutil/trace.go:171","msg":"trace[1534915680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1208; }","duration":"444.776314ms","start":"2026-04-23T07:46:23.405152Z","end":"2026-04-23T07:46:23.849928Z","steps":["trace[1534915680] 'agreement among raft nodes before linearized reading' (duration: 444.61918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:23.849967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:23.406527Z","time spent":"443.43224ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T07:46:23.849927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.144423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:46:23.849994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:23.405132Z","time spent":"444.855166ms","remote":"127.0.0.1:35808","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":"info","ts":"2026-04-23T07:46:23.849976Z","caller":"traceutil/trace.go:171","msg":"trace[1009624586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"444.460735ms","start":"2026-04-23T07:46:23.405503Z","end":"2026-04-23T07:46:23.849964Z","steps":["trace[1009624586] 'agreement among raft nodes before linearized reading' (duration: 444.309222ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:23.850038Z","caller":"traceutil/trace.go:171","msg":"trace[1972723724] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1208; }","duration":"117.318048ms","start":"2026-04-23T07:46:23.732696Z","end":"2026-04-23T07:46:23.850015Z","steps":["trace[1972723724] 'agreement among raft nodes before linearized reading' (duration: 117.110543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:23.85006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:23.405481Z","time spent":"444.565237ms","remote":"127.0.0.1:35808","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-23T07:46:23.849988Z","caller":"traceutil/trace.go:171","msg":"trace[1835263501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1208; }","duration":"444.551878ms","start":"2026-04-23T07:46:23.405427Z","end":"2026-04-23T07:46:23.849979Z","steps":["trace[1835263501] 'agreement among raft nodes before linearized reading' (duration: 444.453366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:23.850185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:23.40542Z","time spent":"444.755533ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T07:46:38.505162Z","caller":"traceutil/trace.go:171","msg":"trace[1087635722] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"233.680811ms","start":"2026-04-23T07:46:38.271459Z","end":"2026-04-23T07:46:38.50514Z","steps":["trace[1087635722] 'process raft request' (duration: 233.553088ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:38.613984Z","caller":"traceutil/trace.go:171","msg":"trace[1682920955] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"100.868679ms","start":"2026-04-23T07:46:38.513078Z","end":"2026-04-23T07:46:38.613947Z","steps":["trace[1682920955] 'process raft request' (duration: 100.518881ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:38.613973Z","caller":"traceutil/trace.go:171","msg":"trace[1454063338] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"100.855449ms","start":"2026-04-23T07:46:38.513079Z","end":"2026-04-23T07:46:38.613934Z","steps":["trace[1454063338] 'process raft request' (duration: 100.755587ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:38.713748Z","caller":"traceutil/trace.go:171","msg":"trace[1862491795] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"197.388831ms","start":"2026-04-23T07:46:38.51629Z","end":"2026-04-23T07:46:38.713679Z","steps":["trace[1862491795] 'process raft request' (duration: 182.79833ms)","trace[1862491795] 'compare' (duration: 14.351006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:39.455239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.568043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:46:39.455352Z","caller":"traceutil/trace.go:171","msg":"trace[957975506] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1279; }","duration":"520.82059ms","start":"2026-04-23T07:46:38.934508Z","end":"2026-04-23T07:46:39.455328Z","steps":["trace[957975506] 'count revisions from in-memory index tree' (duration: 520.424898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:39.455404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:38.93447Z","time spent":"520.920671ms","remote":"127.0.0.1:35758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:46:39.455465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.047226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:46:39.455535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.077055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T07:46:39.455587Z","caller":"traceutil/trace.go:171","msg":"trace[1568696619] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1279; }","duration":"141.256ms","start":"2026-04-23T07:46:39.314303Z","end":"2026-04-23T07:46:39.455559Z","steps":["trace[1568696619] 'count revisions from in-memory index tree' (duration: 140.941473ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:39.455636Z","caller":"traceutil/trace.go:171","msg":"trace[679392078] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1279; }","duration":"335.239089ms","start":"2026-04-23T07:46:39.120376Z","end":"2026-04-23T07:46:39.455615Z","steps":["trace[679392078] 'range keys from in-memory index tree' (duration: 334.885911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:39.455684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:39.120351Z","time spent":"335.324041ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-04-23T07:46:39.881683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:39.457931Z","time spent":"423.748652ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T07:46:40.297719Z","caller":"traceutil/trace.go:171","msg":"trace[1262896648] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"414.532144ms","start":"2026-04-23T07:46:39.883108Z","end":"2026-04-23T07:46:40.29764Z","steps":["trace[1262896648] 'process raft request' (duration: 393.007571ms)","trace[1262896648] 'compare' (duration: 21.334539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:40.297955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:39.883085Z","time spent":"414.752431ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:46:40.377345Z","caller":"traceutil/trace.go:171","msg":"trace[319601783] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1327; }","duration":"456.360521ms","start":"2026-04-23T07:46:39.920962Z","end":"2026-04-23T07:46:40.377323Z","steps":["trace[319601783] 'read index received' (duration: 355.172933ms)","trace[319601783] 'applied index is now lower than readState.Index' (duration: 101.186698ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:40.377447Z","caller":"traceutil/trace.go:171","msg":"trace[993179781] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"477.845573ms","start":"2026-04-23T07:46:39.899551Z","end":"2026-04-23T07:46:40.377397Z","steps":["trace[993179781] 'process raft request' (duration: 477.636538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:40.377582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.606488ms","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-23T07:46:40.377603Z","caller":"traceutil/trace.go:171","msg":"trace[129396232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"456.66966ms","start":"2026-04-23T07:46:39.920927Z","end":"2026-04-23T07:46:40.377597Z","steps":["trace[129396232] 'agreement among raft nodes before linearized reading' (duration: 456.507605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:40.377627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:39.920917Z","time spent":"456.70467ms","remote":"127.0.0.1:35808","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-23T07:46:40.377634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:39.899525Z","time spent":"478.003147ms","remote":"127.0.0.1:35808","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-23T07:46:40.377752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.040155ms","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-23T07:46:40.377765Z","caller":"traceutil/trace.go:171","msg":"trace[915805620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"450.063926ms","start":"2026-04-23T07:46:39.927696Z","end":"2026-04-23T07:46:40.37776Z","steps":["trace[915805620] 'agreement among raft nodes before linearized reading' (duration: 450.025865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:40.377778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:39.92769Z","time spent":"450.083876ms","remote":"127.0.0.1:35808","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-23T07:46:40.377985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.250756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T07:46:40.378002Z","caller":"traceutil/trace.go:171","msg":"trace[367751488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1281; }","duration":"449.330207ms","start":"2026-04-23T07:46:39.928667Z","end":"2026-04-23T07:46:40.377997Z","steps":["trace[367751488] 'agreement among raft nodes before linearized reading' (duration: 449.282566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:40.378014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:39.928633Z","time spent":"449.378008ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T07:46:40.378016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.81098ms","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-23T07:46:40.378122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.611194ms","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-23T07:46:40.37812Z","caller":"traceutil/trace.go:171","msg":"trace[1413014156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"447.963913ms","start":"2026-04-23T07:46:39.930133Z","end":"2026-04-23T07:46:40.378097Z","steps":["trace[1413014156] 'agreement among raft nodes before linearized reading' (duration: 447.705797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:40.37817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:39.930125Z","time spent":"448.035715ms","remote":"127.0.0.1:35808","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-23T07:46:40.378139Z","caller":"traceutil/trace.go:171","msg":"trace[1349106504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"449.648654ms","start":"2026-04-23T07:46:39.928485Z","end":"2026-04-23T07:46:40.378134Z","steps":["trace[1349106504] 'agreement among raft nodes before linearized reading' (duration: 449.603813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:40.378289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.381402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:46:40.378307Z","caller":"traceutil/trace.go:171","msg":"trace[995299154] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1281; }","duration":"174.443532ms","start":"2026-04-23T07:46:40.203859Z","end":"2026-04-23T07:46:40.378303Z","steps":["trace[995299154] 'agreement among raft nodes before linearized reading' (duration: 174.406601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:40.37832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:39.928474Z","time spent":"449.817469ms","remote":"127.0.0.1:35808","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-23T07:46:40.49176Z","caller":"traceutil/trace.go:171","msg":"trace[1568284580] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"108.359536ms","start":"2026-04-23T07:46:40.383172Z","end":"2026-04-23T07:46:40.491531Z","steps":["trace[1568284580] 'process raft request' (duration: 83.957721ms)","trace[1568284580] 'compare' (duration: 24.218561ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:40.500205Z","caller":"traceutil/trace.go:171","msg":"trace[1008504752] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"116.905708ms","start":"2026-04-23T07:46:40.383283Z","end":"2026-04-23T07:46:40.500189Z","steps":["trace[1008504752] 'process raft request' (duration: 116.773504ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:40.500508Z","caller":"traceutil/trace.go:171","msg":"trace[233444709] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"117.092693ms","start":"2026-04-23T07:46:40.383394Z","end":"2026-04-23T07:46:40.500487Z","steps":["trace[233444709] 'process raft request' (duration: 116.757654ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:40.500839Z","caller":"traceutil/trace.go:171","msg":"trace[560891631] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"117.399979ms","start":"2026-04-23T07:46:40.383428Z","end":"2026-04-23T07:46:40.500828Z","steps":["trace[560891631] 'process raft request' (duration: 116.98225ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:40.500835Z","caller":"traceutil/trace.go:171","msg":"trace[1687891016] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1329; }","duration":"116.052876ms","start":"2026-04-23T07:46:40.38477Z","end":"2026-04-23T07:46:40.500823Z","steps":["trace[1687891016] 'read index received' (duration: 82.31909ms)","trace[1687891016] 'applied index is now lower than readState.Index' (duration: 33.732356ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:40.500909Z","caller":"traceutil/trace.go:171","msg":"trace[2050751002] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"114.546319ms","start":"2026-04-23T07:46:40.386321Z","end":"2026-04-23T07:46:40.500867Z","steps":["trace[2050751002] 'process raft request' (duration: 114.418266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:40.501157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.360014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T07:46:40.501249Z","caller":"traceutil/trace.go:171","msg":"trace[159731280] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1286; }","duration":"116.512698ms","start":"2026-04-23T07:46:40.384725Z","end":"2026-04-23T07:46:40.501237Z","steps":["trace[159731280] 'agreement among raft nodes before linearized reading' (duration: 116.250751ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:40.915092Z","caller":"traceutil/trace.go:171","msg":"trace[145451748] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"109.191145ms","start":"2026-04-23T07:46:40.805875Z","end":"2026-04-23T07:46:40.915067Z","steps":["trace[145451748] 'process raft request' (duration: 109.044502ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:41.352374Z","caller":"traceutil/trace.go:171","msg":"trace[2052733315] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"129.699544ms","start":"2026-04-23T07:46:41.222648Z","end":"2026-04-23T07:46:41.352347Z","steps":["trace[2052733315] 'process raft request' (duration: 129.510989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:42.71843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.625395ms","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-23T07:46:42.718524Z","caller":"traceutil/trace.go:171","msg":"trace[1358572396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1289; }","duration":"211.737788ms","start":"2026-04-23T07:46:42.506762Z","end":"2026-04-23T07:46:42.7185Z","steps":["trace[1358572396] 'range keys from in-memory index tree' (duration: 211.470161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:42.71866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.879347ms","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-23T07:46:42.718683Z","caller":"traceutil/trace.go:171","msg":"trace[2039928019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1289; }","duration":"212.924328ms","start":"2026-04-23T07:46:42.505752Z","end":"2026-04-23T07:46:42.718676Z","steps":["trace[2039928019] 'range keys from in-memory index tree' (duration: 212.807566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:42.718757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.961546ms","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-23T07:46:42.71879Z","caller":"traceutil/trace.go:171","msg":"trace[1246030045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1289; }","duration":"223.010078ms","start":"2026-04-23T07:46:42.495757Z","end":"2026-04-23T07:46:42.718767Z","steps":["trace[1246030045] 'range keys from in-memory index tree' (duration: 222.861304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:42.71889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.772293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-23T07:46:42.718473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.746779ms","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-23T07:46:42.718935Z","caller":"traceutil/trace.go:171","msg":"trace[1325669954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1289; }","duration":"212.228701ms","start":"2026-04-23T07:46:42.506702Z","end":"2026-04-23T07:46:42.718931Z","steps":["trace[1325669954] 'range keys from in-memory index tree' (duration: 211.619415ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:42.71893Z","caller":"traceutil/trace.go:171","msg":"trace[799716656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"212.829994ms","start":"2026-04-23T07:46:42.506088Z","end":"2026-04-23T07:46:42.718918Z","steps":["trace[799716656] 'range keys from in-memory index tree' (duration: 212.679371ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:42.843223Z","caller":"traceutil/trace.go:171","msg":"trace[141278638] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"119.472651ms","start":"2026-04-23T07:46:42.723728Z","end":"2026-04-23T07:46:42.843201Z","steps":["trace[141278638] 'process raft request' (duration: 119.329767ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:42.843344Z","caller":"traceutil/trace.go:171","msg":"trace[460032951] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"119.696617ms","start":"2026-04-23T07:46:42.723634Z","end":"2026-04-23T07:46:42.843331Z","steps":["trace[460032951] 'process raft request' (duration: 118.574459ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:42.843442Z","caller":"traceutil/trace.go:171","msg":"trace[425992720] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"113.185385ms","start":"2026-04-23T07:46:42.730249Z","end":"2026-04-23T07:46:42.843435Z","steps":["trace[425992720] 'process raft request' (duration: 112.888628ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:42.843501Z","caller":"traceutil/trace.go:171","msg":"trace[950561110] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"115.025832ms","start":"2026-04-23T07:46:42.728468Z","end":"2026-04-23T07:46:42.843494Z","steps":["trace[950561110] 'process raft request' (duration: 114.636183ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:43.356865Z","caller":"traceutil/trace.go:171","msg":"trace[19151966] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"202.657513ms","start":"2026-04-23T07:46:43.154186Z","end":"2026-04-23T07:46:43.356843Z","steps":["trace[19151966] 'process raft request' (duration: 202.53854ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:43.398626Z","caller":"traceutil/trace.go:171","msg":"trace[1221544892] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1344; }","duration":"215.353227ms","start":"2026-04-23T07:46:43.183256Z","end":"2026-04-23T07:46:43.398609Z","steps":["trace[1221544892] 'read index received' (duration: 173.467069ms)","trace[1221544892] 'applied index is now lower than readState.Index' (duration: 41.885068ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:43.398916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.644134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:46:43.398971Z","caller":"traceutil/trace.go:171","msg":"trace[1780849699] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1299; }","duration":"215.757577ms","start":"2026-04-23T07:46:43.183201Z","end":"2026-04-23T07:46:43.398958Z","steps":["trace[1780849699] 'agreement among raft nodes before linearized reading' (duration: 215.653724ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:43.399144Z","caller":"traceutil/trace.go:171","msg":"trace[1857583056] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"241.735852ms","start":"2026-04-23T07:46:43.157397Z","end":"2026-04-23T07:46:43.399133Z","steps":["trace[1857583056] 'process raft request' (duration: 241.008034ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:43.399273Z","caller":"traceutil/trace.go:171","msg":"trace[632943998] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"127.860899ms","start":"2026-04-23T07:46:43.271403Z","end":"2026-04-23T07:46:43.399264Z","steps":["trace[632943998] 'process raft request' (duration: 127.155601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:44.09719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.034398ms","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-23T07:46:44.097255Z","caller":"traceutil/trace.go:171","msg":"trace[1154927005] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1300; }","duration":"112.166041ms","start":"2026-04-23T07:46:43.985075Z","end":"2026-04-23T07:46:44.097241Z","steps":["trace[1154927005] 'count revisions from in-memory index tree' (duration: 111.956396ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:44.241098Z","caller":"traceutil/trace.go:171","msg":"trace[1319262282] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"150.858269ms","start":"2026-04-23T07:46:44.090213Z","end":"2026-04-23T07:46:44.241071Z","steps":["trace[1319262282] 'process raft request' (duration: 150.712386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:44.691593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.217972ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T07:46:44.692056Z","caller":"traceutil/trace.go:171","msg":"trace[1332935668] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"253.821692ms","start":"2026-04-23T07:46:44.438201Z","end":"2026-04-23T07:46:44.692023Z","steps":["trace[1332935668] 'process raft request' (duration: 253.515333ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:44.8906Z","caller":"traceutil/trace.go:171","msg":"trace[1088432993] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"114.636371ms","start":"2026-04-23T07:46:44.775939Z","end":"2026-04-23T07:46:44.890576Z","steps":["trace[1088432993] 'process raft request' (duration: 114.093227ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:45.09774Z","caller":"traceutil/trace.go:171","msg":"trace[1236868341] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"202.063138ms","start":"2026-04-23T07:46:44.895652Z","end":"2026-04-23T07:46:45.097715Z","steps":["trace[1236868341] 'process raft request' (duration: 202.023947ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:45.097992Z","caller":"traceutil/trace.go:171","msg":"trace[1285946252] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"202.449048ms","start":"2026-04-23T07:46:44.895532Z","end":"2026-04-23T07:46:45.097981Z","steps":["trace[1285946252] 'process raft request' (duration: 202.113369ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:45.097989Z","caller":"traceutil/trace.go:171","msg":"trace[1051668238] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"202.571582ms","start":"2026-04-23T07:46:44.895387Z","end":"2026-04-23T07:46:45.097958Z","steps":["trace[1051668238] 'process raft request' (duration: 202.216463ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:45.098247Z","caller":"traceutil/trace.go:171","msg":"trace[301906766] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"202.818368ms","start":"2026-04-23T07:46:44.895387Z","end":"2026-04-23T07:46:45.098205Z","steps":["trace[301906766] 'process raft request' (duration: 154.427208ms)","trace[301906766] 'compare' (duration: 47.642881ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:53.539396Z","caller":"traceutil/trace.go:171","msg":"trace[1825740655] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"402.228349ms","start":"2026-04-23T07:46:53.137147Z","end":"2026-04-23T07:46:53.539376Z","steps":["trace[1825740655] 'read index received' (duration: 402.077335ms)","trace[1825740655] 'applied index is now lower than readState.Index' (duration: 150.124µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:53.539461Z","caller":"traceutil/trace.go:171","msg":"trace[582916846] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"476.243663ms","start":"2026-04-23T07:46:53.063195Z","end":"2026-04-23T07:46:53.539439Z","steps":["trace[582916846] 'process raft request' (duration: 476.055209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:53.53959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.434094ms","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-23T07:46:53.539633Z","caller":"traceutil/trace.go:171","msg":"trace[1679763630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"402.522366ms","start":"2026-04-23T07:46:53.137099Z","end":"2026-04-23T07:46:53.539622Z","steps":["trace[1679763630] 'agreement among raft nodes before linearized reading' (duration: 402.395913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:53.539589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.063168Z","time spent":"476.340656ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:46:53.539668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.13707Z","time spent":"402.590968ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T07:46:53.539803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.268582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:46:53.539842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.971078ms","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-23T07:46:53.540017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.027028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-23T07:46:53.539894Z","caller":"traceutil/trace.go:171","msg":"trace[407554925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"181.413637ms","start":"2026-04-23T07:46:53.358458Z","end":"2026-04-23T07:46:53.539871Z","steps":["trace[407554925] 'agreement among raft nodes before linearized reading' (duration: 181.283153ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:53.540049Z","caller":"traceutil/trace.go:171","msg":"trace[560367409] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1368; }","duration":"127.0797ms","start":"2026-04-23T07:46:53.41296Z","end":"2026-04-23T07:46:53.540039Z","steps":["trace[560367409] 'agreement among raft nodes before linearized reading' (duration: 126.980037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:53.540082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.441618ms","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-23T07:46:53.540139Z","caller":"traceutil/trace.go:171","msg":"trace[2146449471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1368; }","duration":"382.05654ms","start":"2026-04-23T07:46:53.157818Z","end":"2026-04-23T07:46:53.539874Z","steps":["trace[2146449471] 'agreement among raft nodes before linearized reading' (duration: 381.946587ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:53.540159Z","caller":"traceutil/trace.go:171","msg":"trace[96740965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1368; }","duration":"386.570291ms","start":"2026-04-23T07:46:53.153568Z","end":"2026-04-23T07:46:53.540139Z","steps":["trace[96740965] 'agreement among raft nodes before linearized reading' (duration: 386.308435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:53.540206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.15355Z","time spent":"386.646552ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T07:46:53.54018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.157789Z","time spent":"382.379828ms","remote":"127.0.0.1:35808","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-23T07:46:53.540249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.993033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-23T07:46:53.54038Z","caller":"traceutil/trace.go:171","msg":"trace[1002444557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1368; }","duration":"402.533936ms","start":"2026-04-23T07:46:53.137826Z","end":"2026-04-23T07:46:53.540359Z","steps":["trace[1002444557] 'agreement among raft nodes before linearized reading' (duration: 401.899711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:53.540426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.137811Z","time spent":"402.604147ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T07:46:54.152224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121252828724351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:46:55.111342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.825352ms","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-23T07:46:55.111459Z","caller":"traceutil/trace.go:171","msg":"trace[968176594] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1368; }","duration":"485.964405ms","start":"2026-04-23T07:46:54.625479Z","end":"2026-04-23T07:46:55.111443Z","steps":["trace[968176594] 'range keys from in-memory index tree' (duration: 485.811242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:55.111813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.24497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:46:55.111879Z","caller":"traceutil/trace.go:171","msg":"trace[298512469] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"1.566503176s","start":"2026-04-23T07:46:53.545363Z","end":"2026-04-23T07:46:55.111866Z","steps":["trace[298512469] 'process raft request' (duration: 633.142263ms)","trace[298512469] 'compare' (duration: 932.653086ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:55.111936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.54534Z","time spent":"1.566565318s","remote":"127.0.0.1:35808","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-23T07:46:56.35961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000423361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-23T07:46:56.35971Z","caller":"traceutil/trace.go:171","msg":"trace[1323632534] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000575135s","start":"2026-04-23T07:46:54.359109Z","end":"2026-04-23T07:46:56.359684Z","steps":["trace[1323632534] 'agreement among raft nodes before linearized reading' (duration: 2.000452842s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.359997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:54.359084Z","time spent":"2.000895943s","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/23 07:46:56 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-23T07:46:56.702612Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.524160341s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T07:46:56.703184Z","caller":"traceutil/trace.go:171","msg":"trace[572970360] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"3.157201932s","start":"2026-04-23T07:46:53.545957Z","end":"2026-04-23T07:46:56.703159Z","steps":["trace[572970360] 'process raft request' (duration: 3.156906884s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.703406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.545942Z","time spent":"3.157359395s","remote":"127.0.0.1:35808","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-23T07:46:56.703609Z","caller":"traceutil/trace.go:171","msg":"trace[675294584] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"3.15755582s","start":"2026-04-23T07:46:53.546026Z","end":"2026-04-23T07:46:56.703582Z","steps":["trace[675294584] 'process raft request' (duration: 3.157081689s)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:56.703633Z","caller":"traceutil/trace.go:171","msg":"trace[174867238] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1418; }","duration":"3.051790068s","start":"2026-04-23T07:46:53.651814Z","end":"2026-04-23T07:46:56.703604Z","steps":["trace[174867238] 'read index received' (duration: 526.635273ms)","trace[174867238] 'applied index is now lower than readState.Index' (duration: 2.525153425s)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:56.703912Z","caller":"traceutil/trace.go:171","msg":"trace[1380347439] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"3.157259933s","start":"2026-04-23T07:46:53.546643Z","end":"2026-04-23T07:46:56.703903Z","steps":["trace[1380347439] 'process raft request' (duration: 3.156890504s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.703972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.546634Z","time spent":"3.157310454s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:46:56.703755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.546019Z","time spent":"3.157656763s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:46:56.704039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.052209269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:46:56.703809Z","caller":"traceutil/trace.go:171","msg":"trace[2037451144] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"3.157334345s","start":"2026-04-23T07:46:53.546466Z","end":"2026-04-23T07:46:56.7038Z","steps":["trace[2037451144] 'process raft request' (duration: 3.156935625s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.704263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.546454Z","time spent":"3.157756395s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:46:56.704383Z","caller":"traceutil/trace.go:171","msg":"trace[1198200430] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"1.886403094s","start":"2026-04-23T07:46:54.817969Z","end":"2026-04-23T07:46:56.704372Z","steps":["trace[1198200430] 'process raft request' (duration: 1.885954144s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.704458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:54.817942Z","time spent":"1.886483147s","remote":"127.0.0.1:35808","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-23T07:46:56.704109Z","caller":"traceutil/trace.go:171","msg":"trace[619401318] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1373; }","duration":"3.052337522s","start":"2026-04-23T07:46:53.651754Z","end":"2026-04-23T07:46:56.704092Z","steps":["trace[619401318] 'agreement among raft nodes before linearized reading' (duration: 3.052208239s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.705614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.651729Z","time spent":"3.053858391s","remote":"127.0.0.1:35906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:46:56.709264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14627836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:46:56.709286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755457119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:46:56.709483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.749536222s","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-23T07:46:56.709495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.048825ms","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:507"} {"level":"info","ts":"2026-04-23T07:46:56.709517Z","caller":"traceutil/trace.go:171","msg":"trace[949296334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1375; }","duration":"1.749596203s","start":"2026-04-23T07:46:54.959909Z","end":"2026-04-23T07:46:56.709505Z","steps":["trace[949296334] 'agreement among raft nodes before linearized reading' (duration: 1.749515142s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.709504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16246051s","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:530"} {"level":"warn","ts":"2026-04-23T07:46:56.709541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:54.959886Z","time spent":"1.749650375s","remote":"127.0.0.1:35808","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-23T07:46:56.709322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.554846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:56.709546Z","caller":"traceutil/trace.go:171","msg":"trace[1916112702] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1375; }","duration":"515.138658ms","start":"2026-04-23T07:46:56.19439Z","end":"2026-04-23T07:46:56.709529Z","steps":["trace[1916112702] 'agreement among raft nodes before linearized reading' (duration: 515.032195ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:56.709559Z","caller":"traceutil/trace.go:171","msg":"trace[2053773147] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1375; }","duration":"1.162560805s","start":"2026-04-23T07:46:55.546984Z","end":"2026-04-23T07:46:56.709545Z","steps":["trace[2053773147] 'agreement among raft nodes before linearized reading' (duration: 1.1623576s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.70959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:56.194369Z","time spent":"515.21094ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T07:46:56.709615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:55.546954Z","time spent":"1.162650847s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-23T07:46:56.709522Z","caller":"traceutil/trace.go:171","msg":"trace[639515487] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1375; }","duration":"1.755780656s","start":"2026-04-23T07:46:54.953724Z","end":"2026-04-23T07:46:56.709504Z","steps":["trace[639515487] 'agreement among raft nodes before linearized reading' (duration: 1.75549636s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.709672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:54.953705Z","time spent":"1.755955872s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-04-23T07:46:56.709575Z","caller":"traceutil/trace.go:171","msg":"trace[1984499956] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1375; }","duration":"105.863244ms","start":"2026-04-23T07:46:56.603705Z","end":"2026-04-23T07:46:56.709568Z","steps":["trace[1984499956] 'agreement among raft nodes before linearized reading' (duration: 105.577206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.709357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.758566721s","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":"info","ts":"2026-04-23T07:46:56.709799Z","caller":"traceutil/trace.go:171","msg":"trace[1052057731] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1375; }","duration":"2.759010832s","start":"2026-04-23T07:46:53.950778Z","end":"2026-04-23T07:46:56.709789Z","steps":["trace[1052057731] 'agreement among raft nodes before linearized reading' (duration: 2.758475869s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.709822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.95072Z","time spent":"2.759094944s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T07:46:56.709395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.087093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:56.709866Z","caller":"traceutil/trace.go:171","msg":"trace[664781095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"349.606656ms","start":"2026-04-23T07:46:56.360251Z","end":"2026-04-23T07:46:56.709858Z","steps":["trace[664781095] 'agreement among raft nodes before linearized reading' (duration: 349.109263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.709896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:56.360229Z","time spent":"349.660567ms","remote":"127.0.0.1:35564","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-23T07:46:56.709402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.025711917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:56.710031Z","caller":"traceutil/trace.go:171","msg":"trace[1392059410] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1375; }","duration":"2.026377074s","start":"2026-04-23T07:46:54.683644Z","end":"2026-04-23T07:46:56.710021Z","steps":["trace[1392059410] 'agreement among raft nodes before linearized reading' (duration: 2.025737708s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.710058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:54.683621Z","time spent":"2.026425655s","remote":"127.0.0.1:39986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-23T07:46:56.709426Z","caller":"traceutil/trace.go:171","msg":"trace[355571881] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"1.109916139s","start":"2026-04-23T07:46:55.599501Z","end":"2026-04-23T07:46:56.709417Z","steps":["trace[355571881] 'process raft request' (duration: 1.10950581s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.710162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:55.599479Z","time spent":"1.110660967s","remote":"127.0.0.1:35808","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-23T07:46:56.709474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.885541668s","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":"info","ts":"2026-04-23T07:46:56.71023Z","caller":"traceutil/trace.go:171","msg":"trace[710800610] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1375; }","duration":"2.886333508s","start":"2026-04-23T07:46:53.823886Z","end":"2026-04-23T07:46:56.710219Z","steps":["trace[710800610] 'agreement among raft nodes before linearized reading' (duration: 2.885566929s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.710257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.823864Z","time spent":"2.886385969s","remote":"127.0.0.1:39864","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-23T07:46:56.709326Z","caller":"traceutil/trace.go:171","msg":"trace[271368352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"1.146378583s","start":"2026-04-23T07:46:55.562928Z","end":"2026-04-23T07:46:56.709306Z","steps":["trace[271368352] 'agreement among raft nodes before linearized reading' (duration: 1.1462732s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.710313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:55.562914Z","time spent":"1.147390018s","remote":"127.0.0.1:35548","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-23T07:46:56.71036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.932424821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:56.710396Z","caller":"traceutil/trace.go:171","msg":"trace[828118279] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1375; }","duration":"2.932535223s","start":"2026-04-23T07:46:53.77785Z","end":"2026-04-23T07:46:56.710385Z","steps":["trace[828118279] 'agreement among raft nodes before linearized reading' (duration: 2.931310112s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.710483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:53.777813Z","time spent":"2.932640596s","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "}