{"level":"warn","ts":"2026-05-11T00:51:08.112482Z","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-11T00:51:08.112571Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-11T00:51:08.112632Z","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-11T00:51:08.112647Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-11T00:51:08.112666Z","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-11T00:51:08.113345Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-05-11T00:51:08.113417Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-11T00:51:08.117972Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.37433ms"} {"level":"info","ts":"2026-05-11T00:51:08.126033Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-05-11T00:51:08.126111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:51:08.126133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-05-11T00:51:08.126144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:51:08.126156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-05-11T00:51:08.126196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-05-11T00:51:08.133395Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:51:08.134706Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:51:08.135796Z","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-11T00:51:08.138905Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:51:08.139012Z","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-11T00:51:08.139118Z","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-11T00:51:08.139131Z","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-11T00:51:08.139514Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:51:08.154776Z","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-11T00:51:08.154944Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-11T00:51:08.154988Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-11T00:51:08.15505Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:51:08.15508Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:51:08.149648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-05-11T00:51:08.155848Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-11T00:51:08.229945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:51:08.230062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:51:08.230124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-05-11T00:51:08.230177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:51:08.230199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-11T00:51:08.230252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-05-11T00:51:08.230304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-11T00:51:08.235184Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:51:08.235931Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:51:08.235967Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:51:08.235933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:51:08.236249Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:51:08.236292Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T00:51:08.236525Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:51:08.236622Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:51:08.236658Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:51:08.239666Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-05-11T00:51:08.240956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T00:51:14.696252Z","caller":"traceutil/trace.go:171","msg":"trace[1777556519] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"116.792658ms","start":"2026-05-11T00:51:14.579418Z","end":"2026-05-11T00:51:14.69621Z","steps":["trace[1777556519] 'process raft request' (duration: 116.751967ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:14.696554Z","caller":"traceutil/trace.go:171","msg":"trace[1911689801] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"128.607306ms","start":"2026-05-11T00:51:14.567908Z","end":"2026-05-11T00:51:14.696515Z","steps":["trace[1911689801] 'process raft request' (duration: 128.164734ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:14.696655Z","caller":"traceutil/trace.go:171","msg":"trace[629995410] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:292; }","duration":"117.307352ms","start":"2026-05-11T00:51:14.579335Z","end":"2026-05-11T00:51:14.696642Z","steps":["trace[629995410] 'read index received' (duration: 83.195513ms)","trace[629995410] 'applied index is now lower than readState.Index' (duration: 34.110349ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:14.696216Z","caller":"traceutil/trace.go:171","msg":"trace[1710212181] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"136.093854ms","start":"2026-05-11T00:51:14.560066Z","end":"2026-05-11T00:51:14.69616Z","steps":["trace[1710212181] 'process raft request' (duration: 102.451749ms)","trace[1710212181] 'compare' (duration: 33.412499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:14.696849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.404445ms","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-11T00:51:14.697499Z","caller":"traceutil/trace.go:171","msg":"trace[1919638461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:280; }","duration":"118.181816ms","start":"2026-05-11T00:51:14.579298Z","end":"2026-05-11T00:51:14.69748Z","steps":["trace[1919638461] 'agreement among raft nodes before linearized reading' (duration: 117.388014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:15.905596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.440725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-11T00:51:15.905681Z","caller":"traceutil/trace.go:171","msg":"trace[1148854290] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:293; }","duration":"137.568538ms","start":"2026-05-11T00:51:15.768093Z","end":"2026-05-11T00:51:15.905661Z","steps":["trace[1148854290] 'range keys from in-memory index tree' (duration: 137.234759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:16.564579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930422462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:51:17.06512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930422462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:51:17.072449Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.163205915s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T00:51:17.07296Z","caller":"traceutil/trace.go:171","msg":"trace[939364776] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"1.00928851s","start":"2026-05-11T00:51:16.063649Z","end":"2026-05-11T00:51:17.072937Z","steps":["trace[939364776] 'read index received' (duration: 1.009010142s)","trace[939364776] 'applied index is now lower than readState.Index' (duration: 277.458µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:17.073068Z","caller":"traceutil/trace.go:171","msg":"trace[1377570762] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"1.163872233s","start":"2026-05-11T00:51:15.909155Z","end":"2026-05-11T00:51:17.073027Z","steps":["trace[1377570762] 'process raft request' (duration: 1.163664138s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:17.073183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009510366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T00:51:17.073232Z","caller":"traceutil/trace.go:171","msg":"trace[1332866428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:294; }","duration":"1.009615179s","start":"2026-05-11T00:51:16.063605Z","end":"2026-05-11T00:51:17.073221Z","steps":["trace[1332866428] 'agreement among raft nodes before linearized reading' (duration: 1.009462995s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:17.073297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:16.063585Z","time spent":"1.009701191s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T00:51:17.073491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:15.909131Z","time spent":"1.163985056s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:51:17.073797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007968103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-11T00:51:17.073841Z","caller":"traceutil/trace.go:171","msg":"trace[609108658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:294; }","duration":"1.008035774s","start":"2026-05-11T00:51:16.065792Z","end":"2026-05-11T00:51:17.073828Z","steps":["trace[609108658] 'agreement among raft nodes before linearized reading' (duration: 1.007950612s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:17.073848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.188429ms","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-11T00:51:17.073869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:16.065781Z","time spent":"1.008080756s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T00:51:17.073896Z","caller":"traceutil/trace.go:171","msg":"trace[221393929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:294; }","duration":"343.266271ms","start":"2026-05-11T00:51:16.730616Z","end":"2026-05-11T00:51:17.073882Z","steps":["trace[221393929] 'agreement among raft nodes before linearized reading' (duration: 343.171488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:17.073946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:16.730598Z","time spent":"343.340272ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T00:51:17.073943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.904047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-11T00:51:17.074033Z","caller":"traceutil/trace.go:171","msg":"trace[1481817640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:294; }","duration":"991.049021ms","start":"2026-05-11T00:51:16.082963Z","end":"2026-05-11T00:51:17.074012Z","steps":["trace[1481817640] 'agreement among raft nodes before linearized reading' (duration: 990.836306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:17.074084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:16.082948Z","time spent":"991.126953ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T00:51:18.769547Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.691940194s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-11T00:51:19.174779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.619059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:51:19.175096Z","caller":"traceutil/trace.go:171","msg":"trace[810120213] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"2.097555841s","start":"2026-05-11T00:51:17.077523Z","end":"2026-05-11T00:51:19.175079Z","steps":["trace[810120213] 'read index received' (duration: 59.332µs)","trace[810120213] 'applied index is now lower than readState.Index' (duration: 2.097495099s)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:19.175268Z","caller":"traceutil/trace.go:171","msg":"trace[839339963] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"2.097760847s","start":"2026-05-11T00:51:17.077494Z","end":"2026-05-11T00:51:19.175255Z","steps":["trace[839339963] 'process raft request' (duration: 1.692264573s)","trace[839339963] 'compare' (duration: 404.429104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:19.175346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.097822378s","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-11T00:51:19.175415Z","caller":"traceutil/trace.go:171","msg":"trace[264825996] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:295; }","duration":"2.097940122s","start":"2026-05-11T00:51:17.077459Z","end":"2026-05-11T00:51:19.175399Z","steps":["trace[264825996] 'agreement among raft nodes before linearized reading' (duration: 2.097818089s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:19.175453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:17.077437Z","time spent":"2.098010014s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-11T00:51:19.175593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:17.077474Z","time spent":"2.098076276s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:51:19.6757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930422470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:51:20.176192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930422470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:51:20.67739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930422470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:51:21.064945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000168522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-11T00:51:21.065028Z","caller":"traceutil/trace.go:171","msg":"trace[1002139191] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000304706s","start":"2026-05-11T00:51:19.06471Z","end":"2026-05-11T00:51:21.065015Z","steps":["trace[1002139191] 'agreement among raft nodes before linearized reading' (duration: 2.000200383s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:21.06506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:19.064689Z","time spent":"2.000364127s","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:51:21.177721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930422470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:51:21.351907Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.582203814s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T00:51:21.35264Z","caller":"traceutil/trace.go:171","msg":"trace[769000868] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"4.271711881s","start":"2026-05-11T00:51:17.080902Z","end":"2026-05-11T00:51:21.352614Z","steps":["trace[769000868] 'process raft request' (duration: 4.271655339s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:21.352661Z","caller":"traceutil/trace.go:171","msg":"trace[1044304576] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"4.273926283s","start":"2026-05-11T00:51:17.078707Z","end":"2026-05-11T00:51:21.352633Z","steps":["trace[1044304576] 'process raft request' (duration: 4.273625754s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:21.352808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:17.078685Z","time spent":"4.274049086s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:51:21.352748Z","caller":"traceutil/trace.go:171","msg":"trace[1831651641] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"4.273686256s","start":"2026-05-11T00:51:17.079031Z","end":"2026-05-11T00:51:21.352717Z","steps":["trace[1831651641] 'process raft request' (duration: 4.27346873s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:21.353055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:17.079009Z","time spent":"4.273898772s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:51:21.353076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:17.080894Z","time spent":"4.271831294s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:51:21.368094Z","caller":"traceutil/trace.go:171","msg":"trace[342465267] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"883.993858ms","start":"2026-05-11T00:51:20.48407Z","end":"2026-05-11T00:51:21.368064Z","steps":["trace[342465267] 'process raft request' (duration: 883.835214ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:21.368197Z","caller":"traceutil/trace.go:171","msg":"trace[66073674] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:310; }","duration":"2.192972536s","start":"2026-05-11T00:51:19.175204Z","end":"2026-05-11T00:51:21.368176Z","steps":["trace[66073674] 'read index received' (duration: 2.176963699s)","trace[66073674] 'applied index is now lower than readState.Index' (duration: 16.007667ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:21.368295Z","caller":"traceutil/trace.go:171","msg":"trace[1817238195] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"299.166002ms","start":"2026-05-11T00:51:21.069119Z","end":"2026-05-11T00:51:21.368285Z","steps":["trace[1817238195] 'process raft request' (duration: 298.877754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:21.368223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:20.48405Z","time spent":"884.102931ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:51:21.3685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.5301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-11T00:51:21.368527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.190453415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-11T00:51:21.368559Z","caller":"traceutil/trace.go:171","msg":"trace[1255315739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:300; }","duration":"188.638693ms","start":"2026-05-11T00:51:21.179906Z","end":"2026-05-11T00:51:21.368544Z","steps":["trace[1255315739] 'agreement among raft nodes before linearized reading' (duration: 188.489489ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:21.36858Z","caller":"traceutil/trace.go:171","msg":"trace[232550561] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:300; }","duration":"2.190595758s","start":"2026-05-11T00:51:19.177971Z","end":"2026-05-11T00:51:21.368566Z","steps":["trace[232550561] 'agreement among raft nodes before linearized reading' (duration: 2.190368002s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:21.36868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:19.177955Z","time spent":"2.190711022s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-05-11T00:51:21.535728Z","caller":"traceutil/trace.go:171","msg":"trace[1861531814] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"156.977987ms","start":"2026-05-11T00:51:21.378718Z","end":"2026-05-11T00:51:21.535696Z","steps":["trace[1861531814] 'process raft request' (duration: 156.813923ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:21.843247Z","caller":"traceutil/trace.go:171","msg":"trace[539303960] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"151.129214ms","start":"2026-05-11T00:51:21.692092Z","end":"2026-05-11T00:51:21.843221Z","steps":["trace[539303960] 'process raft request' (duration: 150.952799ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:22.008777Z","caller":"traceutil/trace.go:171","msg":"trace[565327619] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"315.066176ms","start":"2026-05-11T00:51:21.693682Z","end":"2026-05-11T00:51:22.008749Z","steps":["trace[565327619] 'process raft request' (duration: 314.890351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:22.008911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:21.693676Z","time spent":"315.172659ms","remote":"127.0.0.1:33670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:22.00893Z","caller":"traceutil/trace.go:171","msg":"trace[46028816] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"314.765048ms","start":"2026-05-11T00:51:21.694136Z","end":"2026-05-11T00:51:22.008901Z","steps":["trace[46028816] 'process raft request' (duration: 314.545581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:22.00907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:21.694117Z","time spent":"314.879011ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:22.009235Z","caller":"traceutil/trace.go:171","msg":"trace[655854802] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"313.662457ms","start":"2026-05-11T00:51:21.69556Z","end":"2026-05-11T00:51:22.009223Z","steps":["trace[655854802] 'process raft request' (duration: 313.166944ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:22.009317Z","caller":"traceutil/trace.go:171","msg":"trace[86424936] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"313.358659ms","start":"2026-05-11T00:51:21.695948Z","end":"2026-05-11T00:51:22.009306Z","steps":["trace[86424936] 'process raft request' (duration: 313.023909ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:22.009317Z","caller":"traceutil/trace.go:171","msg":"trace[153185933] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"313.647636ms","start":"2026-05-11T00:51:21.695649Z","end":"2026-05-11T00:51:22.009296Z","steps":["trace[153185933] 'process raft request' (duration: 313.155573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:22.009372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:21.695936Z","time spent":"313.40854ms","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:22.009442Z","caller":"traceutil/trace.go:171","msg":"trace[1553000246] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"313.428511ms","start":"2026-05-11T00:51:21.695999Z","end":"2026-05-11T00:51:22.009428Z","steps":["trace[1553000246] 'process raft request' (duration: 313.068151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:22.009445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:21.695644Z","time spent":"313.735039ms","remote":"127.0.0.1:33734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:51:22.009514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:21.695984Z","time spent":"313.489612ms","remote":"127.0.0.1:33888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:51:22.009536Z","caller":"traceutil/trace.go:171","msg":"trace[841696913] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"312.839744ms","start":"2026-05-11T00:51:21.696683Z","end":"2026-05-11T00:51:22.009523Z","steps":["trace[841696913] 'process raft request' (duration: 312.570676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:22.009613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:21.696677Z","time spent":"312.888855ms","remote":"127.0.0.1:33888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:51:22.009713Z","caller":"traceutil/trace.go:171","msg":"trace[1391300547] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"312.502184ms","start":"2026-05-11T00:51:21.697198Z","end":"2026-05-11T00:51:22.009701Z","steps":["trace[1391300547] 'process raft request' (duration: 312.348539ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:22.009732Z","caller":"traceutil/trace.go:171","msg":"trace[883246992] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:321; }","duration":"158.948902ms","start":"2026-05-11T00:51:21.850747Z","end":"2026-05-11T00:51:22.009696Z","steps":["trace[883246992] 'read index received' (duration: 157.733129ms)","trace[883246992] 'applied index is now lower than readState.Index' (duration: 1.157402ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:22.009864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.122837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-11T00:51:22.009775Z","caller":"traceutil/trace.go:171","msg":"trace[2094166536] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"312.72775ms","start":"2026-05-11T00:51:21.697038Z","end":"2026-05-11T00:51:22.009766Z","steps":["trace[2094166536] 'process raft request' (duration: 312.388791ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:22.009937Z","caller":"traceutil/trace.go:171","msg":"trace[105699349] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:315; }","duration":"159.21524ms","start":"2026-05-11T00:51:21.85071Z","end":"2026-05-11T00:51:22.009925Z","steps":["trace[105699349] 'agreement among raft nodes before linearized reading' (duration: 159.113387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:22.009981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:21.697032Z","time spent":"312.926196ms","remote":"127.0.0.1:33720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:51:22.009768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:21.697188Z","time spent":"312.554886ms","remote":"127.0.0.1:33888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:51:22.010094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.211852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T00:51:22.01023Z","caller":"traceutil/trace.go:171","msg":"trace[973794952] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:315; }","duration":"104.372917ms","start":"2026-05-11T00:51:21.905825Z","end":"2026-05-11T00:51:22.010198Z","steps":["trace[973794952] 'agreement among raft nodes before linearized reading' (duration: 104.207093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:22.010254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:21.695554Z","time spent":"313.715349ms","remote":"127.0.0.1:33812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1247,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:51:23.613946Z","caller":"traceutil/trace.go:171","msg":"trace[512243224] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"170.049959ms","start":"2026-05-11T00:51:23.443846Z","end":"2026-05-11T00:51:23.613896Z","steps":["trace[512243224] 'process raft request' (duration: 93.663522ms)","trace[512243224] 'compare' (duration: 76.259314ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:24.647243Z","caller":"traceutil/trace.go:171","msg":"trace[414053723] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"104.205288ms","start":"2026-05-11T00:51:24.543011Z","end":"2026-05-11T00:51:24.647216Z","steps":["trace[414053723] 'process raft request' (duration: 104.067325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:25.809094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.082834ms","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-11T00:51:25.809181Z","caller":"traceutil/trace.go:171","msg":"trace[1135374984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:363; }","duration":"191.220489ms","start":"2026-05-11T00:51:25.617942Z","end":"2026-05-11T00:51:25.809162Z","steps":["trace[1135374984] 'range keys from in-memory index tree' (duration: 190.903169ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:27.654763Z","caller":"traceutil/trace.go:171","msg":"trace[25289128] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"149.913585ms","start":"2026-05-11T00:51:27.504824Z","end":"2026-05-11T00:51:27.654737Z","steps":["trace[25289128] 'read index received' (duration: 114.569484ms)","trace[25289128] 'applied index is now lower than readState.Index' (duration: 35.343271ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:27.654872Z","caller":"traceutil/trace.go:171","msg":"trace[666853651] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"171.267404ms","start":"2026-05-11T00:51:27.483573Z","end":"2026-05-11T00:51:27.654841Z","steps":["trace[666853651] 'process raft request' (duration: 135.81803ms)","trace[666853651] 'compare' (duration: 35.209497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:27.654895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.057988ms","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-11T00:51:27.654948Z","caller":"traceutil/trace.go:171","msg":"trace[1282496447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"150.165511ms","start":"2026-05-11T00:51:27.504769Z","end":"2026-05-11T00:51:27.654935Z","steps":["trace[1282496447] 'agreement among raft nodes before linearized reading' (duration: 150.070289ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:37.886226Z","caller":"traceutil/trace.go:171","msg":"trace[1704170152] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"100.922073ms","start":"2026-05-11T00:51:37.785275Z","end":"2026-05-11T00:51:37.886197Z","steps":["trace[1704170152] 'process raft request' (duration: 97.903808ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:45.73495Z","caller":"traceutil/trace.go:171","msg":"trace[1932981096] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"148.952896ms","start":"2026-05-11T00:51:45.585977Z","end":"2026-05-11T00:51:45.73493Z","steps":["trace[1932981096] 'process raft request' (duration: 148.775553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:47.087727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.299579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:51:47.087803Z","caller":"traceutil/trace.go:171","msg":"trace[833459255] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.086271268s","start":"2026-05-11T00:51:46.001519Z","end":"2026-05-11T00:51:47.087791Z","steps":["trace[833459255] 'process raft request' (duration: 519.848027ms)","trace[833459255] 'compare' (duration: 566.102835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:47.087847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.001502Z","time spent":"1.086322229s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:51:47.164832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930422821,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:51:47.63808Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.111742201s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-11T00:51:47.765068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.632888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:51:47.765431Z","caller":"traceutil/trace.go:171","msg":"trace[2015839235] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.760218845s","start":"2026-05-11T00:51:46.005171Z","end":"2026-05-11T00:51:47.76539Z","steps":["trace[2015839235] 'process raft request' (duration: 1.63321589s)","trace[2015839235] 'compare' (duration: 126.140669ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:47.765517Z","caller":"traceutil/trace.go:171","msg":"trace[1812981458] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.756625476s","start":"2026-05-11T00:51:46.008885Z","end":"2026-05-11T00:51:47.765511Z","steps":["trace[1812981458] 'process raft request' (duration: 1.756443592s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:47.765566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.008867Z","time spent":"1.756672426s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:47.76554Z","caller":"traceutil/trace.go:171","msg":"trace[1777194947] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.747656033s","start":"2026-05-11T00:51:46.017857Z","end":"2026-05-11T00:51:47.765513Z","steps":["trace[1777194947] 'process raft request' (duration: 1.747561221s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:47.765615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.005161Z","time spent":"1.760371738s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:47.765599Z","caller":"traceutil/trace.go:171","msg":"trace[584995727] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.747437847s","start":"2026-05-11T00:51:46.018132Z","end":"2026-05-11T00:51:47.765569Z","steps":["trace[584995727] 'process raft request' (duration: 1.747311794s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:47.765483Z","caller":"traceutil/trace.go:171","msg":"trace[1541924849] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.756572355s","start":"2026-05-11T00:51:46.008892Z","end":"2026-05-11T00:51:47.765464Z","steps":["trace[1541924849] 'process raft request' (duration: 1.756480253s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:47.765739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.008879Z","time spent":"1.75684348s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:51:47.765757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.017843Z","time spent":"1.747822216s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:47.765797Z","caller":"traceutil/trace.go:171","msg":"trace[678520161] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.730136762s","start":"2026-05-11T00:51:46.035651Z","end":"2026-05-11T00:51:47.765788Z","steps":["trace[678520161] 'process raft request' (duration: 1.73002748s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:47.76579Z","caller":"traceutil/trace.go:171","msg":"trace[96399889] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.739421603s","start":"2026-05-11T00:51:46.026353Z","end":"2026-05-11T00:51:47.765775Z","steps":["trace[96399889] 'process raft request' (duration: 1.739230379s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:47.765824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.035634Z","time spent":"1.730177883s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:51:47.765837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.018124Z","time spent":"1.747602461s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:47.765878Z","caller":"traceutil/trace.go:171","msg":"trace[467179171] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.747476888s","start":"2026-05-11T00:51:46.018146Z","end":"2026-05-11T00:51:47.765623Z","steps":["trace[467179171] 'process raft request' (duration: 1.747321165s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:47.765956Z","caller":"traceutil/trace.go:171","msg":"trace[1414728847] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.747471957s","start":"2026-05-11T00:51:46.01819Z","end":"2026-05-11T00:51:47.765662Z","steps":["trace[1414728847] 'process raft request' (duration: 1.747309384s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:47.765882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.02634Z","time spent":"1.739496314s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:51:47.765993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.018186Z","time spent":"1.747792253s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:47.765937Z","caller":"traceutil/trace.go:171","msg":"trace[483628737] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.751370254s","start":"2026-05-11T00:51:46.014273Z","end":"2026-05-11T00:51:47.765643Z","steps":["trace[483628737] 'process raft request' (duration: 1.751122389s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:47.766065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.018137Z","time spent":"1.747852085s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:47.766097Z","caller":"traceutil/trace.go:171","msg":"trace[525233892] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:503; }","duration":"1.101302225s","start":"2026-05-11T00:51:46.664789Z","end":"2026-05-11T00:51:47.766092Z","steps":["trace[525233892] 'read index received' (duration: 973.465633ms)","trace[525233892] 'applied index is now lower than readState.Index' (duration: 127.835492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:47.766133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.014251Z","time spent":"1.751847094s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:47.766104Z","caller":"traceutil/trace.go:171","msg":"trace[717285665] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.717907866s","start":"2026-05-11T00:51:46.048072Z","end":"2026-05-11T00:51:47.76598Z","steps":["trace[717285665] 'process raft request' (duration: 1.717662621s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:47.766288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.048055Z","time spent":"1.718151792s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:51:47.76607Z","caller":"traceutil/trace.go:171","msg":"trace[1524467974] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.60038695s","start":"2026-05-11T00:51:46.165678Z","end":"2026-05-11T00:51:47.766065Z","steps":["trace[1524467974] 'process raft request' (duration: 1.600222576s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:47.766431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.165647Z","time spent":"1.600718537s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:51:47.790437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125626168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:47.790493Z","caller":"traceutil/trace.go:171","msg":"trace[182543703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"1.12573553s","start":"2026-05-11T00:51:46.664743Z","end":"2026-05-11T00:51:47.790478Z","steps":["trace[182543703] 'agreement among raft nodes before linearized reading' (duration: 1.101405587s)","trace[182543703] 'range keys from in-memory index tree' (duration: 24.237262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:47.790521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:46.664723Z","time spent":"1.125792571s","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T00:51:48.948424Z","caller":"traceutil/trace.go:171","msg":"trace[993181910] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"275.571216ms","start":"2026-05-11T00:51:48.67282Z","end":"2026-05-11T00:51:48.948391Z","steps":["trace[993181910] 'process raft request' (duration: 196.682318ms)","trace[993181910] 'compare' (duration: 78.464089ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:48.948624Z","caller":"traceutil/trace.go:171","msg":"trace[191560743] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"211.480318ms","start":"2026-05-11T00:51:48.737115Z","end":"2026-05-11T00:51:48.948595Z","steps":["trace[191560743] 'process raft request' (duration: 211.183732ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:51.736318Z","caller":"traceutil/trace.go:171","msg":"trace[361887817] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"166.819632ms","start":"2026-05-11T00:51:51.56947Z","end":"2026-05-11T00:51:51.736289Z","steps":["trace[361887817] 'process raft request' (duration: 127.569968ms)","trace[361887817] 'compare' (duration: 39.094191ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:52.169492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.894628ms","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-11T00:51:52.169622Z","caller":"traceutil/trace.go:171","msg":"trace[556561388] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:570; }","duration":"283.046601ms","start":"2026-05-11T00:51:51.886557Z","end":"2026-05-11T00:51:52.169604Z","steps":["trace[556561388] 'count revisions from in-memory index tree' (duration: 282.848887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.16967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.950049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:51:52.169727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.369228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:51:52.169819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.703694ms","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-11T00:51:52.169917Z","caller":"traceutil/trace.go:171","msg":"trace[1817749969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"275.847738ms","start":"2026-05-11T00:51:51.894039Z","end":"2026-05-11T00:51:52.169887Z","steps":["trace[1817749969] 'range keys from in-memory index tree' (duration: 275.591122ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:52.169867Z","caller":"traceutil/trace.go:171","msg":"trace[1154123117] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:570; }","duration":"283.536432ms","start":"2026-05-11T00:51:51.886316Z","end":"2026-05-11T00:51:52.169853Z","steps":["trace[1154123117] 'range keys from in-memory index tree' (duration: 283.183614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.169481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.776207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:51:52.170211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.836278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.170327Z","caller":"traceutil/trace.go:171","msg":"trace[1971701421] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:570; }","duration":"269.976381ms","start":"2026-05-11T00:51:51.900338Z","end":"2026-05-11T00:51:52.170314Z","steps":["trace[1971701421] 'range keys from in-memory index tree' (duration: 269.806087ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:52.170261Z","caller":"traceutil/trace.go:171","msg":"trace[7815066] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:570; }","duration":"269.562723ms","start":"2026-05-11T00:51:51.900659Z","end":"2026-05-11T00:51:52.170222Z","steps":["trace[7815066] 'range keys from in-memory index tree' (duration: 268.747115ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:52.169756Z","caller":"traceutil/trace.go:171","msg":"trace[1459713400] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:570; }","duration":"283.068561ms","start":"2026-05-11T00:51:51.886669Z","end":"2026-05-11T00:51:52.169738Z","steps":["trace[1459713400] 'range keys from in-memory index tree' (duration: 282.847357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.169715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.337486ms","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-11T00:51:52.170582Z","caller":"traceutil/trace.go:171","msg":"trace[1666433753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"307.339917ms","start":"2026-05-11T00:51:51.863229Z","end":"2026-05-11T00:51:52.170569Z","steps":["trace[1666433753] 'range keys from in-memory index tree' (duration: 306.144372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.169543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.094192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.170725Z","caller":"traceutil/trace.go:171","msg":"trace[1417642989] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:570; }","duration":"270.248186ms","start":"2026-05-11T00:51:51.900438Z","end":"2026-05-11T00:51:52.170686Z","steps":["trace[1417642989] 'count revisions from in-memory index tree' (duration: 269.069472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.170686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:51.863207Z","time spent":"307.465139ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T00:51:52.196887Z","caller":"traceutil/trace.go:171","msg":"trace[1832204044] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"289.966771ms","start":"2026-05-11T00:51:51.906844Z","end":"2026-05-11T00:51:52.19681Z","steps":["trace[1832204044] 'read index received' (duration: 285.469212ms)","trace[1832204044] 'applied index is now lower than readState.Index' (duration: 4.495709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:52.197218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.350029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T00:51:52.197345Z","caller":"traceutil/trace.go:171","msg":"trace[1179567396] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:572; }","duration":"290.490672ms","start":"2026-05-11T00:51:51.906781Z","end":"2026-05-11T00:51:52.197272Z","steps":["trace[1179567396] 'agreement among raft nodes before linearized reading' (duration: 290.305119ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:52.197594Z","caller":"traceutil/trace.go:171","msg":"trace[609802348] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"292.042174ms","start":"2026-05-11T00:51:51.905541Z","end":"2026-05-11T00:51:52.197583Z","steps":["trace[609802348] 'process raft request' (duration: 287.480163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.198085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.630138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.198168Z","caller":"traceutil/trace.go:171","msg":"trace[1797980428] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:573; }","duration":"237.737269ms","start":"2026-05-11T00:51:51.960399Z","end":"2026-05-11T00:51:52.198137Z","steps":["trace[1797980428] 'agreement among raft nodes before linearized reading' (duration: 237.633507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.198436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.758276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.19849Z","caller":"traceutil/trace.go:171","msg":"trace[711232465] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:573; }","duration":"131.857508ms","start":"2026-05-11T00:51:52.066619Z","end":"2026-05-11T00:51:52.198477Z","steps":["trace[711232465] 'agreement among raft nodes before linearized reading' (duration: 131.779407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.198135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.574821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:51:52.198722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.056632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.198723Z","caller":"traceutil/trace.go:171","msg":"trace[1258850921] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:573; }","duration":"112.208183ms","start":"2026-05-11T00:51:52.086504Z","end":"2026-05-11T00:51:52.198712Z","steps":["trace[1258850921] 'agreement among raft nodes before linearized reading' (duration: 111.598121ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:52.198858Z","caller":"traceutil/trace.go:171","msg":"trace[761758445] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:573; }","duration":"132.132303ms","start":"2026-05-11T00:51:52.066624Z","end":"2026-05-11T00:51:52.198756Z","steps":["trace[761758445] 'agreement among raft nodes before linearized reading' (duration: 132.067881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.198905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.71832ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:51:52.199167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.543106ms","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-11T00:51:52.199164Z","caller":"traceutil/trace.go:171","msg":"trace[1755625938] 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:573; }","duration":"214.882443ms","start":"2026-05-11T00:51:51.984151Z","end":"2026-05-11T00:51:52.199033Z","steps":["trace[1755625938] 'agreement among raft nodes before linearized reading' (duration: 214.694359ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:52.19921Z","caller":"traceutil/trace.go:171","msg":"trace[986580083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"219.602197ms","start":"2026-05-11T00:51:51.979589Z","end":"2026-05-11T00:51:52.199191Z","steps":["trace[986580083] 'agreement among raft nodes before linearized reading' (duration: 219.444124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.199563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.850841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.199599Z","caller":"traceutil/trace.go:171","msg":"trace[851103224] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:573; }","duration":"238.890702ms","start":"2026-05-11T00:51:51.960693Z","end":"2026-05-11T00:51:52.199583Z","steps":["trace[851103224] 'agreement among raft nodes before linearized reading' (duration: 238.736559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.19967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.82754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.199755Z","caller":"traceutil/trace.go:171","msg":"trace[1486667218] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:573; }","duration":"171.994084ms","start":"2026-05-11T00:51:52.027736Z","end":"2026-05-11T00:51:52.19973Z","steps":["trace[1486667218] 'agreement among raft nodes before linearized reading' (duration: 171.81165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.199792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.171402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.199907Z","caller":"traceutil/trace.go:171","msg":"trace[380909304] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:573; }","duration":"113.285094ms","start":"2026-05-11T00:51:52.086565Z","end":"2026-05-11T00:51:52.19985Z","steps":["trace[380909304] 'agreement among raft nodes before linearized reading' (duration: 112.820645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.199923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.047737ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.199996Z","caller":"traceutil/trace.go:171","msg":"trace[1370030444] 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:573; }","duration":"215.139149ms","start":"2026-05-11T00:51:51.98484Z","end":"2026-05-11T00:51:52.199979Z","steps":["trace[1370030444] 'agreement among raft nodes before linearized reading' (duration: 214.929855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.200083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.649959ms","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-11T00:51:52.200145Z","caller":"traceutil/trace.go:171","msg":"trace[2019128763] 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:573; }","duration":"215.71994ms","start":"2026-05-11T00:51:51.984404Z","end":"2026-05-11T00:51:52.200124Z","steps":["trace[2019128763] 'agreement among raft nodes before linearized reading' (duration: 215.640728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.200163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.400541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.200194Z","caller":"traceutil/trace.go:171","msg":"trace[1679857563] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:573; }","duration":"172.452623ms","start":"2026-05-11T00:51:52.027729Z","end":"2026-05-11T00:51:52.200182Z","steps":["trace[1679857563] 'agreement among raft nodes before linearized reading' (duration: 172.379962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.199765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.307161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:51:52.200198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.618762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.200304Z","caller":"traceutil/trace.go:171","msg":"trace[1030891611] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:573; }","duration":"133.774226ms","start":"2026-05-11T00:51:52.066507Z","end":"2026-05-11T00:51:52.200281Z","steps":["trace[1030891611] 'agreement among raft nodes before linearized reading' (duration: 133.588202ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:52.200245Z","caller":"traceutil/trace.go:171","msg":"trace[1815305309] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:573; }","duration":"239.78422ms","start":"2026-05-11T00:51:51.960449Z","end":"2026-05-11T00:51:52.200233Z","steps":["trace[1815305309] 'agreement among raft nodes before linearized reading' (duration: 239.28728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.20061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.961538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.200676Z","caller":"traceutil/trace.go:171","msg":"trace[1138970086] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:573; }","duration":"114.03626ms","start":"2026-05-11T00:51:52.086613Z","end":"2026-05-11T00:51:52.200649Z","steps":["trace[1138970086] 'agreement among raft nodes before linearized reading' (duration: 112.770734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.568348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.107643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:51:52.568501Z","caller":"traceutil/trace.go:171","msg":"trace[1920256551] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"298.080997ms","start":"2026-05-11T00:51:52.270407Z","end":"2026-05-11T00:51:52.568488Z","steps":["trace[1920256551] 'read index received' (duration: 162.646726ms)","trace[1920256551] 'applied index is now lower than readState.Index' (duration: 135.433151ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:52.56859Z","caller":"traceutil/trace.go:171","msg":"trace[1750052832] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"321.049417ms","start":"2026-05-11T00:51:52.247515Z","end":"2026-05-11T00:51:52.568564Z","steps":["trace[1750052832] 'process raft request' (duration: 185.617758ms)","trace[1750052832] 'compare' (duration: 135.000181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:52.568679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:52.247502Z","time spent":"321.1349ms","remote":"127.0.0.1:33582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:51:52.568705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.296501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.568801Z","caller":"traceutil/trace.go:171","msg":"trace[803582662] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:576; }","duration":"298.415813ms","start":"2026-05-11T00:51:52.270376Z","end":"2026-05-11T00:51:52.568792Z","steps":["trace[803582662] 'agreement among raft nodes before linearized reading' (duration: 298.225579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.56876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.263136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.568898Z","caller":"traceutil/trace.go:171","msg":"trace[1513100688] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"264.453001ms","start":"2026-05-11T00:51:52.304432Z","end":"2026-05-11T00:51:52.568885Z","steps":["trace[1513100688] 'agreement among raft nodes before linearized reading' (duration: 264.215526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.569333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.758586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:51:52.569378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.921258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.569405Z","caller":"traceutil/trace.go:171","msg":"trace[1774758555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"264.860619ms","start":"2026-05-11T00:51:52.304535Z","end":"2026-05-11T00:51:52.569396Z","steps":["trace[1774758555] 'agreement among raft nodes before linearized reading' (duration: 264.745286ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:52.56942Z","caller":"traceutil/trace.go:171","msg":"trace[808757213] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"121.01229ms","start":"2026-05-11T00:51:52.448396Z","end":"2026-05-11T00:51:52.569409Z","steps":["trace[808757213] 'agreement among raft nodes before linearized reading' (duration: 120.909858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.569335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.853046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.569529Z","caller":"traceutil/trace.go:171","msg":"trace[1799574663] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"122.043891ms","start":"2026-05-11T00:51:52.447449Z","end":"2026-05-11T00:51:52.569493Z","steps":["trace[1799574663] 'agreement among raft nodes before linearized reading' (duration: 121.861017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.569362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.877437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.569694Z","caller":"traceutil/trace.go:171","msg":"trace[387625773] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"122.202453ms","start":"2026-05-11T00:51:52.447414Z","end":"2026-05-11T00:51:52.569616Z","steps":["trace[387625773] 'agreement among raft nodes before linearized reading' (duration: 121.841586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.778244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.5741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.778411Z","caller":"traceutil/trace.go:171","msg":"trace[1263837314] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"105.831526ms","start":"2026-05-11T00:51:52.672543Z","end":"2026-05-11T00:51:52.778375Z","steps":["trace[1263837314] 'range keys from in-memory index tree' (duration: 105.512629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.778624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.253663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.778728Z","caller":"traceutil/trace.go:171","msg":"trace[1883929064] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"107.385936ms","start":"2026-05-11T00:51:52.671318Z","end":"2026-05-11T00:51:52.778704Z","steps":["trace[1883929064] 'range keys from in-memory index tree' (duration: 107.205503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.778781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.024759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.778862Z","caller":"traceutil/trace.go:171","msg":"trace[291868428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"108.157522ms","start":"2026-05-11T00:51:52.670687Z","end":"2026-05-11T00:51:52.778845Z","steps":["trace[291868428] 'range keys from in-memory index tree' (duration: 107.927128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:52.778903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.413037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:52.778956Z","caller":"traceutil/trace.go:171","msg":"trace[920451894] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"107.486288ms","start":"2026-05-11T00:51:52.671454Z","end":"2026-05-11T00:51:52.77894Z","steps":["trace[920451894] 'count revisions from in-memory index tree' (duration: 107.322165ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:55.400708Z","caller":"traceutil/trace.go:171","msg":"trace[759589554] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"240.278585ms","start":"2026-05-11T00:51:55.160403Z","end":"2026-05-11T00:51:55.400681Z","steps":["trace[759589554] 'read index received' (duration: 239.231673ms)","trace[759589554] 'applied index is now lower than readState.Index' (duration: 1.046002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:55.400912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.49303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-11T00:51:55.400954Z","caller":"traceutil/trace.go:171","msg":"trace[1702019251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"240.588161ms","start":"2026-05-11T00:51:55.160355Z","end":"2026-05-11T00:51:55.400944Z","steps":["trace[1702019251] 'agreement among raft nodes before linearized reading' (duration: 240.428448ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:55.400957Z","caller":"traceutil/trace.go:171","msg":"trace[1847852249] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"394.536575ms","start":"2026-05-11T00:51:55.006383Z","end":"2026-05-11T00:51:55.40092Z","steps":["trace[1847852249] 'process raft request' (duration: 393.572096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:55.401074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:55.00636Z","time spent":"394.644867ms","remote":"127.0.0.1:33658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:51:56.151983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930423051,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T00:51:56.409062Z","caller":"traceutil/trace.go:171","msg":"trace[323651175] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"758.084071ms","start":"2026-05-11T00:51:55.650954Z","end":"2026-05-11T00:51:56.409038Z","steps":["trace[323651175] 'read index received' (duration: 757.931498ms)","trace[323651175] 'applied index is now lower than readState.Index' (duration: 151.503µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:56.409131Z","caller":"traceutil/trace.go:171","msg":"trace[769161801] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"937.258318ms","start":"2026-05-11T00:51:55.471848Z","end":"2026-05-11T00:51:56.409106Z","steps":["trace[769161801] 'process raft request' (duration: 937.046024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:56.409262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.954663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:51:56.409337Z","caller":"traceutil/trace.go:171","msg":"trace[1209406487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"745.035644ms","start":"2026-05-11T00:51:55.664267Z","end":"2026-05-11T00:51:56.409302Z","steps":["trace[1209406487] 'agreement among raft nodes before linearized reading' (duration: 744.916292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:56.409332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.371587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fcsvq\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-11T00:51:56.409375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:55.664258Z","time spent":"745.107836ms","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T00:51:56.409382Z","caller":"traceutil/trace.go:171","msg":"trace[982724837] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fcsvq; range_end:; response_count:1; response_revision:595; }","duration":"758.446099ms","start":"2026-05-11T00:51:55.650923Z","end":"2026-05-11T00:51:56.409369Z","steps":["trace[982724837] 'agreement among raft nodes before linearized reading' (duration: 758.233054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:56.409415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:55.650906Z","time spent":"758.500839ms","remote":"127.0.0.1:33740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-fcsvq\" "} {"level":"warn","ts":"2026-05-11T00:51:56.409473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.747799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-11T00:51:56.40954Z","caller":"traceutil/trace.go:171","msg":"trace[672585999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"197.846941ms","start":"2026-05-11T00:51:56.211673Z","end":"2026-05-11T00:51:56.40952Z","steps":["trace[672585999] 'agreement among raft nodes before linearized reading' (duration: 197.704408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:56.409492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:55.471827Z","time spent":"937.423172ms","remote":"127.0.0.1:33796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:51:56.409627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.048535ms","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-11T00:51:56.409694Z","caller":"traceutil/trace.go:171","msg":"trace[1847062326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"199.159877ms","start":"2026-05-11T00:51:56.210515Z","end":"2026-05-11T00:51:56.409675Z","steps":["trace[1847062326] 'agreement among raft nodes before linearized reading' (duration: 199.029084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:56.409464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.803688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T00:51:56.409859Z","caller":"traceutil/trace.go:171","msg":"trace[1641210036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"137.259197ms","start":"2026-05-11T00:51:56.272572Z","end":"2026-05-11T00:51:56.409831Z","steps":["trace[1641210036] 'agreement among raft nodes before linearized reading' (duration: 136.694306ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:56.592965Z","caller":"traceutil/trace.go:171","msg":"trace[2100558469] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"179.132757ms","start":"2026-05-11T00:51:56.413773Z","end":"2026-05-11T00:51:56.592906Z","steps":["trace[2100558469] 'process raft request' (duration: 149.220544ms)","trace[2100558469] 'compare' (duration: 29.79285ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:58.66405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000214994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-11T00:51:58.664131Z","caller":"traceutil/trace.go:171","msg":"trace[1369838525] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000345126s","start":"2026-05-11T00:51:56.663768Z","end":"2026-05-11T00:51:58.664114Z","steps":["trace[1369838525] 'agreement among raft nodes before linearized reading' (duration: 2.000243734s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:58.66418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:56.66374Z","time spent":"2.000430118s","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/11 00:51:58 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-11T00:51:59.47937Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.283234772s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-11T00:52:00.339649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.509509661s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:52:00.339912Z","caller":"traceutil/trace.go:171","msg":"trace[1661831939] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"3.924779843s","start":"2026-05-11T00:51:56.415107Z","end":"2026-05-11T00:52:00.339887Z","steps":["trace[1661831939] 'process raft request' (duration: 3.92462405s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:00.340033Z","caller":"traceutil/trace.go:171","msg":"trace[608747968] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"3.925813514s","start":"2026-05-11T00:51:56.414201Z","end":"2026-05-11T00:52:00.340014Z","steps":["trace[608747968] 'process raft request' (duration: 415.860024ms)","trace[608747968] 'compare' (duration: 3.509118403s)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:00.340039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:56.415098Z","time spent":"3.924868465s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:52:00.340065Z","caller":"traceutil/trace.go:171","msg":"trace[135618618] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"3.917448302s","start":"2026-05-11T00:51:56.42259Z","end":"2026-05-11T00:52:00.340038Z","steps":["trace[135618618] 'process raft request' (duration: 3.917220488s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:00.340117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:56.414191Z","time spent":"3.925879665s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:52:00.340203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:56.422573Z","time spent":"3.917552734s","remote":"127.0.0.1:33740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:52:00.340218Z","caller":"traceutil/trace.go:171","msg":"trace[1806315856] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:626; }","duration":"3.923928956s","start":"2026-05-11T00:51:56.416278Z","end":"2026-05-11T00:52:00.340207Z","steps":["trace[1806315856] 'read index received' (duration: 146.760112ms)","trace[1806315856] 'applied index is now lower than readState.Index' (duration: 3.777167564s)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:00.570781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.898833ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-11T00:52:00.570876Z","caller":"traceutil/trace.go:171","msg":"trace[265120973] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"230.613097ms","start":"2026-05-11T00:52:00.340246Z","end":"2026-05-11T00:52:00.570859Z","steps":["trace[265120973] 'read index received' (duration: 47.221µs)","trace[265120973] 'applied index is now lower than readState.Index' (duration: 230.564546ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:00.571026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.147609093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-11T00:52:00.57111Z","caller":"traceutil/trace.go:171","msg":"trace[1045019786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"3.147733557s","start":"2026-05-11T00:51:57.423365Z","end":"2026-05-11T00:52:00.571099Z","steps":["trace[1045019786] 'agreement among raft nodes before linearized reading' (duration: 3.147538802s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:00.571144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:57.423345Z","time spent":"3.147790709s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T00:52:00.571428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973380981s","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-11T00:52:00.571473Z","caller":"traceutil/trace.go:171","msg":"trace[861762037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"1.973492034s","start":"2026-05-11T00:51:58.597973Z","end":"2026-05-11T00:52:00.571465Z","steps":["trace[861762037] 'agreement among raft nodes before linearized reading' (duration: 1.973391552s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:00.5715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:58.597951Z","time spent":"1.973542514s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T00:52:00.571512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.906989854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:52:00.571604Z","caller":"traceutil/trace.go:171","msg":"trace[906740004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"1.907128947s","start":"2026-05-11T00:51:58.664451Z","end":"2026-05-11T00:52:00.57158Z","steps":["trace[906740004] 'agreement among raft nodes before linearized reading' (duration: 1.906932403s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:00.571657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:58.664428Z","time spent":"1.907216969s","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:52:00.571696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.155398142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-11T00:52:00.571777Z","caller":"traceutil/trace.go:171","msg":"trace[1392289696] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:599; }","duration":"4.155498954s","start":"2026-05-11T00:51:56.416241Z","end":"2026-05-11T00:52:00.57174Z","steps":["trace[1392289696] 'agreement among raft nodes before linearized reading' (duration: 3.924039358s)","trace[1392289696] 'range keys from in-memory index tree' (duration: 231.359434ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:00.57181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:56.416235Z","time spent":"4.155570016s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-11T00:52:02.172124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.697565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T00:52:02.172191Z","caller":"traceutil/trace.go:171","msg":"trace[1192346297] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:615; }","duration":"264.843699ms","start":"2026-05-11T00:52:01.907332Z","end":"2026-05-11T00:52:02.172176Z","steps":["trace[1192346297] 'range keys from in-memory index tree' (duration: 264.50274ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:02.17215Z","caller":"traceutil/trace.go:171","msg":"trace[1982849301] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"155.299581ms","start":"2026-05-11T00:52:02.016833Z","end":"2026-05-11T00:52:02.172132Z","steps":["trace[1982849301] 'process raft request' (duration: 149.233673ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:03.856446Z","caller":"traceutil/trace.go:171","msg":"trace[711185214] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"116.879921ms","start":"2026-05-11T00:52:03.739543Z","end":"2026-05-11T00:52:03.856423Z","steps":["trace[711185214] 'process raft request' (duration: 116.387091ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:03.971295Z","caller":"traceutil/trace.go:171","msg":"trace[1547311935] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"108.36403ms","start":"2026-05-11T00:52:03.862911Z","end":"2026-05-11T00:52:03.971275Z","steps":["trace[1547311935] 'process raft request' (duration: 108.243907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:04.218028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.763184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:52:04.218155Z","caller":"traceutil/trace.go:171","msg":"trace[1826261085] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"273.900812ms","start":"2026-05-11T00:52:03.944229Z","end":"2026-05-11T00:52:04.21813Z","steps":["trace[1826261085] 'process raft request' (duration: 152.984615ms)","trace[1826261085] 'compare' (duration: 120.528569ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:04.230431Z","caller":"traceutil/trace.go:171","msg":"trace[1761966712] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"249.152099ms","start":"2026-05-11T00:52:03.981262Z","end":"2026-05-11T00:52:04.230415Z","steps":["trace[1761966712] 'process raft request' (duration: 248.893174ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:04.389053Z","caller":"traceutil/trace.go:171","msg":"trace[456253392] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"156.279811ms","start":"2026-05-11T00:52:04.232744Z","end":"2026-05-11T00:52:04.389024Z","steps":["trace[456253392] 'process raft request' (duration: 151.9675ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:04.568072Z","caller":"traceutil/trace.go:171","msg":"trace[1705368033] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"172.668791ms","start":"2026-05-11T00:52:04.395378Z","end":"2026-05-11T00:52:04.568046Z","steps":["trace[1705368033] 'process raft request' (duration: 133.925224ms)","trace[1705368033] 'compare' (duration: 38.539712ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:04.568352Z","caller":"traceutil/trace.go:171","msg":"trace[560106047] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"164.162109ms","start":"2026-05-11T00:52:04.404157Z","end":"2026-05-11T00:52:04.568319Z","steps":["trace[560106047] 'process raft request' (duration: 163.815212ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:04.568355Z","caller":"traceutil/trace.go:171","msg":"trace[1187639754] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"114.033671ms","start":"2026-05-11T00:52:04.454299Z","end":"2026-05-11T00:52:04.568332Z","steps":["trace[1187639754] 'process raft request' (duration: 113.889358ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:04.708081Z","caller":"traceutil/trace.go:171","msg":"trace[1863838838] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"113.794986ms","start":"2026-05-11T00:52:04.594267Z","end":"2026-05-11T00:52:04.708062Z","steps":["trace[1863838838] 'process raft request' (duration: 113.705304ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:04.708225Z","caller":"traceutil/trace.go:171","msg":"trace[1036091340] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"115.141045ms","start":"2026-05-11T00:52:04.593068Z","end":"2026-05-11T00:52:04.708209Z","steps":["trace[1036091340] 'process raft request' (duration: 114.798318ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:05.898088Z","caller":"traceutil/trace.go:171","msg":"trace[1926995987] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"176.021229ms","start":"2026-05-11T00:52:05.721998Z","end":"2026-05-11T00:52:05.898019Z","steps":["trace[1926995987] 'process raft request' (duration: 142.256546ms)","trace[1926995987] 'compare' (duration: 33.196091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:05.898655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.559719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-11T00:52:05.89888Z","caller":"traceutil/trace.go:171","msg":"trace[708220891] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"161.848085ms","start":"2026-05-11T00:52:05.736978Z","end":"2026-05-11T00:52:05.898826Z","steps":["trace[708220891] 'agreement among raft nodes before linearized reading' (duration: 161.13125ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:05.8978Z","caller":"traceutil/trace.go:171","msg":"trace[1399993622] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"160.693451ms","start":"2026-05-11T00:52:05.737022Z","end":"2026-05-11T00:52:05.897715Z","steps":["trace[1399993622] 'read index received' (duration: 127.167112ms)","trace[1399993622] 'applied index is now lower than readState.Index' (duration: 33.524169ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:06.272587Z","caller":"traceutil/trace.go:171","msg":"trace[1790628227] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"135.204829ms","start":"2026-05-11T00:52:06.137364Z","end":"2026-05-11T00:52:06.272569Z","steps":["trace[1790628227] 'read index received' (duration: 134.908493ms)","trace[1790628227] 'applied index is now lower than readState.Index' (duration: 295.756µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:06.27264Z","caller":"traceutil/trace.go:171","msg":"trace[282234932] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"301.678045ms","start":"2026-05-11T00:52:05.970943Z","end":"2026-05-11T00:52:06.272621Z","steps":["trace[282234932] 'process raft request' (duration: 301.43433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:06.272767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:05.970915Z","time spent":"301.801528ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:52:06.272827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.463824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-11T00:52:06.272875Z","caller":"traceutil/trace.go:171","msg":"trace[1833182577] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:676; }","duration":"135.559987ms","start":"2026-05-11T00:52:06.137303Z","end":"2026-05-11T00:52:06.272863Z","steps":["trace[1833182577] 'agreement among raft nodes before linearized reading' (duration: 135.384953ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:06.546668Z","caller":"traceutil/trace.go:171","msg":"trace[1733987550] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"190.916857ms","start":"2026-05-11T00:52:06.355729Z","end":"2026-05-11T00:52:06.546646Z","steps":["trace[1733987550] 'process raft request' (duration: 190.63701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:06.871527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.06065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:52:06.87184Z","caller":"traceutil/trace.go:171","msg":"trace[2118805190] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"440.671827ms","start":"2026-05-11T00:52:06.43115Z","end":"2026-05-11T00:52:06.871822Z","steps":["trace[2118805190] 'read index received' (duration: 115.157156ms)","trace[2118805190] 'applied index is now lower than readState.Index' (duration: 325.513091ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:06.87186Z","caller":"traceutil/trace.go:171","msg":"trace[413099511] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"508.158999ms","start":"2026-05-11T00:52:06.363677Z","end":"2026-05-11T00:52:06.871836Z","steps":["trace[413099511] 'process raft request' (duration: 275.718121ms)","trace[413099511] 'compare' (duration: 231.64458ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:06.871947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.363646Z","time spent":"508.260401ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:52:06.871995Z","caller":"traceutil/trace.go:171","msg":"trace[1155944669] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"334.212958ms","start":"2026-05-11T00:52:06.537707Z","end":"2026-05-11T00:52:06.87192Z","steps":["trace[1155944669] 'process raft request' (duration: 333.914051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:06.872143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.995904ms","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-11T00:52:06.872182Z","caller":"traceutil/trace.go:171","msg":"trace[2060356446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"441.093316ms","start":"2026-05-11T00:52:06.431077Z","end":"2026-05-11T00:52:06.872171Z","steps":["trace[2060356446] 'agreement among raft nodes before linearized reading' (duration: 440.82427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:06.872215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.431047Z","time spent":"441.161188ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T00:52:06.872349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.537681Z","time spent":"334.477004ms","remote":"127.0.0.1:33632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:52:07.372982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930423192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:52:07.694698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.17573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:52:07.695195Z","caller":"traceutil/trace.go:171","msg":"trace[2014678848] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.076467884s","start":"2026-05-11T00:52:06.618697Z","end":"2026-05-11T00:52:07.695165Z","steps":["trace[2014678848] 'process raft request' (duration: 945.70078ms)","trace[2014678848] 'compare' (duration: 129.787222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:07.695592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.618667Z","time spent":"1.076653987s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:52:07.710668Z","caller":"traceutil/trace.go:171","msg":"trace[1615835684] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"832.5607ms","start":"2026-05-11T00:52:06.878086Z","end":"2026-05-11T00:52:07.710647Z","steps":["trace[1615835684] 'process raft request' (duration: 832.396007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.710808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.878065Z","time spent":"832.668202ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:52:07.711229Z","caller":"traceutil/trace.go:171","msg":"trace[575725082] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:712; }","duration":"839.340765ms","start":"2026-05-11T00:52:06.871877Z","end":"2026-05-11T00:52:07.711217Z","steps":["trace[575725082] 'read index received' (duration: 692.574876ms)","trace[575725082] 'applied index is now lower than readState.Index' (duration: 146.764909ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:07.711303Z","caller":"traceutil/trace.go:171","msg":"trace[1958087418] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"795.272078ms","start":"2026-05-11T00:52:06.916023Z","end":"2026-05-11T00:52:07.711295Z","steps":["trace[1958087418] 'process raft request' (duration: 794.578023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.711344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.916001Z","time spent":"795.320279ms","remote":"127.0.0.1:33632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:52:07.711819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139387449s","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-11T00:52:07.711861Z","caller":"traceutil/trace.go:171","msg":"trace[1867386033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.139469411s","start":"2026-05-11T00:52:06.572382Z","end":"2026-05-11T00:52:07.711851Z","steps":["trace[1867386033] 'agreement among raft nodes before linearized reading' (duration: 1.139351197s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.711889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.572363Z","time spent":"1.139519161s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T00:52:07.711986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174010534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-11T00:52:07.712062Z","caller":"traceutil/trace.go:171","msg":"trace[1246537384] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"1.174178368s","start":"2026-05-11T00:52:06.537864Z","end":"2026-05-11T00:52:07.712043Z","steps":["trace[1246537384] 'agreement among raft nodes before linearized reading' (duration: 1.173955273s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.712104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.537833Z","time spent":"1.17426153s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-11T00:52:07.711994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105409727s","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-11T00:52:07.712325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.691346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-05-11T00:52:07.712356Z","caller":"traceutil/trace.go:171","msg":"trace[1513646028] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"774.751447ms","start":"2026-05-11T00:52:06.937598Z","end":"2026-05-11T00:52:07.712349Z","steps":["trace[1513646028] 'agreement among raft nodes before linearized reading' (duration: 774.671016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.712374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.937584Z","time spent":"774.785038ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188274,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-11T00:52:07.712308Z","caller":"traceutil/trace.go:171","msg":"trace[373750694] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.105765035s","start":"2026-05-11T00:52:06.60652Z","end":"2026-05-11T00:52:07.712285Z","steps":["trace[373750694] 'agreement among raft nodes before linearized reading' (duration: 1.105151331s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.712503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.126877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-05-11T00:52:07.712531Z","caller":"traceutil/trace.go:171","msg":"trace[1047374817] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"316.194409ms","start":"2026-05-11T00:52:07.39633Z","end":"2026-05-11T00:52:07.712524Z","steps":["trace[1047374817] 'agreement among raft nodes before linearized reading' (duration: 316.110127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.712551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:07.396308Z","time spent":"316.238099ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188508,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-11T00:52:07.712625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.606499Z","time spent":"1.106015611s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T00:52:07.712793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.864781ms","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-11T00:52:07.71282Z","caller":"traceutil/trace.go:171","msg":"trace[2077313995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"941.913782ms","start":"2026-05-11T00:52:06.7709Z","end":"2026-05-11T00:52:07.712814Z","steps":["trace[2077313995] 'agreement among raft nodes before linearized reading' (duration: 941.843611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.712837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.770888Z","time spent":"941.944083ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T00:52:07.712874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050389754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:52:07.712907Z","caller":"traceutil/trace.go:171","msg":"trace[513576308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"1.050458825s","start":"2026-05-11T00:52:06.662441Z","end":"2026-05-11T00:52:07.7129Z","steps":["trace[513576308] 'agreement among raft nodes before linearized reading' (duration: 1.050409934s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.712888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.887383ms","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-11T00:52:07.712929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.662426Z","time spent":"1.050498966s","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T00:52:07.712953Z","caller":"traceutil/trace.go:171","msg":"trace[598379402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"989.974625ms","start":"2026-05-11T00:52:06.72296Z","end":"2026-05-11T00:52:07.712934Z","steps":["trace[598379402] 'agreement among raft nodes before linearized reading' (duration: 989.797481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.712705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.433362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-05-11T00:52:07.712999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.722946Z","time spent":"990.041826ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T00:52:07.713022Z","caller":"traceutil/trace.go:171","msg":"trace[1078015298] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"974.767968ms","start":"2026-05-11T00:52:06.738246Z","end":"2026-05-11T00:52:07.713014Z","steps":["trace[1078015298] 'agreement among raft nodes before linearized reading' (duration: 974.402031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.713048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.738216Z","time spent":"974.82436ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-11T00:52:07.712731Z","caller":"traceutil/trace.go:171","msg":"trace[545072621] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"760.318446ms","start":"2026-05-11T00:52:06.952406Z","end":"2026-05-11T00:52:07.712724Z","steps":["trace[545072621] 'process raft request' (duration: 758.691221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:07.713114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:06.952382Z","time spent":"760.704755ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:52:19.440316Z","caller":"traceutil/trace.go:171","msg":"trace[887780147] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"413.363483ms","start":"2026-05-11T00:52:19.026929Z","end":"2026-05-11T00:52:19.440293Z","steps":["trace[887780147] 'process raft request' (duration: 413.21071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:19.440469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:19.026907Z","time spent":"413.486917ms","remote":"127.0.0.1:33796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:52:23.872791Z","caller":"traceutil/trace.go:171","msg":"trace[568804202] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"229.329913ms","start":"2026-05-11T00:52:23.643443Z","end":"2026-05-11T00:52:23.872773Z","steps":["trace[568804202] 'process raft request' (duration: 227.734028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:24.084202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.247066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:52:24.084402Z","caller":"traceutil/trace.go:171","msg":"trace[1049169419] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"207.790227ms","start":"2026-05-11T00:52:23.876588Z","end":"2026-05-11T00:52:24.084378Z","steps":["trace[1049169419] 'process raft request' (duration: 83.148912ms)","trace[1049169419] 'compare' (duration: 123.99147ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:24.097528Z","caller":"traceutil/trace.go:171","msg":"trace[761828000] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"220.575611ms","start":"2026-05-11T00:52:23.876936Z","end":"2026-05-11T00:52:24.097511Z","steps":["trace[761828000] 'process raft request' (duration: 220.398607ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:24.097883Z","caller":"traceutil/trace.go:171","msg":"trace[1275533438] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"220.584241ms","start":"2026-05-11T00:52:23.877285Z","end":"2026-05-11T00:52:24.097869Z","steps":["trace[1275533438] 'process raft request' (duration: 220.157491ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:24.097983Z","caller":"traceutil/trace.go:171","msg":"trace[1240015635] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:853; }","duration":"202.406545ms","start":"2026-05-11T00:52:23.895553Z","end":"2026-05-11T00:52:24.097959Z","steps":["trace[1240015635] 'read index received' (duration: 64.25724ms)","trace[1240015635] 'applied index is now lower than readState.Index' (duration: 138.148415ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:24.098026Z","caller":"traceutil/trace.go:171","msg":"trace[721488270] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"220.229243ms","start":"2026-05-11T00:52:23.877768Z","end":"2026-05-11T00:52:24.097997Z","steps":["trace[721488270] 'process raft request' (duration: 220.001757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:24.098181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.608839ms","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-11T00:52:24.098268Z","caller":"traceutil/trace.go:171","msg":"trace[1479258963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"202.733482ms","start":"2026-05-11T00:52:23.895516Z","end":"2026-05-11T00:52:24.098249Z","steps":["trace[1479258963] 'agreement among raft nodes before linearized reading' (duration: 202.529058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:24.41666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.96891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:52:24.416721Z","caller":"traceutil/trace.go:171","msg":"trace[931268583] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:858; }","duration":"106.886743ms","start":"2026-05-11T00:52:24.309823Z","end":"2026-05-11T00:52:24.41671Z","steps":["trace[931268583] 'read index received' (duration: 57.192µs)","trace[931268583] 'applied index is now lower than readState.Index' (duration: 106.828821ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:24.417014Z","caller":"traceutil/trace.go:171","msg":"trace[286748071] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"225.022803ms","start":"2026-05-11T00:52:24.191971Z","end":"2026-05-11T00:52:24.416993Z","steps":["trace[286748071] 'process raft request' (duration: 64.680054ms)","trace[286748071] 'compare' (duration: 159.766135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:24.417282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.431535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-05-11T00:52:24.417303Z","caller":"traceutil/trace.go:171","msg":"trace[1192334784] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:820; }","duration":"107.514547ms","start":"2026-05-11T00:52:24.309782Z","end":"2026-05-11T00:52:24.417297Z","steps":["trace[1192334784] 'agreement among raft nodes before linearized reading' (duration: 106.954615ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:24.654175Z","caller":"traceutil/trace.go:171","msg":"trace[92953293] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"229.292258ms","start":"2026-05-11T00:52:24.424845Z","end":"2026-05-11T00:52:24.654137Z","steps":["trace[92953293] 'process raft request' (duration: 174.746818ms)","trace[92953293] 'compare' (duration: 54.232953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:54.234272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.29572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:52:54.234372Z","caller":"traceutil/trace.go:171","msg":"trace[1226466697] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1014; }","duration":"101.458494ms","start":"2026-05-11T00:52:54.132892Z","end":"2026-05-11T00:52:54.234351Z","steps":["trace[1226466697] 'count revisions from in-memory index tree' (duration: 101.178178ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:53:54.574279Z","caller":"traceutil/trace.go:171","msg":"trace[493996087] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"107.882371ms","start":"2026-05-11T00:53:54.466372Z","end":"2026-05-11T00:53:54.574254Z","steps":["trace[493996087] 'read index received' (duration: 69.858023ms)","trace[493996087] 'applied index is now lower than readState.Index' (duration: 38.023118ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:53:54.574292Z","caller":"traceutil/trace.go:171","msg":"trace[1427118761] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"111.498165ms","start":"2026-05-11T00:53:54.462761Z","end":"2026-05-11T00:53:54.574259Z","steps":["trace[1427118761] 'process raft request' (duration: 73.537359ms)","trace[1427118761] 'compare' (duration: 37.806322ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:53:54.574502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.108477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T00:53:54.574544Z","caller":"traceutil/trace.go:171","msg":"trace[1077453578] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1213; }","duration":"108.195209ms","start":"2026-05-11T00:53:54.466335Z","end":"2026-05-11T00:53:54.574531Z","steps":["trace[1077453578] 'agreement among raft nodes before linearized reading' (duration: 108.018995ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:03.120187Z","caller":"traceutil/trace.go:171","msg":"trace[354659450] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"956.668591ms","start":"2026-05-11T00:54:02.163501Z","end":"2026-05-11T00:54:03.12017Z","steps":["trace[354659450] 'process raft request' (duration: 955.50295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:03.120559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:02.163475Z","time spent":"956.765063ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:54:04.057731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930424578,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:54:04.174727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.584602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:54:04.174826Z","caller":"traceutil/trace.go:171","msg":"trace[1045614077] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.051326352s","start":"2026-05-11T00:54:03.123477Z","end":"2026-05-11T00:54:04.174803Z","steps":["trace[1045614077] 'process raft request' (duration: 419.560986ms)","trace[1045614077] 'compare' (duration: 631.230982ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:54:04.174886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:03.123467Z","time spent":"1.051391294s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:54:04.178824Z","caller":"traceutil/trace.go:171","msg":"trace[726269121] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1333; }","duration":"621.67326ms","start":"2026-05-11T00:54:03.557133Z","end":"2026-05-11T00:54:04.178807Z","steps":["trace[726269121] 'read index received' (duration: 621.416433ms)","trace[726269121] 'applied index is now lower than readState.Index' (duration: 256.197µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:54:04.179115Z","caller":"traceutil/trace.go:171","msg":"trace[1458918994] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.038777451s","start":"2026-05-11T00:54:03.140327Z","end":"2026-05-11T00:54:04.179105Z","steps":["trace[1458918994] 'process raft request' (duration: 1.038294057s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:04.17921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:03.140308Z","time spent":"1.038834753s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:54:04.179491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.712525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:54:04.179574Z","caller":"traceutil/trace.go:171","msg":"trace[491130567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"515.861729ms","start":"2026-05-11T00:54:03.663693Z","end":"2026-05-11T00:54:04.179554Z","steps":["trace[491130567] 'agreement among raft nodes before linearized reading' (duration: 515.559821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:04.179615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:03.663673Z","time spent":"515.933901ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:54:04.179629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.662057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:54:04.179648Z","caller":"traceutil/trace.go:171","msg":"trace[997605287] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"1.039241824s","start":"2026-05-11T00:54:03.140387Z","end":"2026-05-11T00:54:04.179629Z","steps":["trace[997605287] 'process raft request' (duration: 1.038340359s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:04.179679Z","caller":"traceutil/trace.go:171","msg":"trace[1535730367] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1275; }","duration":"208.751679ms","start":"2026-05-11T00:54:03.970915Z","end":"2026-05-11T00:54:04.179667Z","steps":["trace[1535730367] 'agreement among raft nodes before linearized reading' (duration: 208.624185ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:04.179729Z","caller":"traceutil/trace.go:171","msg":"trace[65204532] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.039178791s","start":"2026-05-11T00:54:03.140519Z","end":"2026-05-11T00:54:04.179697Z","steps":["trace[65204532] 'process raft request' (duration: 1.038262886s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:04.179761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:03.140371Z","time spent":"1.039328945s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:54:04.179754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.861678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T00:54:04.179857Z","caller":"traceutil/trace.go:171","msg":"trace[1000011439] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1275; }","duration":"344.95531ms","start":"2026-05-11T00:54:03.834856Z","end":"2026-05-11T00:54:04.179811Z","steps":["trace[1000011439] 'agreement among raft nodes before linearized reading' (duration: 344.729474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:04.179851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:03.140486Z","time spent":"1.039287174s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:54:04.179986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:03.834786Z","time spent":"345.108364ms","remote":"127.0.0.1:34110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":112,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:54:04.180106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.963445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T00:54:04.180147Z","caller":"traceutil/trace.go:171","msg":"trace[1193073166] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1275; }","duration":"623.042267ms","start":"2026-05-11T00:54:03.557093Z","end":"2026-05-11T00:54:04.180135Z","steps":["trace[1193073166] 'agreement among raft nodes before linearized reading' (duration: 622.969515ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:04.179536Z","caller":"traceutil/trace.go:171","msg":"trace[274632705] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.038973096s","start":"2026-05-11T00:54:03.14052Z","end":"2026-05-11T00:54:04.179493Z","steps":["trace[274632705] 'process raft request' (duration: 1.038234266s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:04.180176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:03.557062Z","time spent":"623.106109ms","remote":"127.0.0.1:33834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:54:04.180244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:03.140511Z","time spent":"1.039670975s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:54:06.970745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291405930424603,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T00:54:06.992183Z","caller":"traceutil/trace.go:171","msg":"trace[586839535] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"804.592606ms","start":"2026-05-11T00:54:06.187563Z","end":"2026-05-11T00:54:06.992156Z","steps":["trace[586839535] 'process raft request' (duration: 803.829985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:06.992342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:06.187531Z","time spent":"804.723499ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:54:06.993228Z","caller":"traceutil/trace.go:171","msg":"trace[155200417] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"796.416459ms","start":"2026-05-11T00:54:06.196795Z","end":"2026-05-11T00:54:06.993212Z","steps":["trace[155200417] 'process raft request' (duration: 796.320857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:06.993328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:06.196781Z","time spent":"796.497531ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:54:06.993272Z","caller":"traceutil/trace.go:171","msg":"trace[751417573] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"804.149543ms","start":"2026-05-11T00:54:06.189097Z","end":"2026-05-11T00:54:06.993247Z","steps":["trace[751417573] 'process raft request' (duration: 803.915907ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:06.993445Z","caller":"traceutil/trace.go:171","msg":"trace[88747541] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1340; }","duration":"523.106339ms","start":"2026-05-11T00:54:06.470326Z","end":"2026-05-11T00:54:06.993433Z","steps":["trace[88747541] 'read index received' (duration: 521.229159ms)","trace[88747541] 'applied index is now lower than readState.Index' (duration: 1.87616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:54:06.993483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:06.189087Z","time spent":"804.320688ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:54:06.993611Z","caller":"traceutil/trace.go:171","msg":"trace[350791214] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"796.665665ms","start":"2026-05-11T00:54:06.196935Z","end":"2026-05-11T00:54:06.993601Z","steps":["trace[350791214] 'process raft request' (duration: 796.228464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:06.993645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.491847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:54:06.993679Z","caller":"traceutil/trace.go:171","msg":"trace[1034463332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"328.547099ms","start":"2026-05-11T00:54:06.665121Z","end":"2026-05-11T00:54:06.993668Z","steps":["trace[1034463332] 'agreement among raft nodes before linearized reading' (duration: 328.490708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:06.993676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:06.196917Z","time spent":"796.725707ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:54:06.993709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:06.66511Z","time spent":"328.59176ms","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:54:06.993819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.478189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:54:06.993897Z","caller":"traceutil/trace.go:171","msg":"trace[1861228437] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1282; }","duration":"523.595692ms","start":"2026-05-11T00:54:06.470284Z","end":"2026-05-11T00:54:06.99388Z","steps":["trace[1861228437] 'agreement among raft nodes before linearized reading' (duration: 523.472159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:06.994038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:06.470269Z","time spent":"523.755827ms","remote":"127.0.0.1:50192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-11T00:54:23.850491Z","caller":"traceutil/trace.go:171","msg":"trace[151651159] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"107.523553ms","start":"2026-05-11T00:54:23.742948Z","end":"2026-05-11T00:54:23.850471Z","steps":["trace[151651159] 'read index received' (duration: 107.390509ms)","trace[151651159] 'applied index is now lower than readState.Index' (duration: 132.154µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:54:23.850588Z","caller":"traceutil/trace.go:171","msg":"trace[2057835159] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"139.851653ms","start":"2026-05-11T00:54:23.710728Z","end":"2026-05-11T00:54:23.85058Z","steps":["trace[2057835159] 'process raft request' (duration: 139.609376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:23.850794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.830921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-05-11T00:54:23.850833Z","caller":"traceutil/trace.go:171","msg":"trace[688182058] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1347; }","duration":"107.906074ms","start":"2026-05-11T00:54:23.742915Z","end":"2026-05-11T00:54:23.850822Z","steps":["trace[688182058] 'agreement among raft nodes before linearized reading' (duration: 107.819831ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:25.413756Z","caller":"traceutil/trace.go:171","msg":"trace[1164575008] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"148.912259ms","start":"2026-05-11T00:54:25.26482Z","end":"2026-05-11T00:54:25.413732Z","steps":["trace[1164575008] 'process raft request' (duration: 73.274914ms)","trace[1164575008] 'compare' (duration: 75.493591ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:54:25.434713Z","caller":"traceutil/trace.go:171","msg":"trace[367150812] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1416; }","duration":"137.037982ms","start":"2026-05-11T00:54:25.29765Z","end":"2026-05-11T00:54:25.434688Z","steps":["trace[367150812] 'read index received' (duration: 40.407538ms)","trace[367150812] 'applied index is now lower than readState.Index' (duration: 96.629223ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:54:25.434777Z","caller":"traceutil/trace.go:171","msg":"trace[2146201779] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"169.848886ms","start":"2026-05-11T00:54:25.264897Z","end":"2026-05-11T00:54:25.434746Z","steps":["trace[2146201779] 'process raft request' (duration: 169.657661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:25.435078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.392091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-controller\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-05-11T00:54:25.435139Z","caller":"traceutil/trace.go:171","msg":"trace[101209259] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-controller; range_end:; response_count:1; response_revision:1352; }","duration":"137.507234ms","start":"2026-05-11T00:54:25.297613Z","end":"2026-05-11T00:54:25.43512Z","steps":["trace[101209259] 'agreement among raft nodes before linearized reading' (duration: 137.199296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:25.461121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.327602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:1 size:739"} {"level":"info","ts":"2026-05-11T00:54:25.461191Z","caller":"traceutil/trace.go:171","msg":"trace[1231680925] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:1; response_revision:1353; }","duration":"157.419564ms","start":"2026-05-11T00:54:25.303755Z","end":"2026-05-11T00:54:25.461174Z","steps":["trace[1231680925] 'agreement among raft nodes before linearized reading' (duration: 157.293371ms)"],"step_count":1}