{"level":"warn","ts":"2026-05-30T09:46:34.927734Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:46:34.927832Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-05-30T09:46:34.927908Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:46:34.927921Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-30T09:46:34.927943Z","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-05-30T09:46:34.928557Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-05-30T09:46:34.92864Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-05-30T09:46:34.932849Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.008258ms"} {"level":"info","ts":"2026-05-30T09:46:34.936651Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-05-30T09:46:34.93682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T09:46:34.936856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-05-30T09:46:34.936874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T09:46:34.936888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-05-30T09:46:34.936939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-05-30T09:46:34.94028Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T09:46:34.947426Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T09:46:34.948763Z","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-05-30T09:46:34.957422Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T09:46:34.957604Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T09:46:34.958374Z","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-05-30T09:46:34.958553Z","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-05-30T09:46:34.958597Z","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-05-30T09:46:34.959345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-05-30T09:46:34.959572Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-30T09:46:34.963587Z","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-05-30T09:46:34.963672Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-30T09:46:34.963713Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-30T09:46:34.964456Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T09:46:34.964489Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T09:46:35.337102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T09:46:35.337165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T09:46:35.337189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-05-30T09:46:35.337208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-05-30T09:46:35.337219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-30T09:46:35.33724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-05-30T09:46:35.337259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-30T09:46:35.347023Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T09:46:35.347069Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:46:35.34711Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:46:35.347485Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:46:35.3478Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T09:46:35.34788Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T09:46:35.350908Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:46:35.351034Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:46:35.351074Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:46:35.35248Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-05-30T09:46:35.362237Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-30T09:46:41.820479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04292255s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:46:41.820628Z","caller":"traceutil/trace.go:171","msg":"trace[35274522] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"1.138983614s","start":"2026-05-30T09:46:40.681632Z","end":"2026-05-30T09:46:41.820616Z","steps":["trace[35274522] 'process raft request' (duration: 1.138945104s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:41.820676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:40.681616Z","time spent":"1.139043966s","remote":"127.0.0.1:44676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:46:41.820814Z","caller":"traceutil/trace.go:171","msg":"trace[858235915] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"1.192121741s","start":"2026-05-30T09:46:40.628682Z","end":"2026-05-30T09:46:41.820803Z","steps":["trace[858235915] 'process raft request' (duration: 148.479003ms)","trace[858235915] 'compare' (duration: 1.042756426s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:46:41.820844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:40.628655Z","time spent":"1.192174613s","remote":"127.0.0.1:44676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":184,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:46:41.820937Z","caller":"traceutil/trace.go:171","msg":"trace[1668491688] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"1.157563958s","start":"2026-05-30T09:46:40.663365Z","end":"2026-05-30T09:46:41.820929Z","steps":["trace[1668491688] 'read index received' (duration: 113.809698ms)","trace[1668491688] 'applied index is now lower than readState.Index' (duration: 1.04375333s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:46:41.821106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.561743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-30T09:46:41.821128Z","caller":"traceutil/trace.go:171","msg":"trace[1655478782] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:258; }","duration":"522.649415ms","start":"2026-05-30T09:46:41.298472Z","end":"2026-05-30T09:46:41.821121Z","steps":["trace[1655478782] 'agreement among raft nodes before linearized reading' (duration: 522.556332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:41.821147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:41.298449Z","time spent":"522.692665ms","remote":"127.0.0.1:44666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3490,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-30T09:46:41.821136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157743692s","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-05-30T09:46:41.821239Z","caller":"traceutil/trace.go:171","msg":"trace[1936796515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:258; }","duration":"1.157927087s","start":"2026-05-30T09:46:40.663283Z","end":"2026-05-30T09:46:41.82121Z","steps":["trace[1936796515] 'agreement among raft nodes before linearized reading' (duration: 1.157754203s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:41.821278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.251471ms","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-05-30T09:46:41.821334Z","caller":"traceutil/trace.go:171","msg":"trace[1752898431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:258; }","duration":"419.327643ms","start":"2026-05-30T09:46:41.402Z","end":"2026-05-30T09:46:41.821327Z","steps":["trace[1752898431] 'agreement among raft nodes before linearized reading' (duration: 419.251441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:41.821338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:40.663259Z","time spent":"1.15806302s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T09:46:41.821356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:41.401987Z","time spent":"419.361443ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:46:42.906681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.766958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:46:42.906775Z","caller":"traceutil/trace.go:171","msg":"trace[32779564] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"1.081565712s","start":"2026-05-30T09:46:41.825192Z","end":"2026-05-30T09:46:42.906758Z","steps":["trace[32779564] 'process raft request' (duration: 428.663661ms)","trace[32779564] 'compare' (duration: 652.640585ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:46:42.906821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:41.825165Z","time spent":"1.081638833s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:46:43.244731Z","caller":"traceutil/trace.go:171","msg":"trace[1160814005] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"1.419388866s","start":"2026-05-30T09:46:41.825312Z","end":"2026-05-30T09:46:43.2447Z","steps":["trace[1160814005] 'process raft request' (duration: 1.414469236s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:43.244869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:41.825276Z","time spent":"1.41953143s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:46:43.244891Z","caller":"traceutil/trace.go:171","msg":"trace[899215376] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"1.419206211s","start":"2026-05-30T09:46:41.825673Z","end":"2026-05-30T09:46:43.244879Z","steps":["trace[899215376] 'process raft request' (duration: 1.418937405s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:46:43.244752Z","caller":"traceutil/trace.go:171","msg":"trace[2012883238] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:266; }","duration":"1.419153851s","start":"2026-05-30T09:46:41.825568Z","end":"2026-05-30T09:46:43.244722Z","steps":["trace[2012883238] 'read index received' (duration: 428.22109ms)","trace[2012883238] 'applied index is now lower than readState.Index' (duration: 990.931791ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:46:43.245109Z","caller":"traceutil/trace.go:171","msg":"trace[1091805792] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"1.406781128s","start":"2026-05-30T09:46:41.8383Z","end":"2026-05-30T09:46:43.245081Z","steps":["trace[1091805792] 'process raft request' (duration: 1.406383679s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:43.245244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:41.825662Z","time spent":"1.419263143s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:46:43.245335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:41.838283Z","time spent":"1.406954632s","remote":"127.0.0.1:44666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:46:43.245412Z","caller":"traceutil/trace.go:171","msg":"trace[319200743] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"333.397496ms","start":"2026-05-30T09:46:42.912Z","end":"2026-05-30T09:46:43.245397Z","steps":["trace[319200743] 'process raft request' (duration: 332.811882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:43.245479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:42.911984Z","time spent":"333.466177ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:46:43.245458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356716126s","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-05-30T09:46:43.245587Z","caller":"traceutil/trace.go:171","msg":"trace[809807150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:263; }","duration":"1.356913121s","start":"2026-05-30T09:46:41.888656Z","end":"2026-05-30T09:46:43.245569Z","steps":["trace[809807150] 'agreement among raft nodes before linearized reading' (duration: 1.356564573s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:43.245557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.306853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-30T09:46:43.245623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:41.888643Z","time spent":"1.356972633s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T09:46:43.245659Z","caller":"traceutil/trace.go:171","msg":"trace[1685140917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:263; }","duration":"335.419096ms","start":"2026-05-30T09:46:42.91021Z","end":"2026-05-30T09:46:43.24563Z","steps":["trace[1685140917] 'agreement among raft nodes before linearized reading' (duration: 335.19106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:43.245711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.465405ms","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-05-30T09:46:43.245727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:42.910145Z","time spent":"335.571999ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-30T09:46:43.245757Z","caller":"traceutil/trace.go:171","msg":"trace[1866542008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:263; }","duration":"730.590678ms","start":"2026-05-30T09:46:42.515153Z","end":"2026-05-30T09:46:43.245744Z","steps":["trace[1866542008] 'agreement among raft nodes before linearized reading' (duration: 730.457414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:43.245787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:42.515124Z","time spent":"730.65681ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T09:46:43.245484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419867957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-30T09:46:43.247072Z","caller":"traceutil/trace.go:171","msg":"trace[1152241943] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:262; }","duration":"1.42158015s","start":"2026-05-30T09:46:41.825429Z","end":"2026-05-30T09:46:43.247009Z","steps":["trace[1152241943] 'agreement among raft nodes before linearized reading' (duration: 1.419362755s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:43.247229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:41.825421Z","time spent":"1.421791184s","remote":"127.0.0.1:44676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-30T09:47:14.304661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.64574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:47:14.304772Z","caller":"traceutil/trace.go:171","msg":"trace[1015683250] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"906.042901ms","start":"2026-05-30T09:47:13.398711Z","end":"2026-05-30T09:47:14.304754Z","steps":["trace[1015683250] 'read index received' (duration: 258.304069ms)","trace[1015683250] 'applied index is now lower than readState.Index' (duration: 647.737712ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:14.304838Z","caller":"traceutil/trace.go:171","msg":"trace[523287623] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"907.874165ms","start":"2026-05-30T09:47:13.396953Z","end":"2026-05-30T09:47:14.304827Z","steps":["trace[523287623] 'process raft request' (duration: 259.984379ms)","trace[523287623] 'compare' (duration: 647.535908ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:47:14.304896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.396942Z","time spent":"907.926567ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:47:14.304989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.239506ms","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-05-30T09:47:14.30507Z","caller":"traceutil/trace.go:171","msg":"trace[43390373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:483; }","duration":"906.358479ms","start":"2026-05-30T09:47:13.398695Z","end":"2026-05-30T09:47:14.305053Z","steps":["trace[43390373] 'agreement among raft nodes before linearized reading' (duration: 906.179034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.305111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.398688Z","time spent":"906.413131ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T09:47:14.459823Z","caller":"traceutil/trace.go:171","msg":"trace[931569460] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"894.743879ms","start":"2026-05-30T09:47:13.565061Z","end":"2026-05-30T09:47:14.459805Z","steps":["trace[931569460] 'process raft request' (duration: 894.469562ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.459872Z","caller":"traceutil/trace.go:171","msg":"trace[1730054638] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"894.707299ms","start":"2026-05-30T09:47:13.56513Z","end":"2026-05-30T09:47:14.459838Z","steps":["trace[1730054638] 'process raft request' (duration: 894.433992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.459948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.565053Z","time spent":"894.833921ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:47:14.459968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.565125Z","time spent":"894.799691ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:14.460006Z","caller":"traceutil/trace.go:171","msg":"trace[1996893617] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"920.06534ms","start":"2026-05-30T09:47:13.539877Z","end":"2026-05-30T09:47:14.459943Z","steps":["trace[1996893617] 'process raft request' (duration: 919.407604ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.460039Z","caller":"traceutil/trace.go:171","msg":"trace[542879591] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"890.340613ms","start":"2026-05-30T09:47:13.569554Z","end":"2026-05-30T09:47:14.459894Z","steps":["trace[542879591] 'process raft request' (duration: 890.057216ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.459818Z","caller":"traceutil/trace.go:171","msg":"trace[1294472948] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"909.188677ms","start":"2026-05-30T09:47:13.550595Z","end":"2026-05-30T09:47:14.459783Z","steps":["trace[1294472948] 'process raft request' (duration: 908.862679ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.460056Z","caller":"traceutil/trace.go:171","msg":"trace[1570436483] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"904.31389ms","start":"2026-05-30T09:47:13.555633Z","end":"2026-05-30T09:47:14.459947Z","steps":["trace[1570436483] 'process raft request' (duration: 903.862359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.460117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.550576Z","time spent":"909.519725ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:47:14.460214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.569545Z","time spent":"890.577238ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:14.460266Z","caller":"traceutil/trace.go:171","msg":"trace[281935582] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"914.64535ms","start":"2026-05-30T09:47:13.545609Z","end":"2026-05-30T09:47:14.460255Z","steps":["trace[281935582] 'process raft request' (duration: 913.81194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.460236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.539859Z","time spent":"920.235284ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:47:14.460327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.545592Z","time spent":"914.709661ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:47:14.460378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.555608Z","time spent":"904.561086ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:14.460494Z","caller":"traceutil/trace.go:171","msg":"trace[625191306] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"882.34967ms","start":"2026-05-30T09:47:13.57813Z","end":"2026-05-30T09:47:14.460479Z","steps":["trace[625191306] 'process raft request' (duration: 881.618732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.460543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.578123Z","time spent":"882.400121ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:14.460929Z","caller":"traceutil/trace.go:171","msg":"trace[650037010] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"846.822064ms","start":"2026-05-30T09:47:13.614094Z","end":"2026-05-30T09:47:14.460916Z","steps":["trace[650037010] 'process raft request' (duration: 846.776932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.460994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.614076Z","time spent":"846.889355ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:14.461074Z","caller":"traceutil/trace.go:171","msg":"trace[1345571805] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"875.089115ms","start":"2026-05-30T09:47:13.585978Z","end":"2026-05-30T09:47:14.461067Z","steps":["trace[1345571805] 'process raft request' (duration: 874.46264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.461126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.585942Z","time spent":"875.164757ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:14.461327Z","caller":"traceutil/trace.go:171","msg":"trace[1267308003] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"871.381875ms","start":"2026-05-30T09:47:13.589806Z","end":"2026-05-30T09:47:14.461187Z","steps":["trace[1267308003] 'process raft request' (duration: 870.993466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.461506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.589783Z","time spent":"871.643842ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:14.50732Z","caller":"traceutil/trace.go:171","msg":"trace[1363573840] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:498; }","duration":"202.494274ms","start":"2026-05-30T09:47:14.304797Z","end":"2026-05-30T09:47:14.507291Z","steps":["trace[1363573840] 'read index received' (duration: 88.386972ms)","trace[1363573840] 'applied index is now lower than readState.Index' (duration: 114.105442ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:47:14.507461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.416358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:14.50745Z","caller":"traceutil/trace.go:171","msg":"trace[759912388] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"778.593868ms","start":"2026-05-30T09:47:13.72881Z","end":"2026-05-30T09:47:14.507403Z","steps":["trace[759912388] 'process raft request' (duration: 778.194898ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.507506Z","caller":"traceutil/trace.go:171","msg":"trace[1052717995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"847.522081ms","start":"2026-05-30T09:47:13.659969Z","end":"2026-05-30T09:47:14.507491Z","steps":["trace[1052717995] 'agreement among raft nodes before linearized reading' (duration: 847.435149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.507564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.659953Z","time spent":"847.600252ms","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:47:14.507654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:13.728794Z","time spent":"778.761911ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:14.507668Z","caller":"traceutil/trace.go:171","msg":"trace[2122767709] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"198.117249ms","start":"2026-05-30T09:47:14.309487Z","end":"2026-05-30T09:47:14.507604Z","steps":["trace[2122767709] 'process raft request' (duration: 197.718319ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.617953Z","caller":"traceutil/trace.go:171","msg":"trace[1527342068] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"149.843213ms","start":"2026-05-30T09:47:14.468083Z","end":"2026-05-30T09:47:14.617927Z","steps":["trace[1527342068] 'process raft request' (duration: 142.331852ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.618045Z","caller":"traceutil/trace.go:171","msg":"trace[1132701997] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"147.788525ms","start":"2026-05-30T09:47:14.470229Z","end":"2026-05-30T09:47:14.618017Z","steps":["trace[1132701997] 'process raft request' (duration: 147.58578ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.618095Z","caller":"traceutil/trace.go:171","msg":"trace[1997633637] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"144.900505ms","start":"2026-05-30T09:47:14.473159Z","end":"2026-05-30T09:47:14.61806Z","steps":["trace[1997633637] 'process raft request' (duration: 144.824943ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.618167Z","caller":"traceutil/trace.go:171","msg":"trace[1908737837] transaction","detail":"{read_only:false; number_of_response:1; response_revision:498; }","duration":"146.924804ms","start":"2026-05-30T09:47:14.471223Z","end":"2026-05-30T09:47:14.618147Z","steps":["trace[1908737837] 'process raft request' (duration: 146.670438ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.618386Z","caller":"traceutil/trace.go:171","msg":"trace[539002824] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"145.262414ms","start":"2026-05-30T09:47:14.473096Z","end":"2026-05-30T09:47:14.618359Z","steps":["trace[539002824] 'process raft request' (duration: 144.835084ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.618662Z","caller":"traceutil/trace.go:171","msg":"trace[497368426] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"145.134891ms","start":"2026-05-30T09:47:14.473518Z","end":"2026-05-30T09:47:14.618653Z","steps":["trace[497368426] 'process raft request' (duration: 144.505956ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.621841Z","caller":"traceutil/trace.go:171","msg":"trace[616632208] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"144.001763ms","start":"2026-05-30T09:47:14.47781Z","end":"2026-05-30T09:47:14.621812Z","steps":["trace[616632208] 'process raft request' (duration: 140.728564ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.623444Z","caller":"traceutil/trace.go:171","msg":"trace[854070445] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"144.788472ms","start":"2026-05-30T09:47:14.47855Z","end":"2026-05-30T09:47:14.623338Z","steps":["trace[854070445] 'process raft request' (duration: 140.087319ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.625587Z","caller":"traceutil/trace.go:171","msg":"trace[2081759994] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:512; }","duration":"116.631743ms","start":"2026-05-30T09:47:14.508906Z","end":"2026-05-30T09:47:14.625538Z","steps":["trace[2081759994] 'read index received' (duration: 101.525079ms)","trace[2081759994] 'applied index is now lower than readState.Index' (duration: 15.058843ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:14.626341Z","caller":"traceutil/trace.go:171","msg":"trace[905360560] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"145.012867ms","start":"2026-05-30T09:47:14.481297Z","end":"2026-05-30T09:47:14.62631Z","steps":["trace[905360560] 'process raft request' (duration: 142.442205ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.626407Z","caller":"traceutil/trace.go:171","msg":"trace[1859817414] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"143.418439ms","start":"2026-05-30T09:47:14.482938Z","end":"2026-05-30T09:47:14.626356Z","steps":["trace[1859817414] 'process raft request' (duration: 141.758058ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:14.62641Z","caller":"traceutil/trace.go:171","msg":"trace[1476030734] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"147.420506ms","start":"2026-05-30T09:47:14.478973Z","end":"2026-05-30T09:47:14.626394Z","steps":["trace[1476030734] 'process raft request' (duration: 143.638004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:14.628408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.494472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:14.628451Z","caller":"traceutil/trace.go:171","msg":"trace[1230254554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"119.545983ms","start":"2026-05-30T09:47:14.508892Z","end":"2026-05-30T09:47:14.628438Z","steps":["trace[1230254554] 'agreement among raft nodes before linearized reading' (duration: 116.757865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:15.710716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.100619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:47:15.710997Z","caller":"traceutil/trace.go:171","msg":"trace[66702158] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.142731488s","start":"2026-05-30T09:47:14.568234Z","end":"2026-05-30T09:47:15.710966Z","steps":["trace[66702158] 'process raft request' (duration: 307.23802ms)","trace[66702158] 'compare' (duration: 834.952215ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:15.71118Z","caller":"traceutil/trace.go:171","msg":"trace[1852902556] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.076119332s","start":"2026-05-30T09:47:14.635039Z","end":"2026-05-30T09:47:15.711158Z","steps":["trace[1852902556] 'process raft request' (duration: 1.075969848s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:15.711217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:14.568175Z","time spent":"1.142916973s","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:47:15.711301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:14.635008Z","time spent":"1.076230155s","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:47:15.711328Z","caller":"traceutil/trace.go:171","msg":"trace[1855681269] transaction","detail":"{read_only:false; number_of_response:0; response_revision:507; }","duration":"1.08514036s","start":"2026-05-30T09:47:14.626135Z","end":"2026-05-30T09:47:15.711275Z","steps":["trace[1855681269] 'process raft request' (duration: 1.08472077s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:15.711472Z","caller":"traceutil/trace.go:171","msg":"trace[707748602] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.081563723s","start":"2026-05-30T09:47:14.629894Z","end":"2026-05-30T09:47:15.711457Z","steps":["trace[707748602] 'process raft request' (duration: 1.08101267s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:15.711548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:14.629877Z","time spent":"1.081631435s","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:47:15.711569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:14.626077Z","time spent":"1.085387425s","remote":"127.0.0.1:45000","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:15.711647Z","caller":"traceutil/trace.go:171","msg":"trace[844910224] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.070926507s","start":"2026-05-30T09:47:14.640691Z","end":"2026-05-30T09:47:15.711617Z","steps":["trace[844910224] 'process raft request' (duration: 1.070393824s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:15.711802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:14.640661Z","time spent":"1.071060031s","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:47:15.712128Z","caller":"traceutil/trace.go:171","msg":"trace[1677565973] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.067088665s","start":"2026-05-30T09:47:14.645007Z","end":"2026-05-30T09:47:15.712096Z","steps":["trace[1677565973] 'process raft request' (duration: 1.066409028s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:15.712193Z","caller":"traceutil/trace.go:171","msg":"trace[2089126390] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:523; }","duration":"326.253718ms","start":"2026-05-30T09:47:15.385925Z","end":"2026-05-30T09:47:15.712179Z","steps":["trace[2089126390] 'read index received' (duration: 299.273747ms)","trace[2089126390] 'applied index is now lower than readState.Index' (duration: 26.979081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:47:15.712268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:14.644996Z","time spent":"1.067193727s","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:47:15.712281Z","caller":"traceutil/trace.go:171","msg":"trace[1721879697] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.029142859s","start":"2026-05-30T09:47:14.68313Z","end":"2026-05-30T09:47:15.712273Z","steps":["trace[1721879697] 'process raft request' (duration: 1.028832212s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:15.712364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.438123ms","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-05-30T09:47:15.7124Z","caller":"traceutil/trace.go:171","msg":"trace[634852057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"326.517435ms","start":"2026-05-30T09:47:15.38587Z","end":"2026-05-30T09:47:15.712388Z","steps":["trace[634852057] 'agreement among raft nodes before linearized reading' (duration: 326.427343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:15.712415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:14.683108Z","time spent":"1.029279153s","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:47:15.71243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:15.385846Z","time spent":"326.577056ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:47:15.712567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.058083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-30T09:47:15.712659Z","caller":"traceutil/trace.go:171","msg":"trace[1212673263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"316.190086ms","start":"2026-05-30T09:47:15.396455Z","end":"2026-05-30T09:47:15.712645Z","steps":["trace[1212673263] 'agreement among raft nodes before linearized reading' (duration: 316.055553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:15.712697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:15.396435Z","time spent":"316.253567ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-30T09:47:21.84589Z","caller":"traceutil/trace.go:171","msg":"trace[1017691056] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"186.007116ms","start":"2026-05-30T09:47:21.659838Z","end":"2026-05-30T09:47:21.845845Z","steps":["trace[1017691056] 'read index received' (duration: 179.571791ms)","trace[1017691056] 'applied index is now lower than readState.Index' (duration: 6.434525ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:47:21.846018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.16512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:21.846046Z","caller":"traceutil/trace.go:171","msg":"trace[2133093398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"186.227621ms","start":"2026-05-30T09:47:21.659809Z","end":"2026-05-30T09:47:21.846037Z","steps":["trace[2133093398] 'agreement among raft nodes before linearized reading' (duration: 186.166779ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:21.84599Z","caller":"traceutil/trace.go:171","msg":"trace[1987326112] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"260.338788ms","start":"2026-05-30T09:47:21.58559Z","end":"2026-05-30T09:47:21.845929Z","steps":["trace[1987326112] 'process raft request' (duration: 254.521918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:22.325785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.902466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:47:22.325908Z","caller":"traceutil/trace.go:171","msg":"trace[1222194170] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"468.250871ms","start":"2026-05-30T09:47:21.857638Z","end":"2026-05-30T09:47:22.325889Z","steps":["trace[1222194170] 'process raft request' (duration: 26.160361ms)","trace[1222194170] 'compare' (duration: 441.789693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:47:22.326009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:21.857619Z","time spent":"468.323164ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:47:22.408737Z","caller":"traceutil/trace.go:171","msg":"trace[1420750958] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"549.920921ms","start":"2026-05-30T09:47:21.858791Z","end":"2026-05-30T09:47:22.408712Z","steps":["trace[1420750958] 'process raft request' (duration: 549.784208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:22.408943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:21.858762Z","time spent":"550.052644ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:47:22.42438Z","caller":"traceutil/trace.go:171","msg":"trace[956318528] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"503.631555ms","start":"2026-05-30T09:47:21.920687Z","end":"2026-05-30T09:47:22.424319Z","steps":["trace[956318528] 'process raft request' (duration: 503.521543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:22.425018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:21.92066Z","time spent":"503.817369ms","remote":"127.0.0.1:44982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:47:22.561308Z","caller":"traceutil/trace.go:171","msg":"trace[1130120498] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"126.125862ms","start":"2026-05-30T09:47:22.435134Z","end":"2026-05-30T09:47:22.56126Z","steps":["trace[1130120498] 'read index received' (duration: 61.585095ms)","trace[1130120498] 'applied index is now lower than readState.Index' (duration: 64.539427ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:47:22.561456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.309226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:22.5615Z","caller":"traceutil/trace.go:171","msg":"trace[1951692633] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:596; }","duration":"126.390338ms","start":"2026-05-30T09:47:22.435093Z","end":"2026-05-30T09:47:22.561483Z","steps":["trace[1951692633] 'agreement among raft nodes before linearized reading' (duration: 126.307246ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:22.561599Z","caller":"traceutil/trace.go:171","msg":"trace[531865012] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"132.741871ms","start":"2026-05-30T09:47:22.428818Z","end":"2026-05-30T09:47:22.56156Z","steps":["trace[531865012] 'process raft request' (duration: 67.730093ms)","trace[531865012] 'compare' (duration: 64.605008ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:22.587783Z","caller":"traceutil/trace.go:171","msg":"trace[2021925561] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"147.482067ms","start":"2026-05-30T09:47:22.440278Z","end":"2026-05-30T09:47:22.58776Z","steps":["trace[2021925561] 'process raft request' (duration: 147.035496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:23.161619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316210171887111,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:47:23.662391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316210171887111,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:47:23.70375Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.112388658s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-30T09:47:24.309947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.858942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:47:24.310103Z","caller":"traceutil/trace.go:171","msg":"trace[1954055810] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.718806693s","start":"2026-05-30T09:47:22.591273Z","end":"2026-05-30T09:47:24.31008Z","steps":["trace[1954055810] 'process raft request' (duration: 1.112710096s)","trace[1954055810] 'compare' (duration: 605.705008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:47:24.310521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:22.591258Z","time spent":"1.718885026s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:47:24.328676Z","caller":"traceutil/trace.go:171","msg":"trace[370136861] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.382694988s","start":"2026-05-30T09:47:22.945897Z","end":"2026-05-30T09:47:24.328592Z","steps":["trace[370136861] 'process raft request' (duration: 1.382078223s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:24.329178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:22.945863Z","time spent":"1.383124928s","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:47:24.337434Z","caller":"traceutil/trace.go:171","msg":"trace[829275886] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:616; }","duration":"1.67678917s","start":"2026-05-30T09:47:22.660616Z","end":"2026-05-30T09:47:24.337405Z","steps":["trace[829275886] 'read index received' (duration: 1.043470116s)","trace[829275886] 'applied index is now lower than readState.Index' (duration: 633.317494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:47:24.33763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676987995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:24.337662Z","caller":"traceutil/trace.go:171","msg":"trace[1689494952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"1.677089347s","start":"2026-05-30T09:47:22.660562Z","end":"2026-05-30T09:47:24.337652Z","steps":["trace[1689494952] 'agreement among raft nodes before linearized reading' (duration: 1.677001535s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:24.337696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:22.66054Z","time spent":"1.677147939s","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T09:47:25.953188Z","caller":"traceutil/trace.go:171","msg":"trace[387502737] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"406.185126ms","start":"2026-05-30T09:47:25.546978Z","end":"2026-05-30T09:47:25.953164Z","steps":["trace[387502737] 'read index received' (duration: 405.238993ms)","trace[387502737] 'applied index is now lower than readState.Index' (duration: 945.183µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:25.953328Z","caller":"traceutil/trace.go:171","msg":"trace[1336951970] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"409.068024ms","start":"2026-05-30T09:47:25.544243Z","end":"2026-05-30T09:47:25.953311Z","steps":["trace[1336951970] 'process raft request' (duration: 408.014849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:25.953433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:25.544232Z","time spent":"409.125086ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:47:25.953451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.449212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:25.953539Z","caller":"traceutil/trace.go:171","msg":"trace[1353292181] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:624; }","duration":"406.591115ms","start":"2026-05-30T09:47:25.546922Z","end":"2026-05-30T09:47:25.953514Z","steps":["trace[1353292181] 'agreement among raft nodes before linearized reading' (duration: 406.435771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:25.953585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:25.546905Z","time spent":"406.671217ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-05-30T09:47:25.958559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.208736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:25.958647Z","caller":"traceutil/trace.go:171","msg":"trace[574860902] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:625; }","duration":"411.323659ms","start":"2026-05-30T09:47:25.547301Z","end":"2026-05-30T09:47:25.958624Z","steps":["trace[574860902] 'agreement among raft nodes before linearized reading' (duration: 411.117374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:25.958705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:25.547292Z","time spent":"411.398361ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-05-30T09:47:25.959061Z","caller":"traceutil/trace.go:171","msg":"trace[835374992] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"411.73861ms","start":"2026-05-30T09:47:25.547302Z","end":"2026-05-30T09:47:25.959041Z","steps":["trace[835374992] 'process raft request' (duration: 410.878399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:25.959162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:25.547268Z","time spent":"411.839832ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:47:25.959493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.245957ms","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":"info","ts":"2026-05-30T09:47:25.959535Z","caller":"traceutil/trace.go:171","msg":"trace[838041934] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:625; }","duration":"224.311939ms","start":"2026-05-30T09:47:25.735212Z","end":"2026-05-30T09:47:25.959524Z","steps":["trace[838041934] 'agreement among raft nodes before linearized reading' (duration: 224.255188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:25.959537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.054647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:25.95961Z","caller":"traceutil/trace.go:171","msg":"trace[1820344037] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:625; }","duration":"412.138919ms","start":"2026-05-30T09:47:25.547455Z","end":"2026-05-30T09:47:25.959594Z","steps":["trace[1820344037] 'agreement among raft nodes before linearized reading' (duration: 412.034516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:25.959691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.18792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:25.959727Z","caller":"traceutil/trace.go:171","msg":"trace[364214907] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:625; }","duration":"412.298513ms","start":"2026-05-30T09:47:25.547419Z","end":"2026-05-30T09:47:25.959717Z","steps":["trace[364214907] 'agreement among raft nodes before linearized reading' (duration: 412.246762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:25.959756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:25.547411Z","time spent":"412.338584ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-05-30T09:47:25.959737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.587624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:25.959796Z","caller":"traceutil/trace.go:171","msg":"trace[912290064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"299.692128ms","start":"2026-05-30T09:47:25.660093Z","end":"2026-05-30T09:47:25.959785Z","steps":["trace[912290064] 'agreement among raft nodes before linearized reading' (duration: 299.599105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:25.959846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.346084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:47:25.959855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.368385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:25.959881Z","caller":"traceutil/trace.go:171","msg":"trace[1485270770] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:625; }","duration":"412.387365ms","start":"2026-05-30T09:47:25.547483Z","end":"2026-05-30T09:47:25.95987Z","steps":["trace[1485270770] 'agreement among raft nodes before linearized reading' (duration: 412.338573ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:25.959885Z","caller":"traceutil/trace.go:171","msg":"trace[247904754] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:625; }","duration":"412.412256ms","start":"2026-05-30T09:47:25.547463Z","end":"2026-05-30T09:47:25.959876Z","steps":["trace[247904754] 'agreement among raft nodes before linearized reading' (duration: 412.369105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:25.959913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:25.547457Z","time spent":"412.449606ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-05-30T09:47:25.959913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:25.547477Z","time spent":"412.428876ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-30T09:47:25.960021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:25.54745Z","time spent":"412.563679ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-30T09:47:28.749537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.354003ms","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-05-30T09:47:28.749623Z","caller":"traceutil/trace.go:171","msg":"trace[1284535925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"120.488916ms","start":"2026-05-30T09:47:28.629112Z","end":"2026-05-30T09:47:28.749601Z","steps":["trace[1284535925] 'range keys from in-memory index tree' (duration: 120.169228ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:31.535482Z","caller":"traceutil/trace.go:171","msg":"trace[393787158] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"120.67795ms","start":"2026-05-30T09:47:31.414784Z","end":"2026-05-30T09:47:31.535462Z","steps":["trace[393787158] 'process raft request' (duration: 120.535427ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:31.713158Z","caller":"traceutil/trace.go:171","msg":"trace[627015556] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"139.444273ms","start":"2026-05-30T09:47:31.573679Z","end":"2026-05-30T09:47:31.713124Z","steps":["trace[627015556] 'process raft request' (duration: 55.852907ms)","trace[627015556] 'compare' (duration: 83.195707ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:31.874231Z","caller":"traceutil/trace.go:171","msg":"trace[1952003538] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"128.497569ms","start":"2026-05-30T09:47:31.745708Z","end":"2026-05-30T09:47:31.874205Z","steps":["trace[1952003538] 'process raft request' (duration: 128.362426ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:31.874286Z","caller":"traceutil/trace.go:171","msg":"trace[1281489029] transaction","detail":"{read_only:false; number_of_response:0; response_revision:707; }","duration":"146.484032ms","start":"2026-05-30T09:47:31.727782Z","end":"2026-05-30T09:47:31.874266Z","steps":["trace[1281489029] 'process raft request' (duration: 94.034757ms)","trace[1281489029] 'compare' (duration: 52.201939ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:36.624698Z","caller":"traceutil/trace.go:171","msg":"trace[573465340] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"146.239726ms","start":"2026-05-30T09:47:36.47842Z","end":"2026-05-30T09:47:36.624659Z","steps":["trace[573465340] 'read index received' (duration: 145.823006ms)","trace[573465340] 'applied index is now lower than readState.Index' (duration: 414.86µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:36.62483Z","caller":"traceutil/trace.go:171","msg":"trace[240056889] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"183.564227ms","start":"2026-05-30T09:47:36.441216Z","end":"2026-05-30T09:47:36.62478Z","steps":["trace[240056889] 'process raft request' (duration: 183.174617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:36.624963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.511803ms","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-05-30T09:47:36.625338Z","caller":"traceutil/trace.go:171","msg":"trace[1802359149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:738; }","duration":"146.927062ms","start":"2026-05-30T09:47:36.478375Z","end":"2026-05-30T09:47:36.625303Z","steps":["trace[1802359149] 'agreement among raft nodes before linearized reading' (duration: 146.42761ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:38.749327Z","caller":"traceutil/trace.go:171","msg":"trace[511201877] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"105.690549ms","start":"2026-05-30T09:47:38.643579Z","end":"2026-05-30T09:47:38.74927Z","steps":["trace[511201877] 'process raft request' (duration: 61.051233ms)","trace[511201877] 'compare' (duration: 44.484192ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:38.749608Z","caller":"traceutil/trace.go:171","msg":"trace[325226252] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"104.747376ms","start":"2026-05-30T09:47:38.644827Z","end":"2026-05-30T09:47:38.749574Z","steps":["trace[325226252] 'process raft request' (duration: 104.398398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:39.919098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.512357ms","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-05-30T09:47:39.919154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.711069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:39.919203Z","caller":"traceutil/trace.go:171","msg":"trace[800249007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"159.666411ms","start":"2026-05-30T09:47:39.759515Z","end":"2026-05-30T09:47:39.919181Z","steps":["trace[800249007] 'range keys from in-memory index tree' (duration: 159.297692ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:39.91972Z","caller":"traceutil/trace.go:171","msg":"trace[1248768976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"258.857943ms","start":"2026-05-30T09:47:39.660357Z","end":"2026-05-30T09:47:39.919215Z","steps":["trace[1248768976] 'range keys from in-memory index tree' (duration: 258.632958ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:40.443904Z","caller":"traceutil/trace.go:171","msg":"trace[2014573365] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"519.948438ms","start":"2026-05-30T09:47:39.923932Z","end":"2026-05-30T09:47:40.44388Z","steps":["trace[2014573365] 'process raft request' (duration: 519.763614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:40.444062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:39.923913Z","time spent":"520.069892ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:47:41.386689Z","caller":"traceutil/trace.go:171","msg":"trace[131858195] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"107.953153ms","start":"2026-05-30T09:47:41.278711Z","end":"2026-05-30T09:47:41.386664Z","steps":["trace[131858195] 'process raft request' (duration: 107.491062ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:43.655949Z","caller":"traceutil/trace.go:171","msg":"trace[333384511] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"110.518875ms","start":"2026-05-30T09:47:43.545406Z","end":"2026-05-30T09:47:43.655925Z","steps":["trace[333384511] 'process raft request' (duration: 109.807038ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:43.805495Z","caller":"traceutil/trace.go:171","msg":"trace[1786246466] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:841; }","duration":"141.052211ms","start":"2026-05-30T09:47:43.664425Z","end":"2026-05-30T09:47:43.805477Z","steps":["trace[1786246466] 'read index received' (duration: 18.792494ms)","trace[1786246466] 'applied index is now lower than readState.Index' (duration: 122.259107ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:47:43.805657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.210595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:47:43.805758Z","caller":"traceutil/trace.go:171","msg":"trace[278661880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"141.339858ms","start":"2026-05-30T09:47:43.664397Z","end":"2026-05-30T09:47:43.805737Z","steps":["trace[278661880] 'agreement among raft nodes before linearized reading' (duration: 141.210894ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:43.805902Z","caller":"traceutil/trace.go:171","msg":"trace[1042057671] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"142.67626ms","start":"2026-05-30T09:47:43.663219Z","end":"2026-05-30T09:47:43.805895Z","steps":["trace[1042057671] 'process raft request' (duration: 112.593505ms)","trace[1042057671] 'compare' (duration: 29.588953ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:13.78732Z","caller":"traceutil/trace.go:171","msg":"trace[2028218975] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"127.218809ms","start":"2026-05-30T09:48:13.660061Z","end":"2026-05-30T09:48:13.78728Z","steps":["trace[2028218975] 'read index received' (duration: 116.132561ms)","trace[2028218975] 'applied index is now lower than readState.Index' (duration: 11.084508ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:48:13.787471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.380792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:48:13.787542Z","caller":"traceutil/trace.go:171","msg":"trace[247273668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"127.513526ms","start":"2026-05-30T09:48:13.660011Z","end":"2026-05-30T09:48:13.787524Z","steps":["trace[247273668] 'agreement among raft nodes before linearized reading' (duration: 127.368132ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:14.802031Z","caller":"traceutil/trace.go:171","msg":"trace[1172427271] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"195.035754ms","start":"2026-05-30T09:48:14.60697Z","end":"2026-05-30T09:48:14.802005Z","steps":["trace[1172427271] 'read index received' (duration: 194.85496ms)","trace[1172427271] 'applied index is now lower than readState.Index' (duration: 179.444µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:14.802102Z","caller":"traceutil/trace.go:171","msg":"trace[468408553] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"244.776394ms","start":"2026-05-30T09:48:14.557273Z","end":"2026-05-30T09:48:14.802049Z","steps":["trace[468408553] 'process raft request' (duration: 244.550309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:14.802226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.227119ms","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-05-30T09:48:14.802262Z","caller":"traceutil/trace.go:171","msg":"trace[475547060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"195.315741ms","start":"2026-05-30T09:48:14.606935Z","end":"2026-05-30T09:48:14.80225Z","steps":["trace[475547060] 'agreement among raft nodes before linearized reading' (duration: 195.163178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:15.360494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316210171887928,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:48:15.861114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316210171887928,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T09:48:15.970037Z","caller":"traceutil/trace.go:171","msg":"trace[77134698] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"1.10957931s","start":"2026-05-30T09:48:14.860433Z","end":"2026-05-30T09:48:15.970013Z","steps":["trace[77134698] 'read index received' (duration: 1.108236778s)","trace[77134698] 'applied index is now lower than readState.Index' (duration: 1.341262ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:15.970094Z","caller":"traceutil/trace.go:171","msg":"trace[1006918623] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.163055451s","start":"2026-05-30T09:48:14.807014Z","end":"2026-05-30T09:48:15.970069Z","steps":["trace[1006918623] 'process raft request' (duration: 1.161635246s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:15.970177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109730925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:48:15.970204Z","caller":"traceutil/trace.go:171","msg":"trace[2062689130] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:976; }","duration":"1.109774806s","start":"2026-05-30T09:48:14.860421Z","end":"2026-05-30T09:48:15.970196Z","steps":["trace[2062689130] 'agreement among raft nodes before linearized reading' (duration: 1.109698944s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:15.970227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:14.860346Z","time spent":"1.109875278s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:48:15.970225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:14.806986Z","time spent":"1.163170484s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:48:15.97028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090821858s","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-05-30T09:48:15.970308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.187007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-30T09:48:15.970313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.381732ms","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-05-30T09:48:15.970311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.214911ms","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-05-30T09:48:15.970355Z","caller":"traceutil/trace.go:171","msg":"trace[1124283851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"853.272939ms","start":"2026-05-30T09:48:15.11707Z","end":"2026-05-30T09:48:15.970343Z","steps":["trace[1124283851] 'agreement among raft nodes before linearized reading' (duration: 853.138576ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:15.970352Z","caller":"traceutil/trace.go:171","msg":"trace[1937375861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:976; }","duration":"1.090927381s","start":"2026-05-30T09:48:14.879404Z","end":"2026-05-30T09:48:15.970332Z","steps":["trace[1937375861] 'agreement among raft nodes before linearized reading' (duration: 1.090743207s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:15.970367Z","caller":"traceutil/trace.go:171","msg":"trace[1546275582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"909.471334ms","start":"2026-05-30T09:48:15.060879Z","end":"2026-05-30T09:48:15.97035Z","steps":["trace[1546275582] 'agreement among raft nodes before linearized reading' (duration: 909.339941ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:15.970376Z","caller":"traceutil/trace.go:171","msg":"trace[595195058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"815.307014ms","start":"2026-05-30T09:48:15.155054Z","end":"2026-05-30T09:48:15.970361Z","steps":["trace[595195058] 'agreement among raft nodes before linearized reading' (duration: 815.13985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:15.970391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:15.117047Z","time spent":"853.3354ms","remote":"127.0.0.1:44722","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-05-30T09:48:15.970394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:14.879389Z","time spent":"1.090996762s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T09:48:15.970402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:15.060853Z","time spent":"909.540977ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T09:48:15.970414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:15.155036Z","time spent":"815.369865ms","remote":"127.0.0.1:44722","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-05-30T09:48:15.970565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.398549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:48:15.97078Z","caller":"traceutil/trace.go:171","msg":"trace[1667838499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"311.645406ms","start":"2026-05-30T09:48:15.659123Z","end":"2026-05-30T09:48:15.970769Z","steps":["trace[1667838499] 'agreement among raft nodes before linearized reading' (duration: 311.41366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:15.970831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:15.659101Z","time spent":"311.720888ms","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T09:48:38.419567Z","caller":"traceutil/trace.go:171","msg":"trace[946614711] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"312.23465ms","start":"2026-05-30T09:48:38.107311Z","end":"2026-05-30T09:48:38.419545Z","steps":["trace[946614711] 'read index received' (duration: 306.00639ms)","trace[946614711] 'applied index is now lower than readState.Index' (duration: 6.22713ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:38.419636Z","caller":"traceutil/trace.go:171","msg":"trace[1907274417] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"313.0409ms","start":"2026-05-30T09:48:38.106575Z","end":"2026-05-30T09:48:38.419616Z","steps":["trace[1907274417] 'process raft request' (duration: 306.726538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:38.419737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.106555Z","time spent":"313.128382ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:48:38.419795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.465176ms","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-05-30T09:48:38.419833Z","caller":"traceutil/trace.go:171","msg":"trace[1637150117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"312.535888ms","start":"2026-05-30T09:48:38.107282Z","end":"2026-05-30T09:48:38.419818Z","steps":["trace[1637150117] 'agreement among raft nodes before linearized reading' (duration: 312.385944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:38.419866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.10727Z","time spent":"312.587849ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T09:48:38.419983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.63425ms","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-05-30T09:48:38.420005Z","caller":"traceutil/trace.go:171","msg":"trace[494476346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1049; }","duration":"312.684151ms","start":"2026-05-30T09:48:38.107314Z","end":"2026-05-30T09:48:38.419998Z","steps":["trace[494476346] 'agreement among raft nodes before linearized reading' (duration: 312.599769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:38.420024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.1073Z","time spent":"312.720672ms","remote":"127.0.0.1:44722","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-05-30T09:48:38.420197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.402494ms","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-05-30T09:48:38.420243Z","caller":"traceutil/trace.go:171","msg":"trace[232263768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1049; }","duration":"312.486807ms","start":"2026-05-30T09:48:38.107746Z","end":"2026-05-30T09:48:38.420233Z","steps":["trace[232263768] 'agreement among raft nodes before linearized reading' (duration: 312.355554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:38.420273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.107741Z","time spent":"312.524348ms","remote":"127.0.0.1:44722","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-05-30T09:48:38.935092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316210171888114,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:48:39.204927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.130345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:48:39.205277Z","caller":"traceutil/trace.go:171","msg":"trace[403710296] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1087; }","duration":"770.898906ms","start":"2026-05-30T09:48:38.434356Z","end":"2026-05-30T09:48:39.205255Z","steps":["trace[403710296] 'read index received' (duration: 600.427873ms)","trace[403710296] 'applied index is now lower than readState.Index' (duration: 170.469803ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:39.205332Z","caller":"traceutil/trace.go:171","msg":"trace[1412989124] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"780.960949ms","start":"2026-05-30T09:48:38.424289Z","end":"2026-05-30T09:48:39.20525Z","steps":["trace[1412989124] 'process raft request' (duration: 610.390143ms)","trace[1412989124] 'compare' (duration: 169.597052ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:39.2054Z","caller":"traceutil/trace.go:171","msg":"trace[2013806638] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"780.754744ms","start":"2026-05-30T09:48:38.424632Z","end":"2026-05-30T09:48:39.205387Z","steps":["trace[2013806638] 'process raft request' (duration: 780.412986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:39.205449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.424258Z","time spent":"781.134993ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:48:39.205501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.424609Z","time spent":"780.838625ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:48:39.205487Z","caller":"traceutil/trace.go:171","msg":"trace[1409214154] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"317.147211ms","start":"2026-05-30T09:48:38.88828Z","end":"2026-05-30T09:48:39.205427Z","steps":["trace[1409214154] 'process raft request' (duration: 316.926336ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:39.205346Z","caller":"traceutil/trace.go:171","msg":"trace[540855788] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"780.112348ms","start":"2026-05-30T09:48:38.425152Z","end":"2026-05-30T09:48:39.205264Z","steps":["trace[540855788] 'process raft request' (duration: 779.997505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:39.205905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.535231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-30T09:48:39.205954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.425136Z","time spent":"780.774994ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:48:39.205904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.884205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:48:39.206008Z","caller":"traceutil/trace.go:171","msg":"trace[1518979500] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:1053; }","duration":"771.638813ms","start":"2026-05-30T09:48:38.43435Z","end":"2026-05-30T09:48:39.205989Z","steps":["trace[1518979500] 'agreement among raft nodes before linearized reading' (duration: 771.07714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:39.206111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.434311Z","time spent":"771.783257ms","remote":"127.0.0.1:44520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"info","ts":"2026-05-30T09:48:39.206233Z","caller":"traceutil/trace.go:171","msg":"trace[1090387857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"545.105751ms","start":"2026-05-30T09:48:38.660937Z","end":"2026-05-30T09:48:39.206042Z","steps":["trace[1090387857] 'agreement among raft nodes before linearized reading' (duration: 544.873965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:39.206381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.660907Z","time spent":"545.437209ms","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:48:39.20631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:38.888225Z","time spent":"317.474089ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:48:39.529334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.219375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:48:39.529461Z","caller":"traceutil/trace.go:171","msg":"trace[402409625] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"253.829322ms","start":"2026-05-30T09:48:39.275611Z","end":"2026-05-30T09:48:39.52944Z","steps":["trace[402409625] 'process raft request' (duration: 54.365451ms)","trace[402409625] 'compare' (duration: 199.00514ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:39.951065Z","caller":"traceutil/trace.go:171","msg":"trace[10203340] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"150.208242ms","start":"2026-05-30T09:48:39.800829Z","end":"2026-05-30T09:48:39.951037Z","steps":["trace[10203340] 'process raft request' (duration: 149.974446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:41.332783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.272415ms","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-05-30T09:48:41.332818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.291395ms","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-05-30T09:48:41.332839Z","caller":"traceutil/trace.go:171","msg":"trace[139068427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1055; }","duration":"121.347147ms","start":"2026-05-30T09:48:41.211476Z","end":"2026-05-30T09:48:41.332823Z","steps":["trace[139068427] 'range keys from in-memory index tree' (duration: 121.108011ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:41.332849Z","caller":"traceutil/trace.go:171","msg":"trace[1503220279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1055; }","duration":"121.346687ms","start":"2026-05-30T09:48:41.211494Z","end":"2026-05-30T09:48:41.332841Z","steps":["trace[1503220279] 'range keys from in-memory index tree' (duration: 121.07482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:41.332851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234723362s","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-05-30T09:48:41.332898Z","caller":"traceutil/trace.go:171","msg":"trace[1589742475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1055; }","duration":"1.234811304s","start":"2026-05-30T09:48:40.098074Z","end":"2026-05-30T09:48:41.332885Z","steps":["trace[1589742475] 'range keys from in-memory index tree' (duration: 1.234534837s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:41.332945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:40.098044Z","time spent":"1.234889116s","remote":"127.0.0.1:44722","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-05-30T09:48:41.333129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.863141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:48:41.333258Z","caller":"traceutil/trace.go:171","msg":"trace[1994686671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"673.065756ms","start":"2026-05-30T09:48:40.660166Z","end":"2026-05-30T09:48:41.333231Z","steps":["trace[1994686671] 'range keys from in-memory index tree' (duration: 672.755219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:41.333348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:40.660144Z","time spent":"673.192229ms","remote":"127.0.0.1:44494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:48:41.333667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.206882ms","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-05-30T09:48:41.333712Z","caller":"traceutil/trace.go:171","msg":"trace[1150681920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"909.294374ms","start":"2026-05-30T09:48:40.424407Z","end":"2026-05-30T09:48:41.333701Z","steps":["trace[1150681920] 'range keys from in-memory index tree' (duration: 909.09084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:41.333742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:40.424383Z","time spent":"909.351566ms","remote":"127.0.0.1:44722","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-05-30T09:48:41.333956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.192317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:48:41.333993Z","caller":"traceutil/trace.go:171","msg":"trace[270216891] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1055; }","duration":"168.272189ms","start":"2026-05-30T09:48:41.165711Z","end":"2026-05-30T09:48:41.333983Z","steps":["trace[270216891] 'count revisions from in-memory index tree' (duration: 168.116496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:41.3345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046614477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:48:41.334558Z","caller":"traceutil/trace.go:171","msg":"trace[1218394765] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1055; }","duration":"1.046704958s","start":"2026-05-30T09:48:40.28784Z","end":"2026-05-30T09:48:41.334545Z","steps":["trace[1218394765] 'count revisions from in-memory index tree' (duration: 1.046492394s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:41.334605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:40.287824Z","time spent":"1.046769961s","remote":"127.0.0.1:44676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:48:41.332609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.79343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:48:41.334869Z","caller":"traceutil/trace.go:171","msg":"trace[874461859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"845.080965ms","start":"2026-05-30T09:48:40.489749Z","end":"2026-05-30T09:48:41.33483Z","steps":["trace[874461859] 'range keys from in-memory index tree' (duration: 842.640726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:41.333243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.648074ms","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-05-30T09:48:41.335133Z","caller":"traceutil/trace.go:171","msg":"trace[1352149329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1055; }","duration":"123.5521ms","start":"2026-05-30T09:48:41.211564Z","end":"2026-05-30T09:48:41.335116Z","steps":["trace[1352149329] 'range keys from in-memory index tree' (duration: 121.45553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:41.334982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:40.489731Z","time spent":"845.211578ms","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T09:48:41.363356Z","caller":"traceutil/trace.go:171","msg":"trace[782778519] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"151.489674ms","start":"2026-05-30T09:48:41.211839Z","end":"2026-05-30T09:48:41.363329Z","steps":["trace[782778519] 'process raft request' (duration: 151.32085ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:41.619855Z","caller":"traceutil/trace.go:171","msg":"trace[1360640736] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"280.611818ms","start":"2026-05-30T09:48:41.33922Z","end":"2026-05-30T09:48:41.619832Z","steps":["trace[1360640736] 'process raft request' (duration: 280.431624ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:41.61998Z","caller":"traceutil/trace.go:171","msg":"trace[459558549] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"280.008364ms","start":"2026-05-30T09:48:41.339965Z","end":"2026-05-30T09:48:41.619973Z","steps":["trace[459558549] 'process raft request' (duration: 279.833169ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:41.619855Z","caller":"traceutil/trace.go:171","msg":"trace[989680178] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"280.492775ms","start":"2026-05-30T09:48:41.33934Z","end":"2026-05-30T09:48:41.619833Z","steps":["trace[989680178] 'process raft request' (duration: 280.426714ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:41.620183Z","caller":"traceutil/trace.go:171","msg":"trace[1562207721] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"279.346938ms","start":"2026-05-30T09:48:41.340828Z","end":"2026-05-30T09:48:41.620175Z","steps":["trace[1562207721] 'process raft request' (duration: 279.313297ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:41.620221Z","caller":"traceutil/trace.go:171","msg":"trace[1469272935] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"280.184077ms","start":"2026-05-30T09:48:41.340022Z","end":"2026-05-30T09:48:41.620207Z","steps":["trace[1469272935] 'process raft request' (duration: 279.88925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:44.928842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271792438s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-30T09:48:44.928929Z","caller":"traceutil/trace.go:171","msg":"trace[906225526] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"1.305250175s","start":"2026-05-30T09:48:43.623665Z","end":"2026-05-30T09:48:44.928915Z","steps":["trace[906225526] 'read index received' (duration: 33.435436ms)","trace[906225526] 'applied index is now lower than readState.Index' (duration: 1.271813529s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:48:44.929494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305819468s","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-05-30T09:48:44.929521Z","caller":"traceutil/trace.go:171","msg":"trace[1783797314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"1.30589138s","start":"2026-05-30T09:48:43.623622Z","end":"2026-05-30T09:48:44.929513Z","steps":["trace[1783797314] 'agreement among raft nodes before linearized reading' (duration: 1.305746947s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:44.929545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:43.623601Z","time spent":"1.305938112s","remote":"127.0.0.1:44722","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-05-30T09:48:44.92973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30587489s","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-05-30T09:48:44.929751Z","caller":"traceutil/trace.go:171","msg":"trace[652804099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"1.305942442s","start":"2026-05-30T09:48:43.623802Z","end":"2026-05-30T09:48:44.929745Z","steps":["trace[652804099] 'agreement among raft nodes before linearized reading' (duration: 1.30588202s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:44.929769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:43.623777Z","time spent":"1.305987502s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T09:48:44.929916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.724032ms","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-05-30T09:48:44.929935Z","caller":"traceutil/trace.go:171","msg":"trace[1340500052] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1063; }","duration":"748.777903ms","start":"2026-05-30T09:48:44.181151Z","end":"2026-05-30T09:48:44.929929Z","steps":["trace[1340500052] 'agreement among raft nodes before linearized reading' (duration: 748.737142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:44.929953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:44.181112Z","time spent":"748.835465ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:48:44.930292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270324453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:48:44.930316Z","caller":"traceutil/trace.go:171","msg":"trace[1138633405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"1.270372143s","start":"2026-05-30T09:48:43.659935Z","end":"2026-05-30T09:48:44.930307Z","steps":["trace[1138633405] 'agreement among raft nodes before linearized reading' (duration: 1.270330992s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:44.930337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:43.659923Z","time spent":"1.270409355s","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:48:44.930648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306056694s","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-05-30T09:48:44.930677Z","caller":"traceutil/trace.go:171","msg":"trace[854367450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1063; }","duration":"1.306107645s","start":"2026-05-30T09:48:43.624563Z","end":"2026-05-30T09:48:44.93067Z","steps":["trace[854367450] 'agreement among raft nodes before linearized reading' (duration: 1.306033603s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:44.930695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:43.624551Z","time spent":"1.306138966s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T09:48:44.930851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30627408s","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-05-30T09:48:44.930941Z","caller":"traceutil/trace.go:171","msg":"trace[753877855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"1.306373722s","start":"2026-05-30T09:48:43.624545Z","end":"2026-05-30T09:48:44.930919Z","steps":["trace[753877855] 'agreement among raft nodes before linearized reading' (duration: 1.306131006s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:44.930952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307050248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-30T09:48:44.930994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:43.624513Z","time spent":"1.306467704s","remote":"127.0.0.1:44722","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-05-30T09:48:44.931027Z","caller":"traceutil/trace.go:171","msg":"trace[738705777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"1.307151621s","start":"2026-05-30T09:48:43.623858Z","end":"2026-05-30T09:48:44.93101Z","steps":["trace[738705777] 'agreement among raft nodes before linearized reading' (duration: 1.306447124s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:44.931068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:43.62385Z","time spent":"1.307210032s","remote":"127.0.0.1:44722","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-05-30T09:48:45.139636Z","caller":"traceutil/trace.go:171","msg":"trace[977980134] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"204.316469ms","start":"2026-05-30T09:48:44.935291Z","end":"2026-05-30T09:48:45.139608Z","steps":["trace[977980134] 'process raft request' (duration: 204.239807ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:45.139697Z","caller":"traceutil/trace.go:171","msg":"trace[1902550642] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"204.76415ms","start":"2026-05-30T09:48:44.934884Z","end":"2026-05-30T09:48:45.139648Z","steps":["trace[1902550642] 'process raft request' (duration: 158.720889ms)","trace[1902550642] 'compare' (duration: 45.802395ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:45.140081Z","caller":"traceutil/trace.go:171","msg":"trace[1471451237] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"203.978011ms","start":"2026-05-30T09:48:44.936089Z","end":"2026-05-30T09:48:45.140067Z","steps":["trace[1471451237] 'process raft request' (duration: 203.484639ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:45.140058Z","caller":"traceutil/trace.go:171","msg":"trace[1214535358] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1103; }","duration":"200.828205ms","start":"2026-05-30T09:48:44.939207Z","end":"2026-05-30T09:48:45.140035Z","steps":["trace[1214535358] 'read index received' (duration: 36.730976ms)","trace[1214535358] 'applied index is now lower than readState.Index' (duration: 164.095919ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:45.140131Z","caller":"traceutil/trace.go:171","msg":"trace[88723885] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"203.55992ms","start":"2026-05-30T09:48:44.936558Z","end":"2026-05-30T09:48:45.140118Z","steps":["trace[88723885] 'process raft request' (duration: 203.196591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:45.140231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.008519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:48:45.140913Z","caller":"traceutil/trace.go:171","msg":"trace[1927471526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"201.718806ms","start":"2026-05-30T09:48:44.939183Z","end":"2026-05-30T09:48:45.140902Z","steps":["trace[1927471526] 'agreement among raft nodes before linearized reading' (duration: 200.921277ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:55.576802Z","caller":"traceutil/trace.go:171","msg":"trace[787880781] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"143.241705ms","start":"2026-05-30T09:48:55.43354Z","end":"2026-05-30T09:48:55.576781Z","steps":["trace[787880781] 'process raft request' (duration: 143.097472ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:00.368794Z","caller":"traceutil/trace.go:171","msg":"trace[954992570] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"173.737052ms","start":"2026-05-30T09:49:00.195033Z","end":"2026-05-30T09:49:00.36877Z","steps":["trace[954992570] 'process raft request' (duration: 173.599588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:00.585108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.411932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:49:00.585173Z","caller":"traceutil/trace.go:171","msg":"trace[1169640854] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1167; }","duration":"120.495224ms","start":"2026-05-30T09:49:00.464662Z","end":"2026-05-30T09:49:00.585157Z","steps":["trace[1169640854] 'count revisions from in-memory index tree' (duration: 120.348249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:01.740941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316210171888387,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:49:02.242007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316210171888387,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:49:02.615382Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.531989647s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T09:49:02.61578Z","caller":"traceutil/trace.go:171","msg":"trace[578296581] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"1.532455728s","start":"2026-05-30T09:49:01.083294Z","end":"2026-05-30T09:49:02.61575Z","steps":["trace[578296581] 'process raft request' (duration: 1.532295174s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:02.615941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:01.08328Z","time spent":"1.532576911s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:02.674697Z","caller":"traceutil/trace.go:171","msg":"trace[973789962] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1212; }","duration":"1.434416273s","start":"2026-05-30T09:49:01.240256Z","end":"2026-05-30T09:49:02.674672Z","steps":["trace[973789962] 'read index received' (duration: 1.375302244s)","trace[973789962] 'applied index is now lower than readState.Index' (duration: 59.112659ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:02.674808Z","caller":"traceutil/trace.go:171","msg":"trace[143818656] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"1.024568602s","start":"2026-05-30T09:49:01.650226Z","end":"2026-05-30T09:49:02.674794Z","steps":["trace[143818656] 'process raft request' (duration: 1.024297805s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:02.674922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:01.650197Z","time spent":"1.024641014s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:02.675035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015200747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:49:02.675076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422576019s","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-05-30T09:49:02.675172Z","caller":"traceutil/trace.go:171","msg":"trace[1831349297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1169; }","duration":"1.422690302s","start":"2026-05-30T09:49:01.25245Z","end":"2026-05-30T09:49:02.67514Z","steps":["trace[1831349297] 'agreement among raft nodes before linearized reading' (duration: 1.422487527s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:02.675228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:01.252439Z","time spent":"1.422776494s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T09:49:02.675265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435026378s","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-05-30T09:49:02.675297Z","caller":"traceutil/trace.go:171","msg":"trace[2046830210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1169; }","duration":"1.43510598s","start":"2026-05-30T09:49:01.240182Z","end":"2026-05-30T09:49:02.675288Z","steps":["trace[2046830210] 'agreement among raft nodes before linearized reading' (duration: 1.434950076s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:02.675332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:01.240155Z","time spent":"1.435169652s","remote":"127.0.0.1:44722","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-05-30T09:49:02.675105Z","caller":"traceutil/trace.go:171","msg":"trace[888748385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1169; }","duration":"1.01531112s","start":"2026-05-30T09:49:01.659777Z","end":"2026-05-30T09:49:02.675088Z","steps":["trace[888748385] 'agreement among raft nodes before linearized reading' (duration: 1.015181187s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:02.675418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:01.659765Z","time spent":"1.015643558s","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:49:02.675431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.421721969s","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-05-30T09:49:02.675471Z","caller":"traceutil/trace.go:171","msg":"trace[184249401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1169; }","duration":"1.42178704s","start":"2026-05-30T09:49:01.253673Z","end":"2026-05-30T09:49:02.67546Z","steps":["trace[184249401] 'agreement among raft nodes before linearized reading' (duration: 1.421613756s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:02.675505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:01.253659Z","time spent":"1.421838602s","remote":"127.0.0.1:44722","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-05-30T09:49:02.675535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.946813ms","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-05-30T09:49:02.675566Z","caller":"traceutil/trace.go:171","msg":"trace[1724237779] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1169; }","duration":"632.007085ms","start":"2026-05-30T09:49:02.04355Z","end":"2026-05-30T09:49:02.675557Z","steps":["trace[1724237779] 'agreement among raft nodes before linearized reading' (duration: 631.952574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:02.675597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:02.043517Z","time spent":"632.069437ms","remote":"127.0.0.1:50052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:49:02.675714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419375702s","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-05-30T09:49:02.675745Z","caller":"traceutil/trace.go:171","msg":"trace[1148724981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1169; }","duration":"1.419415733s","start":"2026-05-30T09:49:01.256321Z","end":"2026-05-30T09:49:02.675737Z","steps":["trace[1148724981] 'agreement among raft nodes before linearized reading' (duration: 1.419330231s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:02.675775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:01.256315Z","time spent":"1.419451624s","remote":"127.0.0.1:44722","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-05-30T09:49:02.948923Z","caller":"traceutil/trace.go:171","msg":"trace[953351311] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"267.920984ms","start":"2026-05-30T09:49:02.680985Z","end":"2026-05-30T09:49:02.948906Z","steps":["trace[953351311] 'process raft request' (duration: 265.832994ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:02.949054Z","caller":"traceutil/trace.go:171","msg":"trace[234676499] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"267.990706ms","start":"2026-05-30T09:49:02.681059Z","end":"2026-05-30T09:49:02.949049Z","steps":["trace[234676499] 'process raft request' (duration: 267.76532ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:02.949119Z","caller":"traceutil/trace.go:171","msg":"trace[1623127932] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1214; }","duration":"264.254975ms","start":"2026-05-30T09:49:02.684858Z","end":"2026-05-30T09:49:02.949113Z","steps":["trace[1623127932] 'read index received' (duration: 261.97002ms)","trace[1623127932] 'applied index is now lower than readState.Index' (duration: 2.284675ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:02.949209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.335277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:02.949231Z","caller":"traceutil/trace.go:171","msg":"trace[601062828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1173; }","duration":"264.388139ms","start":"2026-05-30T09:49:02.684838Z","end":"2026-05-30T09:49:02.949226Z","steps":["trace[601062828] 'agreement among raft nodes before linearized reading' (duration: 264.309987ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:02.949167Z","caller":"traceutil/trace.go:171","msg":"trace[659023919] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"268.023916ms","start":"2026-05-30T09:49:02.681119Z","end":"2026-05-30T09:49:02.949143Z","steps":["trace[659023919] 'process raft request' (duration: 267.735699ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:02.949335Z","caller":"traceutil/trace.go:171","msg":"trace[27132688] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"268.194651ms","start":"2026-05-30T09:49:02.681135Z","end":"2026-05-30T09:49:02.94933Z","steps":["trace[27132688] 'process raft request' (duration: 267.73576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:03.5862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.00939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:03.586265Z","caller":"traceutil/trace.go:171","msg":"trace[1106446811] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1173; }","duration":"244.123732ms","start":"2026-05-30T09:49:03.342127Z","end":"2026-05-30T09:49:03.586251Z","steps":["trace[1106446811] 'count revisions from in-memory index tree' (duration: 243.945498ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:04.799197Z","caller":"traceutil/trace.go:171","msg":"trace[1876333196] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"118.307421ms","start":"2026-05-30T09:49:04.680851Z","end":"2026-05-30T09:49:04.799158Z","steps":["trace[1876333196] 'process raft request' (duration: 114.047129ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:10.694032Z","caller":"traceutil/trace.go:171","msg":"trace[1342508462] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"104.309405ms","start":"2026-05-30T09:49:10.589703Z","end":"2026-05-30T09:49:10.694012Z","steps":["trace[1342508462] 'process raft request' (duration: 104.189932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:10.923698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.375083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:49:10.92379Z","caller":"traceutil/trace.go:171","msg":"trace[1315712637] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"199.270255ms","start":"2026-05-30T09:49:10.724502Z","end":"2026-05-30T09:49:10.923772Z","steps":["trace[1315712637] 'process raft request' (duration: 80.723898ms)","trace[1315712637] 'compare' (duration: 118.279101ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:10.92547Z","caller":"traceutil/trace.go:171","msg":"trace[1899668698] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"100.932844ms","start":"2026-05-30T09:49:10.824527Z","end":"2026-05-30T09:49:10.92546Z","steps":["trace[1899668698] 'process raft request' (duration: 100.886093ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:21.565964Z","caller":"traceutil/trace.go:171","msg":"trace[1452458764] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"104.277413ms","start":"2026-05-30T09:49:21.461661Z","end":"2026-05-30T09:49:21.565938Z","steps":["trace[1452458764] 'process raft request' (duration: 104.12374ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:22.333842Z","caller":"traceutil/trace.go:171","msg":"trace[1189723920] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"464.907873ms","start":"2026-05-30T09:49:21.868902Z","end":"2026-05-30T09:49:22.33381Z","steps":["trace[1189723920] 'process raft request' (duration: 464.76623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:22.33401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:21.868877Z","time spent":"465.057057ms","remote":"127.0.0.1:44666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:22.954886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.032177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:49:22.954977Z","caller":"traceutil/trace.go:171","msg":"trace[1585149592] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"613.85121ms","start":"2026-05-30T09:49:22.341111Z","end":"2026-05-30T09:49:22.954962Z","steps":["trace[1585149592] 'process raft request' (duration: 387.667129ms)","trace[1585149592] 'compare' (duration: 225.343981ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:22.95503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:22.341088Z","time spent":"613.925852ms","remote":"127.0.0.1:44648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:24.284195Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.55543204s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T09:49:24.284733Z","caller":"traceutil/trace.go:171","msg":"trace[767712700] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"1.943523168s","start":"2026-05-30T09:49:22.341189Z","end":"2026-05-30T09:49:24.284712Z","steps":["trace[767712700] 'process raft request' (duration: 1.943341434s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.28487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:22.341174Z","time spent":"1.943621891s","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:24.284949Z","caller":"traceutil/trace.go:171","msg":"trace[1750264931] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"1.940279521s","start":"2026-05-30T09:49:22.344634Z","end":"2026-05-30T09:49:24.284913Z","steps":["trace[1750264931] 'process raft request' (duration: 1.940034035s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.285088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:22.344623Z","time spent":"1.940385673s","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:24.284949Z","caller":"traceutil/trace.go:171","msg":"trace[435858714] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1299; }","duration":"1.940634069s","start":"2026-05-30T09:49:22.344257Z","end":"2026-05-30T09:49:24.284891Z","steps":["trace[435858714] 'read index received' (duration: 384.547214ms)","trace[435858714] 'applied index is now lower than readState.Index' (duration: 1.556084645s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:24.285101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.940838614s","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-05-30T09:49:24.285236Z","caller":"traceutil/trace.go:171","msg":"trace[621109553] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1253; }","duration":"1.941027468s","start":"2026-05-30T09:49:22.344197Z","end":"2026-05-30T09:49:24.285225Z","steps":["trace[621109553] 'agreement among raft nodes before linearized reading' (duration: 1.940788213s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.285345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:22.344165Z","time spent":"1.941166542s","remote":"127.0.0.1:44710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:49:24.343823Z","caller":"traceutil/trace.go:171","msg":"trace[1971897527] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"1.372735642s","start":"2026-05-30T09:49:22.971062Z","end":"2026-05-30T09:49:24.343798Z","steps":["trace[1971897527] 'process raft request' (duration: 1.372347013s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.344027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:22.971033Z","time spent":"1.372909337s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:24.433133Z","caller":"traceutil/trace.go:171","msg":"trace[1701027644] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"147.933243ms","start":"2026-05-30T09:49:24.285174Z","end":"2026-05-30T09:49:24.433107Z","steps":["trace[1701027644] 'read index received' (duration: 58.164507ms)","trace[1701027644] 'applied index is now lower than readState.Index' (duration: 89.767436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:24.433276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.787693577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:24.433349Z","caller":"traceutil/trace.go:171","msg":"trace[330253523] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1254; }","duration":"1.78782375s","start":"2026-05-30T09:49:22.645515Z","end":"2026-05-30T09:49:24.433339Z","steps":["trace[330253523] 'agreement among raft nodes before linearized reading' (duration: 1.787699717s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.433392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:22.645496Z","time spent":"1.787885741s","remote":"127.0.0.1:44622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:49:24.433931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.561738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:24.433976Z","caller":"traceutil/trace.go:171","msg":"trace[484379233] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1254; }","duration":"607.65288ms","start":"2026-05-30T09:49:23.826312Z","end":"2026-05-30T09:49:24.433965Z","steps":["trace[484379233] 'agreement among raft nodes before linearized reading' (duration: 607.550558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.434007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:23.826278Z","time spent":"607.720482ms","remote":"127.0.0.1:50200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:49:24.434167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614450877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:49:24.434209Z","caller":"traceutil/trace.go:171","msg":"trace[591785101] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1254; }","duration":"1.614524009s","start":"2026-05-30T09:49:22.819677Z","end":"2026-05-30T09:49:24.434201Z","steps":["trace[591785101] 'agreement among raft nodes before linearized reading' (duration: 1.614462148s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.434236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:22.819663Z","time spent":"1.61456614s","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":31,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:49:24.4343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456822081s","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-05-30T09:49:24.43438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773307801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:24.434414Z","caller":"traceutil/trace.go:171","msg":"trace[85422593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"1.773364183s","start":"2026-05-30T09:49:22.661042Z","end":"2026-05-30T09:49:24.434406Z","steps":["trace[85422593] 'agreement among raft nodes before linearized reading' (duration: 1.773317762s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.434444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:22.661024Z","time spent":"1.773414544s","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:49:24.434443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276350088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:49:24.434475Z","caller":"traceutil/trace.go:171","msg":"trace[993845963] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1254; }","duration":"1.27643485s","start":"2026-05-30T09:49:23.15803Z","end":"2026-05-30T09:49:24.434465Z","steps":["trace[993845963] 'agreement among raft nodes before linearized reading' (duration: 1.276375939s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.434513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:23.158009Z","time spent":"1.276495821s","remote":"127.0.0.1:44966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:49:24.434381Z","caller":"traceutil/trace.go:171","msg":"trace[30980914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1254; }","duration":"1.456955665s","start":"2026-05-30T09:49:22.977406Z","end":"2026-05-30T09:49:24.434362Z","steps":["trace[30980914] 'agreement among raft nodes before linearized reading' (duration: 1.456698639s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.434747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:22.977394Z","time spent":"1.457338474s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T09:49:24.434772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295345484s","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-05-30T09:49:24.434801Z","caller":"traceutil/trace.go:171","msg":"trace[1330751877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1254; }","duration":"1.295395815s","start":"2026-05-30T09:49:23.139396Z","end":"2026-05-30T09:49:24.434792Z","steps":["trace[1330751877] 'agreement among raft nodes before linearized reading' (duration: 1.295314643s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.434827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:23.13938Z","time spent":"1.295440526s","remote":"127.0.0.1:44722","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-05-30T09:49:24.434994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296651446s","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-05-30T09:49:24.435028Z","caller":"traceutil/trace.go:171","msg":"trace[387992358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1254; }","duration":"1.296705277s","start":"2026-05-30T09:49:23.138315Z","end":"2026-05-30T09:49:24.43502Z","steps":["trace[387992358] 'agreement among raft nodes before linearized reading' (duration: 1.296625415s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.435052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:23.138303Z","time spent":"1.296743467s","remote":"127.0.0.1:44722","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-05-30T09:49:24.435202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358517941s","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-05-30T09:49:24.435225Z","caller":"traceutil/trace.go:171","msg":"trace[9869151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"1.358568762s","start":"2026-05-30T09:49:23.076649Z","end":"2026-05-30T09:49:24.435218Z","steps":["trace[9869151] 'agreement among raft nodes before linearized reading' (duration: 1.358504531s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.435252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:23.076631Z","time spent":"1.358611353s","remote":"127.0.0.1:44722","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-05-30T09:49:24.435435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.362763113s","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-05-30T09:49:24.435461Z","caller":"traceutil/trace.go:171","msg":"trace[1530192971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1254; }","duration":"1.362829565s","start":"2026-05-30T09:49:23.072624Z","end":"2026-05-30T09:49:24.435453Z","steps":["trace[1530192971] 'agreement among raft nodes before linearized reading' (duration: 1.362742463s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:24.435485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:23.072596Z","time spent":"1.362883176s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-30T09:49:27.074113Z","caller":"traceutil/trace.go:171","msg":"trace[1604257634] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"142.195844ms","start":"2026-05-30T09:49:26.931886Z","end":"2026-05-30T09:49:27.074082Z","steps":["trace[1604257634] 'process raft request' (duration: 129.140361ms)","trace[1604257634] 'compare' (duration: 12.93289ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:28.577242Z","caller":"traceutil/trace.go:171","msg":"trace[1656945796] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"139.874899ms","start":"2026-05-30T09:49:28.437347Z","end":"2026-05-30T09:49:28.577221Z","steps":["trace[1656945796] 'read index received' (duration: 139.785306ms)","trace[1656945796] 'applied index is now lower than readState.Index' (duration: 88.743µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:28.57738Z","caller":"traceutil/trace.go:171","msg":"trace[425717980] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"195.276289ms","start":"2026-05-30T09:49:28.382079Z","end":"2026-05-30T09:49:28.577355Z","steps":["trace[425717980] 'process raft request' (duration: 195.020623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:28.57746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.101244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-30T09:49:28.577499Z","caller":"traceutil/trace.go:171","msg":"trace[448478192] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:1281; }","duration":"140.156935ms","start":"2026-05-30T09:49:28.437332Z","end":"2026-05-30T09:49:28.577489Z","steps":["trace[448478192] 'agreement among raft nodes before linearized reading' (duration: 140.024022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:28.577575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.28474ms","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-05-30T09:49:28.577644Z","caller":"traceutil/trace.go:171","msg":"trace[762810235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"103.391512ms","start":"2026-05-30T09:49:28.474234Z","end":"2026-05-30T09:49:28.577625Z","steps":["trace[762810235] 'agreement among raft nodes before linearized reading' (duration: 103.186977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:29.160488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316210171888625,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:49:29.658005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:28.578369Z","time spent":"1.079629095s","remote":"127.0.0.1:44520","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-30T09:49:30.044043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.523869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:49:30.044455Z","caller":"traceutil/trace.go:171","msg":"trace[465973155] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"1.462429647s","start":"2026-05-30T09:49:28.581982Z","end":"2026-05-30T09:49:30.044412Z","steps":["trace[465973155] 'process raft request' (duration: 1.462173781s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:30.044467Z","caller":"traceutil/trace.go:171","msg":"trace[1515027735] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"1.462698654s","start":"2026-05-30T09:49:28.581724Z","end":"2026-05-30T09:49:30.044423Z","steps":["trace[1515027735] 'process raft request' (duration: 1.245674402s)","trace[1515027735] 'compare' (duration: 216.233233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:30.044581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:28.581972Z","time spent":"1.46255234s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:30.044619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:28.581706Z","time spent":"1.462833306s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:30.044654Z","caller":"traceutil/trace.go:171","msg":"trace[1716092086] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1331; }","duration":"1.384951787s","start":"2026-05-30T09:49:28.659685Z","end":"2026-05-30T09:49:30.044636Z","steps":["trace[1716092086] 'read index received' (duration: 922.546953ms)","trace[1716092086] 'applied index is now lower than readState.Index' (duration: 462.403754ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:30.044726Z","caller":"traceutil/trace.go:171","msg":"trace[860064715] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"1.462648832s","start":"2026-05-30T09:49:28.582045Z","end":"2026-05-30T09:49:30.044694Z","steps":["trace[860064715] 'process raft request' (duration: 1.462307844s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:30.044763Z","caller":"traceutil/trace.go:171","msg":"trace[21352112] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"1.462728915s","start":"2026-05-30T09:49:28.582024Z","end":"2026-05-30T09:49:30.044753Z","steps":["trace[21352112] 'process raft request' (duration: 1.462275774s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:30.04477Z","caller":"traceutil/trace.go:171","msg":"trace[987424985] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.462692604s","start":"2026-05-30T09:49:28.582062Z","end":"2026-05-30T09:49:30.044754Z","steps":["trace[987424985] 'process raft request' (duration: 1.462456638s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:30.044812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:28.582012Z","time spent":"1.462771685s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:30.044827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:28.582038Z","time spent":"1.462743195s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:30.044805Z","caller":"traceutil/trace.go:171","msg":"trace[514283277] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"385.547089ms","start":"2026-05-30T09:49:29.659211Z","end":"2026-05-30T09:49:30.044758Z","steps":["trace[514283277] 'process raft request' (duration: 385.374115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:30.044985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385293855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:49:30.044997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.542797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:30.045042Z","caller":"traceutil/trace.go:171","msg":"trace[2014390220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"1.385371307s","start":"2026-05-30T09:49:28.659654Z","end":"2026-05-30T09:49:30.045025Z","steps":["trace[2014390220] 'agreement among raft nodes before linearized reading' (duration: 1.385226614s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:30.045035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:29.659187Z","time spent":"385.721062ms","remote":"127.0.0.1:44520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:30.045083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:28.659641Z","time spent":"1.385432758s","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:49:30.04485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:28.58204Z","time spent":"1.462762575s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:30.045045Z","caller":"traceutil/trace.go:171","msg":"trace[1380882045] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1287; }","duration":"492.593088ms","start":"2026-05-30T09:49:29.552438Z","end":"2026-05-30T09:49:30.045031Z","steps":["trace[1380882045] 'agreement among raft nodes before linearized reading' (duration: 492.516226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:30.045185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.256379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-05-30T09:49:30.045196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:29.552379Z","time spent":"492.803804ms","remote":"127.0.0.1:44742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:49:30.045228Z","caller":"traceutil/trace.go:171","msg":"trace[1173366426] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1287; }","duration":"362.347491ms","start":"2026-05-30T09:49:29.682868Z","end":"2026-05-30T09:49:30.045216Z","steps":["trace[1173366426] 'agreement among raft nodes before linearized reading' (duration: 362.202077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:30.045278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:29.682847Z","time spent":"362.420893ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-30T09:49:30.459344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.036317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:49:30.459542Z","caller":"traceutil/trace.go:171","msg":"trace[300370907] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"408.020578ms","start":"2026-05-30T09:49:30.051504Z","end":"2026-05-30T09:49:30.459525Z","steps":["trace[300370907] 'read index received' (duration: 119.625483ms)","trace[300370907] 'applied index is now lower than readState.Index' (duration: 288.393845ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:30.459625Z","caller":"traceutil/trace.go:171","msg":"trace[1376462702] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"408.648433ms","start":"2026-05-30T09:49:30.050943Z","end":"2026-05-30T09:49:30.459591Z","steps":["trace[1376462702] 'process raft request' (duration: 120.291339ms)","trace[1376462702] 'compare' (duration: 287.921023ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:30.459718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:30.05092Z","time spent":"408.751036ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:30.459727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.490333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:49:30.459763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.229343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-30T09:49:30.459865Z","caller":"traceutil/trace.go:171","msg":"trace[1444745132] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1288; }","duration":"408.353156ms","start":"2026-05-30T09:49:30.05148Z","end":"2026-05-30T09:49:30.459833Z","steps":["trace[1444745132] 'agreement among raft nodes before linearized reading' (duration: 408.13815ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:30.459862Z","caller":"traceutil/trace.go:171","msg":"trace[1345183683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"404.632727ms","start":"2026-05-30T09:49:30.055198Z","end":"2026-05-30T09:49:30.459831Z","steps":["trace[1345183683] 'agreement among raft nodes before linearized reading' (duration: 404.458822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:30.459918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:30.051469Z","time spent":"408.440397ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-30T09:49:30.459958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:30.055188Z","time spent":"404.737519ms","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T09:49:30.771397Z","caller":"traceutil/trace.go:171","msg":"trace[1485966273] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"188.003835ms","start":"2026-05-30T09:49:30.583374Z","end":"2026-05-30T09:49:30.771377Z","steps":["trace[1485966273] 'process raft request' (duration: 187.886002ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:32.749022Z","caller":"traceutil/trace.go:171","msg":"trace[346881792] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"696.009823ms","start":"2026-05-30T09:49:32.052967Z","end":"2026-05-30T09:49:32.748976Z","steps":["trace[346881792] 'process raft request' (duration: 695.782397ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:32.749229Z","caller":"traceutil/trace.go:171","msg":"trace[890916368] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"696.078335ms","start":"2026-05-30T09:49:32.053126Z","end":"2026-05-30T09:49:32.749205Z","steps":["trace[890916368] 'process raft request' (duration: 695.804499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:32.749166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:32.05294Z","time spent":"696.151137ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:32.749404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:32.053118Z","time spent":"696.208748ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:32.749787Z","caller":"traceutil/trace.go:171","msg":"trace[671921305] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"696.326561ms","start":"2026-05-30T09:49:32.05341Z","end":"2026-05-30T09:49:32.749736Z","steps":["trace[671921305] 'process raft request' (duration: 695.692526ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:32.749993Z","caller":"traceutil/trace.go:171","msg":"trace[302474890] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"696.034994ms","start":"2026-05-30T09:49:32.053942Z","end":"2026-05-30T09:49:32.749977Z","steps":["trace[302474890] 'process raft request' (duration: 695.652185ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:32.749937Z","caller":"traceutil/trace.go:171","msg":"trace[368449128] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1342; }","duration":"662.043388ms","start":"2026-05-30T09:49:32.087836Z","end":"2026-05-30T09:49:32.74988Z","steps":["trace[368449128] 'read index received' (duration: 36.311032ms)","trace[368449128] 'applied index is now lower than readState.Index' (duration: 625.731266ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:32.750008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:32.053401Z","time spent":"696.493795ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:32.750167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:32.053929Z","time spent":"696.109445ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:32.750182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.338974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-30T09:49:32.750231Z","caller":"traceutil/trace.go:171","msg":"trace[986850045] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1296; }","duration":"662.420907ms","start":"2026-05-30T09:49:32.087792Z","end":"2026-05-30T09:49:32.750213Z","steps":["trace[986850045] 'agreement among raft nodes before linearized reading' (duration: 662.290853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:32.750276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:32.087773Z","time spent":"662.495949ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-30T09:49:32.750289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.665693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:49:32.750387Z","caller":"traceutil/trace.go:171","msg":"trace[287130965] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1296; }","duration":"211.808406ms","start":"2026-05-30T09:49:32.538519Z","end":"2026-05-30T09:49:32.750327Z","steps":["trace[287130965] 'agreement among raft nodes before linearized reading' (duration: 211.618732ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:33.05496Z","caller":"traceutil/trace.go:171","msg":"trace[1194372289] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"155.473694ms","start":"2026-05-30T09:49:32.89946Z","end":"2026-05-30T09:49:33.054934Z","steps":["trace[1194372289] 'process raft request' (duration: 155.152366ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:34.411612Z","caller":"traceutil/trace.go:171","msg":"trace[1527000043] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"280.372962ms","start":"2026-05-30T09:49:34.131192Z","end":"2026-05-30T09:49:34.411565Z","steps":["trace[1527000043] 'process raft request' (duration: 280.030374ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:34.852283Z","caller":"traceutil/trace.go:171","msg":"trace[115152207] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"191.710863ms","start":"2026-05-30T09:49:34.660551Z","end":"2026-05-30T09:49:34.852262Z","steps":["trace[115152207] 'read index received' (duration: 191.489088ms)","trace[115152207] 'applied index is now lower than readState.Index' (duration: 220.415µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:34.852387Z","caller":"traceutil/trace.go:171","msg":"trace[834440156] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"248.015775ms","start":"2026-05-30T09:49:34.604315Z","end":"2026-05-30T09:49:34.852331Z","steps":["trace[834440156] 'process raft request' (duration: 247.758908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:34.852447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.888317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:34.852482Z","caller":"traceutil/trace.go:171","msg":"trace[1946586610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"191.977469ms","start":"2026-05-30T09:49:34.660494Z","end":"2026-05-30T09:49:34.852471Z","steps":["trace[1946586610] 'agreement among raft nodes before linearized reading' (duration: 191.880257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:35.032126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.978889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:49:35.032241Z","caller":"traceutil/trace.go:171","msg":"trace[1941143966] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"179.874699ms","start":"2026-05-30T09:49:34.852349Z","end":"2026-05-30T09:49:35.032224Z","steps":["trace[1941143966] 'read index received' (duration: 57.790548ms)","trace[1941143966] 'applied index is now lower than readState.Index' (duration: 122.082431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:35.0324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.499776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:49:35.032471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.072207ms","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-05-30T09:49:35.032411Z","caller":"traceutil/trace.go:171","msg":"trace[1005020946] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"209.43277ms","start":"2026-05-30T09:49:34.822923Z","end":"2026-05-30T09:49:35.032356Z","steps":["trace[1005020946] 'process raft request' (duration: 87.116182ms)","trace[1005020946] 'compare' (duration: 121.737954ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:35.0325Z","caller":"traceutil/trace.go:171","msg":"trace[1125896168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"278.141859ms","start":"2026-05-30T09:49:34.754349Z","end":"2026-05-30T09:49:35.032491Z","steps":["trace[1125896168] 'agreement among raft nodes before linearized reading' (duration: 277.974715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:35.032582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.466717ms","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-05-30T09:49:35.032613Z","caller":"traceutil/trace.go:171","msg":"trace[82655681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"278.534129ms","start":"2026-05-30T09:49:34.754072Z","end":"2026-05-30T09:49:35.032606Z","steps":["trace[82655681] 'agreement among raft nodes before linearized reading' (duration: 278.438276ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:35.032485Z","caller":"traceutil/trace.go:171","msg":"trace[940911119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"178.612209ms","start":"2026-05-30T09:49:34.853854Z","end":"2026-05-30T09:49:35.032466Z","steps":["trace[940911119] 'agreement among raft nodes before linearized reading' (duration: 178.497606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:35.032424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.612447ms","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":"warn","ts":"2026-05-30T09:49:35.032687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.79335ms","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-05-30T09:49:35.032884Z","caller":"traceutil/trace.go:171","msg":"trace[248295035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"278.041697ms","start":"2026-05-30T09:49:34.754823Z","end":"2026-05-30T09:49:35.032865Z","steps":["trace[248295035] 'agreement among raft nodes before linearized reading' (duration: 277.645307ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:35.032722Z","caller":"traceutil/trace.go:171","msg":"trace[1690329545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"277.955715ms","start":"2026-05-30T09:49:34.754752Z","end":"2026-05-30T09:49:35.032708Z","steps":["trace[1690329545] 'agreement among raft nodes before linearized reading' (duration: 277.549635ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:35.34519Z","caller":"traceutil/trace.go:171","msg":"trace[67113314] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"307.887274ms","start":"2026-05-30T09:49:35.037271Z","end":"2026-05-30T09:49:35.345159Z","steps":["trace[67113314] 'process raft request' (duration: 288.251742ms)","trace[67113314] 'compare' (duration: 19.332034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:35.345369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:35.037248Z","time spent":"308.018496ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:35.352915Z","caller":"traceutil/trace.go:171","msg":"trace[718775166] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"315.179919ms","start":"2026-05-30T09:49:35.037719Z","end":"2026-05-30T09:49:35.352899Z","steps":["trace[718775166] 'process raft request' (duration: 315.031195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:35.352999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:35.037694Z","time spent":"315.26489ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:35.353448Z","caller":"traceutil/trace.go:171","msg":"trace[1038189156] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"314.450601ms","start":"2026-05-30T09:49:35.038978Z","end":"2026-05-30T09:49:35.353428Z","steps":["trace[1038189156] 'process raft request' (duration: 314.288987ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:35.3535Z","caller":"traceutil/trace.go:171","msg":"trace[695387742] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"315.639999ms","start":"2026-05-30T09:49:35.037834Z","end":"2026-05-30T09:49:35.353474Z","steps":["trace[695387742] 'process raft request' (duration: 315.031705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:35.353596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:35.038955Z","time spent":"314.585824ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:35.353688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:35.037825Z","time spent":"315.735161ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:49:36.503893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.458447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:49:36.503975Z","caller":"traceutil/trace.go:171","msg":"trace[49719403] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1311; }","duration":"729.58656ms","start":"2026-05-30T09:49:35.774369Z","end":"2026-05-30T09:49:36.503955Z","steps":["trace[49719403] 'count revisions from in-memory index tree' (duration: 729.350694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:36.504013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:35.774347Z","time spent":"729.654201ms","remote":"127.0.0.1:50136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:49:36.504242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040608878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-30T09:49:36.504314Z","caller":"traceutil/trace.go:171","msg":"trace[624981272] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1311; }","duration":"1.040779172s","start":"2026-05-30T09:49:35.463517Z","end":"2026-05-30T09:49:36.504296Z","steps":["trace[624981272] 'range keys from in-memory index tree' (duration: 1.040373083s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:36.504355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:35.463494Z","time spent":"1.040851625s","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-30T09:49:36.751926Z","caller":"traceutil/trace.go:171","msg":"trace[2030543702] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"243.778284ms","start":"2026-05-30T09:49:36.508125Z","end":"2026-05-30T09:49:36.751904Z","steps":["trace[2030543702] 'process raft request' (duration: 243.575619ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:36.876417Z","caller":"traceutil/trace.go:171","msg":"trace[208038240] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"368.12417ms","start":"2026-05-30T09:49:36.508257Z","end":"2026-05-30T09:49:36.876381Z","steps":["trace[208038240] 'process raft request' (duration: 314.265967ms)","trace[208038240] 'compare' (duration: 53.635248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:36.876535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.914189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:36.876589Z","caller":"traceutil/trace.go:171","msg":"trace[1806386859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"217.022651ms","start":"2026-05-30T09:49:36.659556Z","end":"2026-05-30T09:49:36.876578Z","steps":["trace[1806386859] 'agreement among raft nodes before linearized reading' (duration: 216.903048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:36.876809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:49:36.508231Z","time spent":"368.253753ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:49:36.876389Z","caller":"traceutil/trace.go:171","msg":"trace[269137290] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1363; }","duration":"216.727404ms","start":"2026-05-30T09:49:36.65961Z","end":"2026-05-30T09:49:36.876338Z","steps":["trace[269137290] 'read index received' (duration: 92.128262ms)","trace[269137290] 'applied index is now lower than readState.Index' (duration: 124.598082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:36.87723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.639968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-30T09:49:36.877276Z","caller":"traceutil/trace.go:171","msg":"trace[432438097] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1313; }","duration":"130.703609ms","start":"2026-05-30T09:49:36.746562Z","end":"2026-05-30T09:49:36.877266Z","steps":["trace[432438097] 'agreement among raft nodes before linearized reading' (duration: 130.628877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:36.877696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.14329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:43590"} {"level":"info","ts":"2026-05-30T09:49:36.877742Z","caller":"traceutil/trace.go:171","msg":"trace[97999723] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1313; }","duration":"131.220681ms","start":"2026-05-30T09:49:36.746508Z","end":"2026-05-30T09:49:36.877729Z","steps":["trace[97999723] 'agreement among raft nodes before linearized reading' (duration: 130.561695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:01.685599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.715923ms","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-05-30T09:50:01.685649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.953553ms","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-05-30T09:50:01.685697Z","caller":"traceutil/trace.go:171","msg":"trace[325472364] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1444; }","duration":"121.852966ms","start":"2026-05-30T09:50:01.563819Z","end":"2026-05-30T09:50:01.685672Z","steps":["trace[325472364] 'range keys from in-memory index tree' (duration: 121.552799ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:01.68571Z","caller":"traceutil/trace.go:171","msg":"trace[1701073010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1444; }","duration":"128.049375ms","start":"2026-05-30T09:50:01.557648Z","end":"2026-05-30T09:50:01.685697Z","steps":["trace[1701073010] 'range keys from in-memory index tree' (duration: 127.821949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:01.685898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.646834ms","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-05-30T09:50:01.685942Z","caller":"traceutil/trace.go:171","msg":"trace[623691833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1444; }","duration":"124.727895ms","start":"2026-05-30T09:50:01.561201Z","end":"2026-05-30T09:50:01.685929Z","steps":["trace[623691833] 'range keys from in-memory index tree' (duration: 124.424408ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:49.681082Z","caller":"traceutil/trace.go:171","msg":"trace[96166530] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"117.81214ms","start":"2026-05-30T09:50:49.563218Z","end":"2026-05-30T09:50:49.681031Z","steps":["trace[96166530] 'process raft request' (duration: 117.589324ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:54.142065Z","caller":"traceutil/trace.go:171","msg":"trace[611027160] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"116.16341ms","start":"2026-05-30T09:50:54.02587Z","end":"2026-05-30T09:50:54.142033Z","steps":["trace[611027160] 'process raft request' (duration: 105.509265ms)","trace[611027160] 'compare' (duration: 10.505681ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:54.186235Z","caller":"traceutil/trace.go:171","msg":"trace[1764743351] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"159.923257ms","start":"2026-05-30T09:50:54.026278Z","end":"2026-05-30T09:50:54.186201Z","steps":["trace[1764743351] 'process raft request' (duration: 159.764423ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:10.769485Z","caller":"traceutil/trace.go:171","msg":"trace[449978755] linearizableReadLoop","detail":"{readStateIndex:1832; appliedIndex:1831; }","duration":"108.01117ms","start":"2026-05-30T09:51:10.661454Z","end":"2026-05-30T09:51:10.769465Z","steps":["trace[449978755] 'read index received' (duration: 65.341987ms)","trace[449978755] 'applied index is now lower than readState.Index' (duration: 42.668063ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:10.769566Z","caller":"traceutil/trace.go:171","msg":"trace[80604222] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"238.465197ms","start":"2026-05-30T09:51:10.531071Z","end":"2026-05-30T09:51:10.769537Z","steps":["trace[80604222] 'process raft request' (duration: 195.786523ms)","trace[80604222] 'compare' (duration: 42.51433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:10.769855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.182004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:10.769919Z","caller":"traceutil/trace.go:171","msg":"trace[541353684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1761; }","duration":"108.518102ms","start":"2026-05-30T09:51:10.661384Z","end":"2026-05-30T09:51:10.769902Z","steps":["trace[541353684] 'agreement among raft nodes before linearized reading' (duration: 108.207424ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:57.926037Z","caller":"traceutil/trace.go:171","msg":"trace[576757944] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"103.191252ms","start":"2026-05-30T09:51:57.822803Z","end":"2026-05-30T09:51:57.925995Z","steps":["trace[576757944] 'process raft request' (duration: 61.813471ms)","trace[576757944] 'compare' (duration: 41.122645ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:11.232468Z","caller":"traceutil/trace.go:171","msg":"trace[1192561682] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"101.912203ms","start":"2026-05-30T09:52:11.130533Z","end":"2026-05-30T09:52:11.232445Z","steps":["trace[1192561682] 'process raft request' (duration: 101.77553ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:33.625256Z","caller":"traceutil/trace.go:171","msg":"trace[742689924] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"108.580208ms","start":"2026-05-30T09:52:33.516647Z","end":"2026-05-30T09:52:33.625228Z","steps":["trace[742689924] 'process raft request' (duration: 73.388406ms)","trace[742689924] 'compare' (duration: 35.054389ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:45.812619Z","caller":"traceutil/trace.go:171","msg":"trace[155881609] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2285; }","duration":"151.783921ms","start":"2026-05-30T09:52:45.660813Z","end":"2026-05-30T09:52:45.812597Z","steps":["trace[155881609] 'read index received' (duration: 151.602567ms)","trace[155881609] 'applied index is now lower than readState.Index' (duration: 180.544µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:45.812729Z","caller":"traceutil/trace.go:171","msg":"trace[1601192092] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"171.115884ms","start":"2026-05-30T09:52:45.641562Z","end":"2026-05-30T09:52:45.812678Z","steps":["trace[1601192092] 'process raft request' (duration: 170.891889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:45.812779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.950056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:45.813136Z","caller":"traceutil/trace.go:171","msg":"trace[1776425366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2196; }","duration":"152.344785ms","start":"2026-05-30T09:52:45.660755Z","end":"2026-05-30T09:52:45.8131Z","steps":["trace[1776425366] 'agreement among raft nodes before linearized reading' (duration: 151.929616ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:02.495409Z","caller":"traceutil/trace.go:171","msg":"trace[1100034410] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"173.642355ms","start":"2026-05-30T09:53:02.321742Z","end":"2026-05-30T09:53:02.495385Z","steps":["trace[1100034410] 'process raft request' (duration: 173.514432ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:02.581171Z","caller":"traceutil/trace.go:171","msg":"trace[149710293] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"157.999321ms","start":"2026-05-30T09:53:02.42314Z","end":"2026-05-30T09:53:02.58114Z","steps":["trace[149710293] 'process raft request' (duration: 157.866037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:02.981115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.964761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:53:02.981223Z","caller":"traceutil/trace.go:171","msg":"trace[1194407135] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"298.8093ms","start":"2026-05-30T09:53:02.682393Z","end":"2026-05-30T09:53:02.981202Z","steps":["trace[1194407135] 'process raft request' (duration: 127.645904ms)","trace[1194407135] 'compare' (duration: 170.830398ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:03.738888Z","caller":"traceutil/trace.go:171","msg":"trace[1762073249] linearizableReadLoop","detail":"{readStateIndex:2367; appliedIndex:2366; }","duration":"300.797218ms","start":"2026-05-30T09:53:03.438054Z","end":"2026-05-30T09:53:03.738852Z","steps":["trace[1762073249] 'read index received' (duration: 300.542521ms)","trace[1762073249] 'applied index is now lower than readState.Index' (duration: 253.457µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:03.738954Z","caller":"traceutil/trace.go:171","msg":"trace[1604314464] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"593.847279ms","start":"2026-05-30T09:53:03.145077Z","end":"2026-05-30T09:53:03.738924Z","steps":["trace[1604314464] 'process raft request' (duration: 593.617844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.739162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.052971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:53:03.739215Z","caller":"traceutil/trace.go:171","msg":"trace[2080374825] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2274; }","duration":"242.161934ms","start":"2026-05-30T09:53:03.497041Z","end":"2026-05-30T09:53:03.739203Z","steps":["trace[2080374825] 'agreement among raft nodes before linearized reading' (duration: 242.065162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.739309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:03.14505Z","time spent":"594.089805ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:03.739627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.212265ms","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:509"} {"level":"info","ts":"2026-05-30T09:53:03.739785Z","caller":"traceutil/trace.go:171","msg":"trace[1715192054] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2274; }","duration":"242.383199ms","start":"2026-05-30T09:53:03.497337Z","end":"2026-05-30T09:53:03.73972Z","steps":["trace[1715192054] 'agreement among raft nodes before linearized reading' (duration: 241.941949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.739799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.06974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:03.739904Z","caller":"traceutil/trace.go:171","msg":"trace[1748788201] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2274; }","duration":"221.217063ms","start":"2026-05-30T09:53:03.51866Z","end":"2026-05-30T09:53:03.739877Z","steps":["trace[1748788201] 'agreement among raft nodes before linearized reading' (duration: 221.025949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.740477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.411546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-30T09:53:03.740536Z","caller":"traceutil/trace.go:171","msg":"trace[1184578593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2274; }","duration":"302.528429ms","start":"2026-05-30T09:53:03.437994Z","end":"2026-05-30T09:53:03.740522Z","steps":["trace[1184578593] 'agreement among raft nodes before linearized reading' (duration: 301.025743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.740688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:03.437974Z","time spent":"302.700843ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T09:53:03.940643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.302553ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-30T09:53:03.940905Z","caller":"traceutil/trace.go:171","msg":"trace[1363431773] linearizableReadLoop","detail":"{readStateIndex:2368; appliedIndex:2367; }","duration":"199.193967ms","start":"2026-05-30T09:53:03.741681Z","end":"2026-05-30T09:53:03.940875Z","steps":["trace[1363431773] 'read index received' (duration: 3.642547ms)","trace[1363431773] 'applied index is now lower than readState.Index' (duration: 195.550149ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:03.941258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.541655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:03.941423Z","caller":"traceutil/trace.go:171","msg":"trace[1098739206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2274; }","duration":"199.736189ms","start":"2026-05-30T09:53:03.741655Z","end":"2026-05-30T09:53:03.941391Z","steps":["trace[1098739206] 'agreement among raft nodes before linearized reading' (duration: 199.476173ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.943842Z","caller":"traceutil/trace.go:171","msg":"trace[273147756] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"199.192417ms","start":"2026-05-30T09:53:03.744608Z","end":"2026-05-30T09:53:03.943801Z","steps":["trace[273147756] 'process raft request' (duration: 196.150584ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.95085Z","caller":"traceutil/trace.go:171","msg":"trace[1914801141] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"205.738283ms","start":"2026-05-30T09:53:03.745084Z","end":"2026-05-30T09:53:03.950823Z","steps":["trace[1914801141] 'process raft request' (duration: 198.688265ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.951427Z","caller":"traceutil/trace.go:171","msg":"trace[92679318] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"206.04168ms","start":"2026-05-30T09:53:03.745362Z","end":"2026-05-30T09:53:03.951404Z","steps":["trace[92679318] 'process raft request' (duration: 205.413295ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.951856Z","caller":"traceutil/trace.go:171","msg":"trace[927616629] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"205.889917ms","start":"2026-05-30T09:53:03.745953Z","end":"2026-05-30T09:53:03.951843Z","steps":["trace[927616629] 'process raft request' (duration: 205.404755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.952794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.533668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-30T09:53:03.952879Z","caller":"traceutil/trace.go:171","msg":"trace[466622965] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2278; }","duration":"121.651171ms","start":"2026-05-30T09:53:03.831187Z","end":"2026-05-30T09:53:03.952839Z","steps":["trace[466622965] 'agreement among raft nodes before linearized reading' (duration: 120.562585ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:21.994777Z","caller":"traceutil/trace.go:171","msg":"trace[277477751] linearizableReadLoop","detail":"{readStateIndex:2477; appliedIndex:2476; }","duration":"101.627764ms","start":"2026-05-30T09:53:21.893127Z","end":"2026-05-30T09:53:21.994755Z","steps":["trace[277477751] 'read index received' (duration: 101.405249ms)","trace[277477751] 'applied index is now lower than readState.Index' (duration: 221.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:21.994861Z","caller":"traceutil/trace.go:171","msg":"trace[2131947071] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"158.989363ms","start":"2026-05-30T09:53:21.835836Z","end":"2026-05-30T09:53:21.994826Z","steps":["trace[2131947071] 'process raft request' (duration: 158.776037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:21.99506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.83494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:21.995139Z","caller":"traceutil/trace.go:171","msg":"trace[1177961098] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2379; }","duration":"102.039084ms","start":"2026-05-30T09:53:21.893086Z","end":"2026-05-30T09:53:21.995125Z","steps":["trace[1177961098] 'agreement among raft nodes before linearized reading' (duration: 101.846039ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:30.597237Z","caller":"traceutil/trace.go:171","msg":"trace[393763402] linearizableReadLoop","detail":"{readStateIndex:2524; appliedIndex:2523; }","duration":"481.512416ms","start":"2026-05-30T09:53:30.115688Z","end":"2026-05-30T09:53:30.597201Z","steps":["trace[393763402] 'read index received' (duration: 481.339002ms)","trace[393763402] 'applied index is now lower than readState.Index' (duration: 172.374µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:30.598342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.645433ms","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-05-30T09:53:30.598407Z","caller":"traceutil/trace.go:171","msg":"trace[1181595025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2424; }","duration":"482.757346ms","start":"2026-05-30T09:53:30.115634Z","end":"2026-05-30T09:53:30.598391Z","steps":["trace[1181595025] 'agreement among raft nodes before linearized reading' (duration: 482.531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:30.598487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:30.115618Z","time spent":"482.858869ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:53:30.598703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.3145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:30.598761Z","caller":"traceutil/trace.go:171","msg":"trace[1878057912] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2424; }","duration":"478.400632ms","start":"2026-05-30T09:53:30.120343Z","end":"2026-05-30T09:53:30.598744Z","steps":["trace[1878057912] 'agreement among raft nodes before linearized reading' (duration: 478.29541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:30.598809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:30.120333Z","time spent":"478.466983ms","remote":"127.0.0.1:35770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:53:30.598783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.041807ms","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-05-30T09:53:30.598969Z","caller":"traceutil/trace.go:171","msg":"trace[285587299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2424; }","duration":"479.325524ms","start":"2026-05-30T09:53:30.119608Z","end":"2026-05-30T09:53:30.598933Z","steps":["trace[285587299] 'agreement among raft nodes before linearized reading' (duration: 478.714709ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:30.59798Z","caller":"traceutil/trace.go:171","msg":"trace[1039596945] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"568.747226ms","start":"2026-05-30T09:53:30.029132Z","end":"2026-05-30T09:53:30.59788Z","steps":["trace[1039596945] 'process raft request' (duration: 567.920757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:30.599051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:30.119592Z","time spent":"479.444967ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T09:53:30.599068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.761621ms","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":"info","ts":"2026-05-30T09:53:30.599203Z","caller":"traceutil/trace.go:171","msg":"trace[565182183] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2424; }","duration":"452.900434ms","start":"2026-05-30T09:53:30.14626Z","end":"2026-05-30T09:53:30.599161Z","steps":["trace[565182183] 'agreement among raft nodes before linearized reading' (duration: 452.253089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:30.599245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:30.02911Z","time spent":"570.025517ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:30.599267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:30.146225Z","time spent":"453.032216ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-30T09:53:30.724735Z","caller":"traceutil/trace.go:171","msg":"trace[2091085123] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"122.121833ms","start":"2026-05-30T09:53:30.602591Z","end":"2026-05-30T09:53:30.724713Z","steps":["trace[2091085123] 'process raft request' (duration: 114.684015ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:30.747485Z","caller":"traceutil/trace.go:171","msg":"trace[518946171] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"144.343163ms","start":"2026-05-30T09:53:30.603127Z","end":"2026-05-30T09:53:30.74747Z","steps":["trace[518946171] 'process raft request' (duration: 144.270501ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:30.747759Z","caller":"traceutil/trace.go:171","msg":"trace[731826657] linearizableReadLoop","detail":"{readStateIndex:2527; appliedIndex:2524; }","duration":"119.06658ms","start":"2026-05-30T09:53:30.628681Z","end":"2026-05-30T09:53:30.747747Z","steps":["trace[731826657] 'read index received' (duration: 88.624404ms)","trace[731826657] 'applied index is now lower than readState.Index' (duration: 30.440996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:30.748021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.307446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-30T09:53:30.748109Z","caller":"traceutil/trace.go:171","msg":"trace[1965802317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2428; }","duration":"119.430319ms","start":"2026-05-30T09:53:30.628648Z","end":"2026-05-30T09:53:30.748079Z","steps":["trace[1965802317] 'agreement among raft nodes before linearized reading' (duration: 119.183873ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:30.748024Z","caller":"traceutil/trace.go:171","msg":"trace[1558985342] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"144.881586ms","start":"2026-05-30T09:53:30.60306Z","end":"2026-05-30T09:53:30.747942Z","steps":["trace[1558985342] 'process raft request' (duration: 144.146118ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:32.897122Z","caller":"traceutil/trace.go:171","msg":"trace[1395894904] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"142.732935ms","start":"2026-05-30T09:53:32.754362Z","end":"2026-05-30T09:53:32.897095Z","steps":["trace[1395894904] 'process raft request' (duration: 142.497249ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:32.89783Z","caller":"traceutil/trace.go:171","msg":"trace[554293511] linearizableReadLoop","detail":"{readStateIndex:2540; appliedIndex:2538; }","duration":"140.566523ms","start":"2026-05-30T09:53:32.757248Z","end":"2026-05-30T09:53:32.897815Z","steps":["trace[554293511] 'read index received' (duration: 139.61767ms)","trace[554293511] 'applied index is now lower than readState.Index' (duration: 947.683µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:32.897907Z","caller":"traceutil/trace.go:171","msg":"trace[265845146] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"141.363732ms","start":"2026-05-30T09:53:32.756509Z","end":"2026-05-30T09:53:32.897873Z","steps":["trace[265845146] 'process raft request' (duration: 141.233829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:32.898003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.741757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-30T09:53:32.898036Z","caller":"traceutil/trace.go:171","msg":"trace[47953899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2439; }","duration":"140.788218ms","start":"2026-05-30T09:53:32.757239Z","end":"2026-05-30T09:53:32.898027Z","steps":["trace[47953899] 'agreement among raft nodes before linearized reading' (duration: 140.638224ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:41.111064Z","caller":"traceutil/trace.go:171","msg":"trace[1264881847] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"100.015065ms","start":"2026-05-30T09:53:41.011028Z","end":"2026-05-30T09:53:41.111044Z","steps":["trace[1264881847] 'process raft request' (duration: 91.00102ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:43.251224Z","caller":"traceutil/trace.go:171","msg":"trace[1829948375] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"179.609784ms","start":"2026-05-30T09:53:43.071591Z","end":"2026-05-30T09:53:43.251201Z","steps":["trace[1829948375] 'process raft request' (duration: 177.263708ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:43.251272Z","caller":"traceutil/trace.go:171","msg":"trace[464086552] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"179.461771ms","start":"2026-05-30T09:53:43.071789Z","end":"2026-05-30T09:53:43.251251Z","steps":["trace[464086552] 'process raft request' (duration: 179.331127ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:43.251242Z","caller":"traceutil/trace.go:171","msg":"trace[408385229] linearizableReadLoop","detail":"{readStateIndex:2593; appliedIndex:2591; }","duration":"138.080824ms","start":"2026-05-30T09:53:43.113138Z","end":"2026-05-30T09:53:43.251219Z","steps":["trace[408385229] 'read index received' (duration: 135.776649ms)","trace[408385229] 'applied index is now lower than readState.Index' (duration: 2.303415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:43.251619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.085218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:990"} {"level":"info","ts":"2026-05-30T09:53:43.251702Z","caller":"traceutil/trace.go:171","msg":"trace[1967565769] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:2490; }","duration":"165.203151ms","start":"2026-05-30T09:53:43.086483Z","end":"2026-05-30T09:53:43.251686Z","steps":["trace[1967565769] 'agreement among raft nodes before linearized reading' (duration: 165.010136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:43.252002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.646843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-30T09:53:43.25208Z","caller":"traceutil/trace.go:171","msg":"trace[1519587178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2490; }","duration":"137.735885ms","start":"2026-05-30T09:53:43.114326Z","end":"2026-05-30T09:53:43.252062Z","steps":["trace[1519587178] 'agreement among raft nodes before linearized reading' (duration: 137.53744ms)"],"step_count":1}