{"level":"warn","ts":"2026-05-05T06:49:41.189655Z","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-05T06:49:41.18974Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-05T06:49:41.189807Z","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-05T06:49:41.189814Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-05T06:49:41.189833Z","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-05T06:49:41.190478Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-05-05T06:49:41.190553Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-05T06:49:41.195496Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.739011ms"} {"level":"info","ts":"2026-05-05T06:49:41.208685Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-05-05T06:49:41.208778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T06:49:41.2088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-05-05T06:49:41.208814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T06:49:41.208823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-05-05T06:49:41.208861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-05-05T06:49:41.223677Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T06:49:41.226116Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T06:49:41.229181Z","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-05T06:49:41.231972Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T06:49:41.232192Z","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-05T06:49:41.232183Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T06:49:41.232295Z","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-05T06:49:41.232309Z","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-05T06:49:41.234982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-05-05T06:49:41.235166Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-05T06:49:41.23895Z","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-05T06:49:41.239094Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-05T06:49:41.239149Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-05T06:49:41.239201Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T06:49:41.239243Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T06:49:42.109622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T06:49:42.109701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T06:49:42.109744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-05-05T06:49:42.10977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-05-05T06:49:42.109787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-05T06:49:42.109808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-05-05T06:49:42.109882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-05T06:49:42.205778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T06:49:42.205646Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:49:42.205834Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:49:42.205889Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:49:42.206205Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T06:49:42.206311Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T06:49:42.209284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-05-05T06:49:42.21248Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T06:49:42.286451Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:49:42.286653Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:49:42.286727Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-05T06:49:52.512644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.882085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4370"} {"level":"info","ts":"2026-05-05T06:49:52.512769Z","caller":"traceutil/trace.go:171","msg":"trace[662711391] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:339; }","duration":"142.027289ms","start":"2026-05-05T06:49:52.370719Z","end":"2026-05-05T06:49:52.512746Z","steps":["trace[662711391] 'range keys from in-memory index tree' (duration: 141.779943ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:58.376314Z","caller":"traceutil/trace.go:171","msg":"trace[755397671] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"252.031264ms","start":"2026-05-05T06:49:58.124253Z","end":"2026-05-05T06:49:58.376285Z","steps":["trace[755397671] 'process raft request' (duration: 250.469044ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:59.364503Z","caller":"traceutil/trace.go:171","msg":"trace[267665907] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"110.679063ms","start":"2026-05-05T06:49:59.253793Z","end":"2026-05-05T06:49:59.364472Z","steps":["trace[267665907] 'process raft request' (duration: 105.623545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:01.237903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.346735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:01.238024Z","caller":"traceutil/trace.go:171","msg":"trace[1160440931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"114.538541ms","start":"2026-05-05T06:50:01.123462Z","end":"2026-05-05T06:50:01.238Z","steps":["trace[1160440931] 'range keys from in-memory index tree' (duration: 114.239322ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:03.09371Z","caller":"traceutil/trace.go:171","msg":"trace[1938714673] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"339.606ms","start":"2026-05-05T06:50:02.754075Z","end":"2026-05-05T06:50:03.093681Z","steps":["trace[1938714673] 'process raft request' (duration: 339.384164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:03.094374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:02.754058Z","time spent":"339.729132ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:50:03.624611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670627241,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:50:04.125153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670627241,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T06:50:04.523064Z","caller":"traceutil/trace.go:171","msg":"trace[1784145204] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.425899194s","start":"2026-05-05T06:50:03.097137Z","end":"2026-05-05T06:50:04.523037Z","steps":["trace[1784145204] 'process raft request' (duration: 1.341551051s)","trace[1784145204] 'compare' (duration: 84.188419ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:04.523962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:03.097115Z","time spent":"1.426773415s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:04.539435Z","caller":"traceutil/trace.go:171","msg":"trace[1120789350] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"534.502142ms","start":"2026-05-05T06:50:04.004899Z","end":"2026-05-05T06:50:04.539401Z","steps":["trace[1120789350] 'process raft request' (duration: 533.395754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:04.539618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:04.004875Z","time spent":"534.652836ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:50:04.539681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415897099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:04.539771Z","caller":"traceutil/trace.go:171","msg":"trace[174468018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"1.416064833s","start":"2026-05-05T06:50:03.123688Z","end":"2026-05-05T06:50:04.539753Z","steps":["trace[174468018] 'agreement among raft nodes before linearized reading' (duration: 1.415901149s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:04.53982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:03.123668Z","time spent":"1.416143035s","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:50:04.540246Z","caller":"traceutil/trace.go:171","msg":"trace[93450747] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"1.415568601s","start":"2026-05-05T06:50:03.12373Z","end":"2026-05-05T06:50:04.539299Z","steps":["trace[93450747] 'read index received' (duration: 1.314935643s)","trace[93450747] 'applied index is now lower than readState.Index' (duration: 100.631487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:04.540397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180127908s","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-05T06:50:04.540457Z","caller":"traceutil/trace.go:171","msg":"trace[1185041084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"1.18022155s","start":"2026-05-05T06:50:03.36022Z","end":"2026-05-05T06:50:04.540442Z","steps":["trace[1185041084] 'agreement among raft nodes before linearized reading' (duration: 1.180124568s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:04.540496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:03.360198Z","time spent":"1.180290692s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T06:50:04.540698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.108139ms","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-05T06:50:04.540741Z","caller":"traceutil/trace.go:171","msg":"trace[1285389210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"602.180371ms","start":"2026-05-05T06:50:03.938552Z","end":"2026-05-05T06:50:04.540732Z","steps":["trace[1285389210] 'agreement among raft nodes before linearized reading' (duration: 602.102559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:04.540775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:03.938535Z","time spent":"602.234032ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T06:50:08.641402Z","caller":"traceutil/trace.go:171","msg":"trace[1081814471] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"100.171365ms","start":"2026-05-05T06:50:08.541202Z","end":"2026-05-05T06:50:08.641373Z","steps":["trace[1081814471] 'process raft request' (duration: 100.016491ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:10.793155Z","caller":"traceutil/trace.go:171","msg":"trace[1129997451] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"143.389584ms","start":"2026-05-05T06:50:10.649747Z","end":"2026-05-05T06:50:10.793136Z","steps":["trace[1129997451] 'process raft request' (duration: 143.24067ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:15.023651Z","caller":"traceutil/trace.go:171","msg":"trace[1553895964] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"212.743896ms","start":"2026-05-05T06:50:14.810869Z","end":"2026-05-05T06:50:15.023613Z","steps":["trace[1553895964] 'read index received' (duration: 212.606422ms)","trace[1553895964] 'applied index is now lower than readState.Index' (duration: 136.494µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:15.023841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.93456ms","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-05T06:50:15.023891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.982071ms","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-05T06:50:15.023787Z","caller":"traceutil/trace.go:171","msg":"trace[1244937012] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"399.012531ms","start":"2026-05-05T06:50:14.624733Z","end":"2026-05-05T06:50:15.023745Z","steps":["trace[1244937012] 'process raft request' (duration: 398.789904ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:15.023915Z","caller":"traceutil/trace.go:171","msg":"trace[1179312924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"209.005572ms","start":"2026-05-05T06:50:14.814872Z","end":"2026-05-05T06:50:15.023878Z","steps":["trace[1179312924] 'agreement among raft nodes before linearized reading' (duration: 208.93491ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:15.02392Z","caller":"traceutil/trace.go:171","msg":"trace[187729774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"209.031382ms","start":"2026-05-05T06:50:14.81488Z","end":"2026-05-05T06:50:15.023911Z","steps":["trace[187729774] 'agreement among raft nodes before linearized reading' (duration: 208.95731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:15.023808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.927791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T06:50:15.02406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:14.624713Z","time spent":"399.233296ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:15.02408Z","caller":"traceutil/trace.go:171","msg":"trace[203060864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:465; }","duration":"213.244399ms","start":"2026-05-05T06:50:14.810825Z","end":"2026-05-05T06:50:15.024069Z","steps":["trace[203060864] 'agreement among raft nodes before linearized reading' (duration: 212.933621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:15.536582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670627368,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:50:15.879336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:15.28673Z","time spent":"592.597969ms","remote":"127.0.0.1:32836","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T06:50:15.883156Z","caller":"traceutil/trace.go:171","msg":"trace[2043721859] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"856.053303ms","start":"2026-05-05T06:50:15.027085Z","end":"2026-05-05T06:50:15.883138Z","steps":["trace[2043721859] 'process raft request' (duration: 855.93ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:15.883269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:15.027063Z","time spent":"856.140744ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:15.890276Z","caller":"traceutil/trace.go:171","msg":"trace[2108903038] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:479; }","duration":"854.192795ms","start":"2026-05-05T06:50:15.036041Z","end":"2026-05-05T06:50:15.890234Z","steps":["trace[2108903038] 'read index received' (duration: 847.011993ms)","trace[2108903038] 'applied index is now lower than readState.Index' (duration: 7.180102ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:15.890297Z","caller":"traceutil/trace.go:171","msg":"trace[932933836] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"861.41269ms","start":"2026-05-05T06:50:15.028866Z","end":"2026-05-05T06:50:15.890278Z","steps":["trace[932933836] 'process raft request' (duration: 861.237555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:15.890361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:15.028857Z","time spent":"861.475311ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:15.890395Z","caller":"traceutil/trace.go:171","msg":"trace[1520025031] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"861.43193ms","start":"2026-05-05T06:50:15.028935Z","end":"2026-05-05T06:50:15.890367Z","steps":["trace[1520025031] 'process raft request' (duration: 861.273356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:15.890499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.630086ms","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-05T06:50:15.89054Z","caller":"traceutil/trace.go:171","msg":"trace[2108316665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"743.73934ms","start":"2026-05-05T06:50:15.146793Z","end":"2026-05-05T06:50:15.890532Z","steps":["trace[2108316665] 'agreement among raft nodes before linearized reading' (duration: 743.682729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:15.890539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.172802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T06:50:15.890567Z","caller":"traceutil/trace.go:171","msg":"trace[725318200] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:468; }","duration":"726.247105ms","start":"2026-05-05T06:50:15.164312Z","end":"2026-05-05T06:50:15.890559Z","steps":["trace[725318200] 'agreement among raft nodes before linearized reading' (duration: 726.191173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:15.890555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:15.028929Z","time spent":"861.542982ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:50:15.890589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:15.164286Z","time spent":"726.298885ms","remote":"127.0.0.1:35994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-05-05T06:50:15.890422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.36723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:15.89067Z","caller":"traceutil/trace.go:171","msg":"trace[1149264012] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:468; }","duration":"854.648597ms","start":"2026-05-05T06:50:15.035981Z","end":"2026-05-05T06:50:15.890629Z","steps":["trace[1149264012] 'agreement among raft nodes before linearized reading' (duration: 854.384451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:15.89075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:15.035954Z","time spent":"854.770631ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-05T06:50:15.89085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:15.146761Z","time spent":"743.797611ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T06:50:19.118996Z","caller":"traceutil/trace.go:171","msg":"trace[1620929285] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"468.041574ms","start":"2026-05-05T06:50:18.65093Z","end":"2026-05-05T06:50:19.118972Z","steps":["trace[1620929285] 'process raft request' (duration: 467.881309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:19.119155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:18.650912Z","time spent":"468.169976ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:50:20.443136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.066142ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T06:50:21.009276Z","caller":"traceutil/trace.go:171","msg":"trace[412086834] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"135.158204ms","start":"2026-05-05T06:50:20.874081Z","end":"2026-05-05T06:50:21.009239Z","steps":["trace[412086834] 'process raft request' (duration: 134.487498ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:23.814143Z","caller":"traceutil/trace.go:171","msg":"trace[331141057] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"348.437023ms","start":"2026-05-05T06:50:23.465677Z","end":"2026-05-05T06:50:23.814114Z","steps":["trace[331141057] 'process raft request' (duration: 347.839238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:23.814309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:23.46565Z","time spent":"348.574537ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:23.989315Z","caller":"traceutil/trace.go:171","msg":"trace[120698510] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"118.545872ms","start":"2026-05-05T06:50:23.870734Z","end":"2026-05-05T06:50:23.98928Z","steps":["trace[120698510] 'process raft request' (duration: 118.309136ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:24.483314Z","caller":"traceutil/trace.go:171","msg":"trace[1919172400] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"467.392977ms","start":"2026-05-05T06:50:24.015862Z","end":"2026-05-05T06:50:24.483255Z","steps":["trace[1919172400] 'read index received' (duration: 427.318799ms)","trace[1919172400] 'applied index is now lower than readState.Index' (duration: 40.073298ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:24.483758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.865209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:24.483806Z","caller":"traceutil/trace.go:171","msg":"trace[416690669] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"475.426241ms","start":"2026-05-05T06:50:24.008286Z","end":"2026-05-05T06:50:24.483713Z","steps":["trace[416690669] 'process raft request' (duration: 435.029455ms)","trace[416690669] 'compare' (duration: 39.735579ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:24.483843Z","caller":"traceutil/trace.go:171","msg":"trace[1116857394] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:581; }","duration":"468.006272ms","start":"2026-05-05T06:50:24.01582Z","end":"2026-05-05T06:50:24.483827Z","steps":["trace[1116857394] 'agreement among raft nodes before linearized reading' (duration: 467.815898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:24.483887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.015807Z","time spent":"468.070585ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-05T06:50:24.483927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.008254Z","time spent":"475.608565ms","remote":"127.0.0.1:47840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:50:24.483943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.419498ms","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-05T06:50:24.483988Z","caller":"traceutil/trace.go:171","msg":"trace[701063227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"419.47954ms","start":"2026-05-05T06:50:24.064493Z","end":"2026-05-05T06:50:24.483972Z","steps":["trace[701063227] 'agreement among raft nodes before linearized reading' (duration: 419.337525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:24.48402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.064486Z","time spent":"419.527081ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:50:24.484139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.777458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:50:24.484193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.852179ms","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-05T06:50:24.484204Z","caller":"traceutil/trace.go:171","msg":"trace[611671692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"361.044394ms","start":"2026-05-05T06:50:24.12314Z","end":"2026-05-05T06:50:24.484184Z","steps":["trace[611671692] 'agreement among raft nodes before linearized reading' (duration: 360.781008ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:24.484228Z","caller":"traceutil/trace.go:171","msg":"trace[294554354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"419.90824ms","start":"2026-05-05T06:50:24.064308Z","end":"2026-05-05T06:50:24.484216Z","steps":["trace[294554354] 'agreement among raft nodes before linearized reading' (duration: 419.808348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:24.484261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.064294Z","time spent":"419.961051ms","remote":"127.0.0.1:47916","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-05T06:50:24.484263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.123113Z","time spent":"361.137007ms","remote":"127.0.0.1:35962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:50:24.484134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.61204ms","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-05T06:50:24.484337Z","caller":"traceutil/trace.go:171","msg":"trace[2024526071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"446.818375ms","start":"2026-05-05T06:50:24.037499Z","end":"2026-05-05T06:50:24.484317Z","steps":["trace[2024526071] 'agreement among raft nodes before linearized reading' (duration: 446.26231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:24.484396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.037442Z","time spent":"446.938457ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:50:24.782066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.914961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:50:24.782332Z","caller":"traceutil/trace.go:171","msg":"trace[1375714384] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"292.00114ms","start":"2026-05-05T06:50:24.490318Z","end":"2026-05-05T06:50:24.78232Z","steps":["trace[1375714384] 'process raft request' (duration: 291.970379ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:24.782422Z","caller":"traceutil/trace.go:171","msg":"trace[2000605849] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"293.788465ms","start":"2026-05-05T06:50:24.48858Z","end":"2026-05-05T06:50:24.782368Z","steps":["trace[2000605849] 'process raft request' (duration: 152.511826ms)","trace[2000605849] 'compare' (duration: 139.909015ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:24.782475Z","caller":"traceutil/trace.go:171","msg":"trace[168715542] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"293.393986ms","start":"2026-05-05T06:50:24.489076Z","end":"2026-05-05T06:50:24.78247Z","steps":["trace[168715542] 'process raft request' (duration: 293.18049ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:24.782455Z","caller":"traceutil/trace.go:171","msg":"trace[442100029] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"293.451907ms","start":"2026-05-05T06:50:24.488981Z","end":"2026-05-05T06:50:24.782433Z","steps":["trace[442100029] 'process raft request' (duration: 293.17322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:25.623204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670627625,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:50:26.124164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670627625,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:50:26.567215Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.780680218s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T06:50:27.12314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000374571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-05T06:50:27.12322Z","caller":"traceutil/trace.go:171","msg":"trace[17537055] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000503545s","start":"2026-05-05T06:50:25.122701Z","end":"2026-05-05T06:50:27.123205Z","steps":["trace[17537055] 'agreement among raft nodes before linearized reading' (duration: 2.000369381s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.123265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:25.122684Z","time spent":"2.000572376s","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/05 06:50:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-05T06:50:27.563735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72522855s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:27.563925Z","caller":"traceutil/trace.go:171","msg":"trace[1427103839] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:585; }","duration":"1.725439885s","start":"2026-05-05T06:50:25.838462Z","end":"2026-05-05T06:50:27.563902Z","steps":["trace[1427103839] 'range keys from in-memory index tree' (duration: 1.725193959s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.564521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.962614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:50:27.564599Z","caller":"traceutil/trace.go:171","msg":"trace[889634496] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"2.778163575s","start":"2026-05-05T06:50:24.78642Z","end":"2026-05-05T06:50:27.564584Z","steps":["trace[889634496] 'process raft request' (duration: 1.781072608s)","trace[889634496] 'compare' (duration: 996.093732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:27.564676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.786388Z","time spent":"2.778248697s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T06:50:27.758432Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.189803243s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T06:50:27.761938Z","caller":"traceutil/trace.go:171","msg":"trace[1521131059] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"2.973864849s","start":"2026-05-05T06:50:24.788042Z","end":"2026-05-05T06:50:27.761907Z","steps":["trace[1521131059] 'process raft request' (duration: 2.973699734s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.763109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.788035Z","time spent":"2.974950057s","remote":"127.0.0.1:47840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:27.761967Z","caller":"traceutil/trace.go:171","msg":"trace[343887243] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"2.973188642s","start":"2026-05-05T06:50:24.788752Z","end":"2026-05-05T06:50:27.761941Z","steps":["trace[343887243] 'process raft request' (duration: 2.97307767s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.763573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.788737Z","time spent":"2.974773912s","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:27.762065Z","caller":"traceutil/trace.go:171","msg":"trace[842488115] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:602; }","duration":"2.639313687s","start":"2026-05-05T06:50:25.12274Z","end":"2026-05-05T06:50:27.762054Z","steps":["trace[842488115] 'read index received' (duration: 1.444759572s)","trace[842488115] 'applied index is now lower than readState.Index' (duration: 1.194552815s)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:27.762106Z","caller":"traceutil/trace.go:171","msg":"trace[253670457] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"2.39821847s","start":"2026-05-05T06:50:25.363878Z","end":"2026-05-05T06:50:27.762096Z","steps":["trace[253670457] 'process raft request' (duration: 2.398003075s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.76384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:25.36386Z","time spent":"2.399895182s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:27.763991Z","caller":"traceutil/trace.go:171","msg":"trace[1592660662] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"2.974600938s","start":"2026-05-05T06:50:24.787234Z","end":"2026-05-05T06:50:27.761835Z","steps":["trace[1592660662] 'process raft request' (duration: 2.971361185s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.764057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.599435503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-05T06:50:27.764186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.631573ms","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-05T06:50:27.764109Z","caller":"traceutil/trace.go:171","msg":"trace[1604930159] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:590; }","duration":"2.599515066s","start":"2026-05-05T06:50:25.164573Z","end":"2026-05-05T06:50:27.764088Z","steps":["trace[1604930159] 'agreement among raft nodes before linearized reading' (duration: 2.599324061s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:27.764218Z","caller":"traceutil/trace.go:171","msg":"trace[1840202266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"977.709564ms","start":"2026-05-05T06:50:26.786498Z","end":"2026-05-05T06:50:27.764208Z","steps":["trace[1840202266] 'agreement among raft nodes before linearized reading' (duration: 977.54466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.764236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:25.164554Z","time spent":"2.59967136s","remote":"127.0.0.1:35994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-05-05T06:50:27.76425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:24.787221Z","time spent":"2.976846514s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T06:50:27.764279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.901276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:27.764296Z","caller":"traceutil/trace.go:171","msg":"trace[1936154655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"640.947177ms","start":"2026-05-05T06:50:27.123343Z","end":"2026-05-05T06:50:27.764291Z","steps":["trace[1936154655] 'agreement among raft nodes before linearized reading' (duration: 640.918207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.764313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:27.123328Z","time spent":"640.981168ms","remote":"127.0.0.1:35962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:50:27.764466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.616223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T06:50:27.764467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.969112ms","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-05T06:50:27.764486Z","caller":"traceutil/trace.go:171","msg":"trace[1647628990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"977.668123ms","start":"2026-05-05T06:50:26.786811Z","end":"2026-05-05T06:50:27.764479Z","steps":["trace[1647628990] 'agreement among raft nodes before linearized reading' (duration: 977.603951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.764506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:26.78679Z","time spent":"977.709945ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T06:50:27.764505Z","caller":"traceutil/trace.go:171","msg":"trace[1285692230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"978.041243ms","start":"2026-05-05T06:50:26.786452Z","end":"2026-05-05T06:50:27.764493Z","steps":["trace[1285692230] 'agreement among raft nodes before linearized reading' (duration: 977.931371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.764244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:26.786476Z","time spent":"977.763546ms","remote":"127.0.0.1:47916","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-05T06:50:27.764544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:26.786431Z","time spent":"978.105786ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:50:27.764782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751761664s","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-05T06:50:27.764812Z","caller":"traceutil/trace.go:171","msg":"trace[1651772098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"1.751821235s","start":"2026-05-05T06:50:26.01298Z","end":"2026-05-05T06:50:27.764802Z","steps":["trace[1651772098] 'agreement among raft nodes before linearized reading' (duration: 1.751697562s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:27.764837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:26.012959Z","time spent":"1.751872387s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T06:50:28.190352Z","caller":"traceutil/trace.go:171","msg":"trace[832268417] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"188.529531ms","start":"2026-05-05T06:50:28.001799Z","end":"2026-05-05T06:50:28.190329Z","steps":["trace[832268417] 'process raft request' (duration: 188.398748ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:28.230413Z","caller":"traceutil/trace.go:171","msg":"trace[31292632] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"106.789783ms","start":"2026-05-05T06:50:28.123598Z","end":"2026-05-05T06:50:28.230388Z","steps":["trace[31292632] 'read index received' (duration: 66.665204ms)","trace[31292632] 'applied index is now lower than readState.Index' (duration: 40.121149ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:28.230486Z","caller":"traceutil/trace.go:171","msg":"trace[704549111] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"216.45715ms","start":"2026-05-05T06:50:28.013977Z","end":"2026-05-05T06:50:28.230435Z","steps":["trace[704549111] 'process raft request' (duration: 216.264905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:28.230579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.959258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:28.230635Z","caller":"traceutil/trace.go:171","msg":"trace[1412738822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"107.072741ms","start":"2026-05-05T06:50:28.123546Z","end":"2026-05-05T06:50:28.230618Z","steps":["trace[1412738822] 'agreement among raft nodes before linearized reading' (duration: 106.963278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:29.435827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.178519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:29.435916Z","caller":"traceutil/trace.go:171","msg":"trace[503558647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"313.312192ms","start":"2026-05-05T06:50:29.122576Z","end":"2026-05-05T06:50:29.435888Z","steps":["trace[503558647] 'range keys from in-memory index tree' (duration: 313.064296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:29.43598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.263759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g8t4n\" ","response":"range_response_count:1 size:17247"} {"level":"warn","ts":"2026-05-05T06:50:29.436003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:29.122552Z","time spent":"313.438675ms","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:50:29.436058Z","caller":"traceutil/trace.go:171","msg":"trace[814040103] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g8t4n; range_end:; response_count:1; response_revision:600; }","duration":"433.414983ms","start":"2026-05-05T06:50:29.002621Z","end":"2026-05-05T06:50:29.436036Z","steps":["trace[814040103] 'range keys from in-memory index tree' (duration: 433.018033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:29.436114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:29.002583Z","time spent":"433.521805ms","remote":"127.0.0.1:47840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17270,"request content":"key:\"/registry/pods/kube-system/cilium-g8t4n\" "} {"level":"info","ts":"2026-05-05T06:50:29.634895Z","caller":"traceutil/trace.go:171","msg":"trace[596151401] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"183.327529ms","start":"2026-05-05T06:50:29.451536Z","end":"2026-05-05T06:50:29.634864Z","steps":["trace[596151401] 'process raft request' (duration: 183.140704ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:29.683836Z","caller":"traceutil/trace.go:171","msg":"trace[2044897197] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"153.848009ms","start":"2026-05-05T06:50:29.529966Z","end":"2026-05-05T06:50:29.683814Z","steps":["trace[2044897197] 'process raft request' (duration: 122.949164ms)","trace[2044897197] 'compare' (duration: 30.756702ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:37.161462Z","caller":"traceutil/trace.go:171","msg":"trace[1819246487] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"159.922685ms","start":"2026-05-05T06:50:37.001512Z","end":"2026-05-05T06:50:37.161435Z","steps":["trace[1819246487] 'process raft request' (duration: 135.702007ms)","trace[1819246487] 'compare' (duration: 23.767707ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:38.510333Z","caller":"traceutil/trace.go:171","msg":"trace[1027793903] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"115.682578ms","start":"2026-05-05T06:50:38.39463Z","end":"2026-05-05T06:50:38.510312Z","steps":["trace[1027793903] 'process raft request' (duration: 115.554515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:41.278894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.361299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:41.279024Z","caller":"traceutil/trace.go:171","msg":"trace[1118991251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"155.543404ms","start":"2026-05-05T06:50:41.123457Z","end":"2026-05-05T06:50:41.279Z","steps":["trace[1118991251] 'range keys from in-memory index tree' (duration: 155.288598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:44.972163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647214965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:44.97223Z","caller":"traceutil/trace.go:171","msg":"trace[589805140] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:750; }","duration":"1.647336398s","start":"2026-05-05T06:50:43.324882Z","end":"2026-05-05T06:50:44.972218Z","steps":["trace[589805140] 'count revisions from in-memory index tree' (duration: 1.647105263s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:44.972254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:43.324861Z","time spent":"1.6473874s","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:50:44.972932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078516363s","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-05T06:50:44.97303Z","caller":"traceutil/trace.go:171","msg":"trace[118884247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"1.078647757s","start":"2026-05-05T06:50:43.894358Z","end":"2026-05-05T06:50:44.973006Z","steps":["trace[118884247] 'range keys from in-memory index tree' (duration: 1.07835168s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:44.973025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048721104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T06:50:44.973082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:43.894347Z","time spent":"1.078722819s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T06:50:44.973131Z","caller":"traceutil/trace.go:171","msg":"trace[96676145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"1.048865608s","start":"2026-05-05T06:50:43.92424Z","end":"2026-05-05T06:50:44.973106Z","steps":["trace[96676145] 'range keys from in-memory index tree' (duration: 1.048534249s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:44.973181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:43.92423Z","time spent":"1.04894046s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:50:44.973574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079438328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T06:50:44.973638Z","caller":"traceutil/trace.go:171","msg":"trace[1584655320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"1.07950049s","start":"2026-05-05T06:50:43.89412Z","end":"2026-05-05T06:50:44.973621Z","steps":["trace[1584655320] 'range keys from in-memory index tree' (duration: 1.079217902s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:44.973671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:43.894051Z","time spent":"1.079612983s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:50:44.973548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045992775s","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-05T06:50:44.973836Z","caller":"traceutil/trace.go:171","msg":"trace[1205194661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"1.046652882s","start":"2026-05-05T06:50:43.927159Z","end":"2026-05-05T06:50:44.973812Z","steps":["trace[1205194661] 'range keys from in-memory index tree' (duration: 1.045325777s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:44.973963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:43.927141Z","time spent":"1.046777995s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T06:50:44.979284Z","caller":"traceutil/trace.go:171","msg":"trace[1223335916] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"136.5271ms","start":"2026-05-05T06:50:44.842743Z","end":"2026-05-05T06:50:44.979271Z","steps":["trace[1223335916] 'read index received' (duration: 136.382256ms)","trace[1223335916] 'applied index is now lower than readState.Index' (duration: 144.134µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:44.979346Z","caller":"traceutil/trace.go:171","msg":"trace[938153988] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"873.008647ms","start":"2026-05-05T06:50:44.106331Z","end":"2026-05-05T06:50:44.97934Z","steps":["trace[938153988] 'process raft request' (duration: 872.838923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:44.979422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:44.106305Z","time spent":"873.058578ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:50:44.979499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.746785ms","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-05T06:50:44.979548Z","caller":"traceutil/trace.go:171","msg":"trace[429100609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"136.841637ms","start":"2026-05-05T06:50:44.842696Z","end":"2026-05-05T06:50:44.979538Z","steps":["trace[429100609] 'agreement among raft nodes before linearized reading' (duration: 136.693264ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:48.233876Z","caller":"traceutil/trace.go:171","msg":"trace[1473030947] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"106.75868ms","start":"2026-05-05T06:50:48.127095Z","end":"2026-05-05T06:50:48.233854Z","steps":["trace[1473030947] 'process raft request' (duration: 106.581706ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:48.47516Z","caller":"traceutil/trace.go:171","msg":"trace[1492882689] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"112.456427ms","start":"2026-05-05T06:50:48.362608Z","end":"2026-05-05T06:50:48.475064Z","steps":["trace[1492882689] 'process raft request' (duration: 106.438593ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:59.108504Z","caller":"traceutil/trace.go:171","msg":"trace[706858736] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"128.35166ms","start":"2026-05-05T06:50:58.98013Z","end":"2026-05-05T06:50:59.108482Z","steps":["trace[706858736] 'process raft request' (duration: 89.276614ms)","trace[706858736] 'compare' (duration: 38.752577ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:59.108717Z","caller":"traceutil/trace.go:171","msg":"trace[212158325] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"128.179176ms","start":"2026-05-05T06:50:58.980516Z","end":"2026-05-05T06:50:59.108696Z","steps":["trace[212158325] 'process raft request' (duration: 127.850308ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:59.29276Z","caller":"traceutil/trace.go:171","msg":"trace[466075457] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"178.075907ms","start":"2026-05-05T06:50:59.1146Z","end":"2026-05-05T06:50:59.292676Z","steps":["trace[466075457] 'process raft request' (duration: 162.155291ms)","trace[466075457] 'compare' (duration: 15.776472ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:59.292902Z","caller":"traceutil/trace.go:171","msg":"trace[1456328692] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"178.254542ms","start":"2026-05-05T06:50:59.114628Z","end":"2026-05-05T06:50:59.292883Z","steps":["trace[1456328692] 'process raft request' (duration: 178.012056ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:59.293181Z","caller":"traceutil/trace.go:171","msg":"trace[119871823] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"178.465677ms","start":"2026-05-05T06:50:59.114684Z","end":"2026-05-05T06:50:59.29315Z","steps":["trace[119871823] 'process raft request' (duration: 178.128318ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:59.293228Z","caller":"traceutil/trace.go:171","msg":"trace[5024071] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:917; }","duration":"170.117104ms","start":"2026-05-05T06:50:59.123093Z","end":"2026-05-05T06:50:59.29321Z","steps":["trace[5024071] 'read index received' (duration: 42.873542ms)","trace[5024071] 'applied index is now lower than readState.Index' (duration: 127.241362ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:59.293573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.432892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T06:50:59.293616Z","caller":"traceutil/trace.go:171","msg":"trace[766284843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"170.543785ms","start":"2026-05-05T06:50:59.123061Z","end":"2026-05-05T06:50:59.293605Z","steps":["trace[766284843] 'agreement among raft nodes before linearized reading' (duration: 170.33339ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:59.293731Z","caller":"traceutil/trace.go:171","msg":"trace[1620136658] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"175.778808ms","start":"2026-05-05T06:50:59.117943Z","end":"2026-05-05T06:50:59.293722Z","steps":["trace[1620136658] 'process raft request' (duration: 175.07983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:59.29393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.090957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-gshjw\" ","response":"range_response_count:1 size:5072"} {"level":"info","ts":"2026-05-05T06:50:59.294015Z","caller":"traceutil/trace.go:171","msg":"trace[531911905] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-gshjw; range_end:; response_count:1; response_revision:895; }","duration":"121.330812ms","start":"2026-05-05T06:50:59.172672Z","end":"2026-05-05T06:50:59.294002Z","steps":["trace[531911905] 'agreement among raft nodes before linearized reading' (duration: 121.007523ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:05.508992Z","caller":"traceutil/trace.go:171","msg":"trace[1991207553] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:943; }","duration":"120.985763ms","start":"2026-05-05T06:51:05.387966Z","end":"2026-05-05T06:51:05.508952Z","steps":["trace[1991207553] 'read index received' (duration: 29.422259ms)","trace[1991207553] 'applied index is now lower than readState.Index' (duration: 91.562064ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:51:05.509102Z","caller":"traceutil/trace.go:171","msg":"trace[451808499] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"179.691369ms","start":"2026-05-05T06:51:05.32939Z","end":"2026-05-05T06:51:05.509082Z","steps":["trace[451808499] 'process raft request' (duration: 179.500844ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:05.509114Z","caller":"traceutil/trace.go:171","msg":"trace[1434127169] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"180.1292ms","start":"2026-05-05T06:51:05.328936Z","end":"2026-05-05T06:51:05.509065Z","steps":["trace[1434127169] 'process raft request' (duration: 88.519215ms)","trace[1434127169] 'compare' (duration: 91.261456ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:05.509309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.309761ms","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-05T06:51:05.509347Z","caller":"traceutil/trace.go:171","msg":"trace[1614070171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"121.410283ms","start":"2026-05-05T06:51:05.387926Z","end":"2026-05-05T06:51:05.509336Z","steps":["trace[1614070171] 'agreement among raft nodes before linearized reading' (duration: 121.199438ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:19.147017Z","caller":"traceutil/trace.go:171","msg":"trace[556677919] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"112.190909ms","start":"2026-05-05T06:51:19.03481Z","end":"2026-05-05T06:51:19.147001Z","steps":["trace[556677919] 'process raft request' (duration: 111.922812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:19.871267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670628401,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:51:20.372043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670628401,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:51:20.872919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670628401,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:51:21.026715Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.679354664s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T06:51:21.027055Z","caller":"traceutil/trace.go:171","msg":"trace[818921708] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"1.679757135s","start":"2026-05-05T06:51:19.347277Z","end":"2026-05-05T06:51:21.027034Z","steps":["trace[818921708] 'process raft request' (duration: 1.67959728s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:21.027228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:19.347258Z","time spent":"1.679888389s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:51:21.042447Z","caller":"traceutil/trace.go:171","msg":"trace[110527149] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.353580293s","start":"2026-05-05T06:51:19.688847Z","end":"2026-05-05T06:51:21.042428Z","steps":["trace[110527149] 'process raft request' (duration: 1.353409019s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:21.042575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:19.688823Z","time spent":"1.353689146s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:51:21.752947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.548547ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T06:51:21.753475Z","caller":"traceutil/trace.go:171","msg":"trace[671786167] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:993; }","duration":"2.382609376s","start":"2026-05-05T06:51:19.370839Z","end":"2026-05-05T06:51:21.753448Z","steps":["trace[671786167] 'read index received' (duration: 1.656008249s)","trace[671786167] 'applied index is now lower than readState.Index' (duration: 726.599417ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:51:21.75353Z","caller":"traceutil/trace.go:171","msg":"trace[1895298738] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.304681827s","start":"2026-05-05T06:51:20.448832Z","end":"2026-05-05T06:51:21.753514Z","steps":["trace[1895298738] 'process raft request' (duration: 1.304203905s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:21.753622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:20.448811Z","time spent":"1.304760979s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:51:21.954421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.370782935s","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-05T06:51:21.954498Z","caller":"traceutil/trace.go:171","msg":"trace[777694461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"2.370939509s","start":"2026-05-05T06:51:19.583538Z","end":"2026-05-05T06:51:21.954478Z","steps":["trace[777694461] 'agreement among raft nodes before linearized reading' (duration: 2.17008836s)","trace[777694461] 'range keys from in-memory index tree' (duration: 200.667294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:21.954531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:19.583495Z","time spent":"2.371029111s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:51:21.954764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.468525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:51:21.954785Z","caller":"traceutil/trace.go:171","msg":"trace[1536160932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"834.522385ms","start":"2026-05-05T06:51:21.120254Z","end":"2026-05-05T06:51:21.954776Z","steps":["trace[1536160932] 'agreement among raft nodes before linearized reading' (duration: 633.356229ms)","trace[1536160932] 'range keys from in-memory index tree' (duration: 201.139486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:21.954805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:21.120235Z","time spent":"834.563437ms","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:51:21.954851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.372760075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T06:51:21.954911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562800474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:51:21.954922Z","caller":"traceutil/trace.go:171","msg":"trace[182890307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"2.372885467s","start":"2026-05-05T06:51:19.582016Z","end":"2026-05-05T06:51:21.954902Z","steps":["trace[182890307] 'agreement among raft nodes before linearized reading' (duration: 2.171623138s)","trace[182890307] 'range keys from in-memory index tree' (duration: 201.054343ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:51:21.954934Z","caller":"traceutil/trace.go:171","msg":"trace[221431447] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:966; }","duration":"1.562859716s","start":"2026-05-05T06:51:20.392067Z","end":"2026-05-05T06:51:21.954926Z","steps":["trace[221431447] 'agreement among raft nodes before linearized reading' (duration: 1.361552306s)","trace[221431447] 'count revisions from in-memory index tree' (duration: 201.275409ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:21.954924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.525877277s","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-05T06:51:21.954965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:19.581998Z","time spent":"2.37295878s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T06:51:21.955005Z","caller":"traceutil/trace.go:171","msg":"trace[1850680661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"2.52600625s","start":"2026-05-05T06:51:19.428978Z","end":"2026-05-05T06:51:21.954984Z","steps":["trace[1850680661] 'agreement among raft nodes before linearized reading' (duration: 2.324605418s)","trace[1850680661] 'range keys from in-memory index tree' (duration: 201.161717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:21.955052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:19.428959Z","time spent":"2.526084172s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T06:51:21.955129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.375072244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T06:51:21.955138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.584309136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T06:51:21.954961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:20.392047Z","time spent":"1.562909078s","remote":"127.0.0.1:47900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-05-05T06:51:21.95516Z","caller":"traceutil/trace.go:171","msg":"trace[1556103884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"2.375139525s","start":"2026-05-05T06:51:19.580011Z","end":"2026-05-05T06:51:21.95515Z","steps":["trace[1556103884] 'agreement among raft nodes before linearized reading' (duration: 2.173635511s)","trace[1556103884] 'range keys from in-memory index tree' (duration: 201.435553ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:21.955182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:19.579974Z","time spent":"2.375202837s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T06:51:21.95516Z","caller":"traceutil/trace.go:171","msg":"trace[1745904289] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:966; }","duration":"2.584374388s","start":"2026-05-05T06:51:19.370779Z","end":"2026-05-05T06:51:21.955153Z","steps":["trace[1745904289] 'agreement among raft nodes before linearized reading' (duration: 2.3827463s)","trace[1745904289] 'count revisions from in-memory index tree' (duration: 201.586517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:21.955218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:19.370756Z","time spent":"2.58445548s","remote":"127.0.0.1:48134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:51:43.632343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670628587,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T06:51:43.818142Z","caller":"traceutil/trace.go:171","msg":"trace[1601153841] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"688.929794ms","start":"2026-05-05T06:51:43.129189Z","end":"2026-05-05T06:51:43.818118Z","steps":["trace[1601153841] 'read index received' (duration: 688.706778ms)","trace[1601153841] 'applied index is now lower than readState.Index' (duration: 221.926µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:43.818431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.805604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:51:43.818484Z","caller":"traceutil/trace.go:171","msg":"trace[493577272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"687.899067ms","start":"2026-05-05T06:51:43.130575Z","end":"2026-05-05T06:51:43.818474Z","steps":["trace[493577272] 'agreement among raft nodes before linearized reading' (duration: 687.808684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:43.818527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:43.130558Z","time spent":"687.960838ms","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:51:43.818424Z","caller":"traceutil/trace.go:171","msg":"trace[878728075] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"702.875303ms","start":"2026-05-05T06:51:43.115397Z","end":"2026-05-05T06:51:43.818272Z","steps":["trace[878728075] 'process raft request' (duration: 702.542984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:43.818695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.965838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:51:43.818784Z","caller":"traceutil/trace.go:171","msg":"trace[1701951023] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1037; }","duration":"643.117363ms","start":"2026-05-05T06:51:43.175651Z","end":"2026-05-05T06:51:43.818768Z","steps":["trace[1701951023] 'agreement among raft nodes before linearized reading' (duration: 642.944589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:43.818773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:43.115377Z","time spent":"703.273443ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:51:43.818837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:43.175605Z","time spent":"643.213935ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:51:43.819521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.335671ms","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-05T06:51:43.819572Z","caller":"traceutil/trace.go:171","msg":"trace[1852450139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1037; }","duration":"690.459273ms","start":"2026-05-05T06:51:43.1291Z","end":"2026-05-05T06:51:43.819559Z","steps":["trace[1852450139] 'agreement among raft nodes before linearized reading' (duration: 689.218311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:43.819605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:43.129052Z","time spent":"690.545546ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:51:44.585087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.982146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:51:44.585187Z","caller":"traceutil/trace.go:171","msg":"trace[1962943376] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"480.956478ms","start":"2026-05-05T06:51:44.104216Z","end":"2026-05-05T06:51:44.585173Z","steps":["trace[1962943376] 'read index received' (duration: 41.832µs)","trace[1962943376] 'applied index is now lower than readState.Index' (duration: 480.913636ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:51:44.585401Z","caller":"traceutil/trace.go:171","msg":"trace[60829626] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"762.16184ms","start":"2026-05-05T06:51:43.823228Z","end":"2026-05-05T06:51:44.58539Z","steps":["trace[60829626] 'process raft request' (duration: 117.779553ms)","trace[60829626] 'compare' (duration: 643.894704ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:44.585457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:43.823213Z","time spent":"762.219061ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:51:44.585647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.43929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T06:51:44.585674Z","caller":"traceutil/trace.go:171","msg":"trace[173071719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1038; }","duration":"481.493822ms","start":"2026-05-05T06:51:44.104174Z","end":"2026-05-05T06:51:44.585668Z","steps":["trace[173071719] 'agreement among raft nodes before linearized reading' (duration: 481.402369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:44.585694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.104155Z","time spent":"481.533772ms","remote":"127.0.0.1:47916","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-05T06:51:44.585866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.304633ms","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-05T06:51:44.585891Z","caller":"traceutil/trace.go:171","msg":"trace[2066812838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1038; }","duration":"477.350794ms","start":"2026-05-05T06:51:44.108535Z","end":"2026-05-05T06:51:44.585886Z","steps":["trace[2066812838] 'agreement among raft nodes before linearized reading' (duration: 477.293763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:44.585908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.108522Z","time spent":"477.381985ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:51:44.586072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.731396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:51:44.586097Z","caller":"traceutil/trace.go:171","msg":"trace[355951074] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1038; }","duration":"446.793518ms","start":"2026-05-05T06:51:44.139298Z","end":"2026-05-05T06:51:44.586091Z","steps":["trace[355951074] 'agreement among raft nodes before linearized reading' (duration: 446.742246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:44.586117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.139275Z","time spent":"446.834468ms","remote":"127.0.0.1:48072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:51:44.586238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.615321ms","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-05T06:51:44.586502Z","caller":"traceutil/trace.go:171","msg":"trace[1874042337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"477.897268ms","start":"2026-05-05T06:51:44.108592Z","end":"2026-05-05T06:51:44.586489Z","steps":["trace[1874042337] 'agreement among raft nodes before linearized reading' (duration: 477.505688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:44.586541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.108585Z","time spent":"477.946631ms","remote":"127.0.0.1:47916","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-05T06:51:44.586953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.922847ms","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-05T06:51:44.586984Z","caller":"traceutil/trace.go:171","msg":"trace[1088052344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1038; }","duration":"474.03927ms","start":"2026-05-05T06:51:44.112938Z","end":"2026-05-05T06:51:44.586978Z","steps":["trace[1088052344] 'agreement among raft nodes before linearized reading' (duration: 473.966118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:44.58701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.112929Z","time spent":"474.075021ms","remote":"127.0.0.1:47916","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-05T06:51:44.587297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.958672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:51:44.587596Z","caller":"traceutil/trace.go:171","msg":"trace[1444457088] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1038; }","duration":"452.351582ms","start":"2026-05-05T06:51:44.135132Z","end":"2026-05-05T06:51:44.587484Z","steps":["trace[1444457088] 'agreement among raft nodes before linearized reading' (duration: 451.707456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:44.587833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.135092Z","time spent":"452.719511ms","remote":"127.0.0.1:47838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-05-05T06:51:44.894727Z","caller":"traceutil/trace.go:171","msg":"trace[609665652] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"301.403702ms","start":"2026-05-05T06:51:44.593298Z","end":"2026-05-05T06:51:44.894701Z","steps":["trace[609665652] 'process raft request' (duration: 301.201626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:44.894878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.593268Z","time spent":"301.532616ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:51:44.902072Z","caller":"traceutil/trace.go:171","msg":"trace[1913932012] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"308.384762ms","start":"2026-05-05T06:51:44.59367Z","end":"2026-05-05T06:51:44.902055Z","steps":["trace[1913932012] 'process raft request' (duration: 308.272429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:44.902298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.593662Z","time spent":"308.468364ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:51:44.902534Z","caller":"traceutil/trace.go:171","msg":"trace[102695053] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"308.722001ms","start":"2026-05-05T06:51:44.593786Z","end":"2026-05-05T06:51:44.902508Z","steps":["trace[102695053] 'process raft request' (duration: 308.242878ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:44.902604Z","caller":"traceutil/trace.go:171","msg":"trace[2078782440] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"308.72348ms","start":"2026-05-05T06:51:44.593855Z","end":"2026-05-05T06:51:44.902578Z","steps":["trace[2078782440] 'process raft request' (duration: 308.496355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:44.902965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.593823Z","time spent":"309.060659ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:51:44.902717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:44.593757Z","time spent":"308.849344ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:51:46.785655Z","caller":"traceutil/trace.go:171","msg":"trace[682048576] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"186.943374ms","start":"2026-05-05T06:51:46.598678Z","end":"2026-05-05T06:51:46.785621Z","steps":["trace[682048576] 'process raft request' (duration: 186.732618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:48.279815Z","caller":"traceutil/trace.go:171","msg":"trace[1269594055] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"419.556107ms","start":"2026-05-05T06:51:47.860236Z","end":"2026-05-05T06:51:48.279792Z","steps":["trace[1269594055] 'process raft request' (duration: 419.433413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:48.280078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:47.860211Z","time spent":"419.73502ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:51:48.605946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.596757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:51:48.606044Z","caller":"traceutil/trace.go:171","msg":"trace[1665150734] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1050; }","duration":"134.789952ms","start":"2026-05-05T06:51:48.471236Z","end":"2026-05-05T06:51:48.606026Z","steps":["trace[1665150734] 'count revisions from in-memory index tree' (duration: 134.475364ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:49.079987Z","caller":"traceutil/trace.go:171","msg":"trace[513728426] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"286.715485ms","start":"2026-05-05T06:51:48.793253Z","end":"2026-05-05T06:51:49.079968Z","steps":["trace[513728426] 'process raft request' (duration: 286.570771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:49.080094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.819178ms","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-05T06:51:49.080122Z","caller":"traceutil/trace.go:171","msg":"trace[1913231216] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1051; }","duration":"194.88477ms","start":"2026-05-05T06:51:48.885229Z","end":"2026-05-05T06:51:49.080114Z","steps":["trace[1913231216] 'agreement among raft nodes before linearized reading' (duration: 194.828209ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:49.08012Z","caller":"traceutil/trace.go:171","msg":"trace[827802594] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"194.673085ms","start":"2026-05-05T06:51:48.885267Z","end":"2026-05-05T06:51:49.07994Z","steps":["trace[827802594] 'read index received' (duration: 194.444038ms)","trace[827802594] 'applied index is now lower than readState.Index' (duration: 228.297µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:49.080231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.179016ms","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-05T06:51:49.080249Z","caller":"traceutil/trace.go:171","msg":"trace[2140535355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1051; }","duration":"148.225507ms","start":"2026-05-05T06:51:48.932018Z","end":"2026-05-05T06:51:49.080243Z","steps":["trace[2140535355] 'agreement among raft nodes before linearized reading' (duration: 148.147765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:49.080315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.534318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T06:51:49.080329Z","caller":"traceutil/trace.go:171","msg":"trace[464482619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"131.573589ms","start":"2026-05-05T06:51:48.948751Z","end":"2026-05-05T06:51:49.080325Z","steps":["trace[464482619] 'agreement among raft nodes before linearized reading' (duration: 131.530157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:49.080352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.851569ms","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-05T06:51:49.080371Z","caller":"traceutil/trace.go:171","msg":"trace[1149942951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1051; }","duration":"135.895501ms","start":"2026-05-05T06:51:48.94447Z","end":"2026-05-05T06:51:49.080365Z","steps":["trace[1149942951] 'agreement among raft nodes before linearized reading' (duration: 135.823829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:49.080392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.448094ms","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-05T06:51:49.080403Z","caller":"traceutil/trace.go:171","msg":"trace[912160049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1051; }","duration":"136.490125ms","start":"2026-05-05T06:51:48.943909Z","end":"2026-05-05T06:51:49.0804Z","steps":["trace[912160049] 'agreement among raft nodes before linearized reading' (duration: 136.458125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:03.409721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670628842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:52:03.910068Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670628842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:52:04.034831Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.668897943s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T06:52:04.035594Z","caller":"traceutil/trace.go:171","msg":"trace[609847650] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"1.669681523s","start":"2026-05-05T06:52:02.365828Z","end":"2026-05-05T06:52:04.03551Z","steps":["trace[609847650] 'process raft request' (duration: 1.66954725s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.035783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:02.365812Z","time spent":"1.669866807s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:04.086425Z","caller":"traceutil/trace.go:171","msg":"trace[1048870689] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1187; }","duration":"1.17995488s","start":"2026-05-05T06:52:02.906449Z","end":"2026-05-05T06:52:04.086404Z","steps":["trace[1048870689] 'read index received' (duration: 1.128851454s)","trace[1048870689] 'applied index is now lower than readState.Index' (duration: 51.102436ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:04.086535Z","caller":"traceutil/trace.go:171","msg":"trace[2129720611] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"1.131517313s","start":"2026-05-05T06:52:02.955006Z","end":"2026-05-05T06:52:04.086523Z","steps":["trace[2129720611] 'process raft request' (duration: 1.131275887s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.086644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:02.954989Z","time spent":"1.131588154s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:04.086643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041530954s","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-05T06:52:04.086711Z","caller":"traceutil/trace.go:171","msg":"trace[359529784] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1148; }","duration":"1.041610106s","start":"2026-05-05T06:52:03.045086Z","end":"2026-05-05T06:52:04.086696Z","steps":["trace[359529784] 'agreement among raft nodes before linearized reading' (duration: 1.041488403s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.086752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:03.044962Z","time spent":"1.04178058s","remote":"127.0.0.1:32992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:52:04.086891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.043978ms","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-05T06:52:04.086968Z","caller":"traceutil/trace.go:171","msg":"trace[1846092456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1148; }","duration":"818.155133ms","start":"2026-05-05T06:52:03.268794Z","end":"2026-05-05T06:52:04.086949Z","steps":["trace[1846092456] 'agreement among raft nodes before linearized reading' (duration: 817.974657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.087012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:03.268779Z","time spent":"818.224114ms","remote":"127.0.0.1:47916","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-05T06:52:04.08706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.464435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T06:52:04.08707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.621732ms","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-05T06:52:04.087103Z","caller":"traceutil/trace.go:171","msg":"trace[525020037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1148; }","duration":"857.504716ms","start":"2026-05-05T06:52:03.229587Z","end":"2026-05-05T06:52:04.087092Z","steps":["trace[525020037] 'agreement among raft nodes before linearized reading' (duration: 857.395743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.087131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:03.229581Z","time spent":"857.543907ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:52:04.08718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18073942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:52:04.087215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.585061ms","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-05T06:52:04.087251Z","caller":"traceutil/trace.go:171","msg":"trace[712188379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1148; }","duration":"861.621052ms","start":"2026-05-05T06:52:03.225622Z","end":"2026-05-05T06:52:04.087243Z","steps":["trace[712188379] 'agreement among raft nodes before linearized reading' (duration: 861.52947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.087288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:03.225604Z","time spent":"861.676973ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:52:04.087432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.303959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:52:04.087462Z","caller":"traceutil/trace.go:171","msg":"trace[1919740318] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1148; }","duration":"662.356319ms","start":"2026-05-05T06:52:03.425099Z","end":"2026-05-05T06:52:04.087455Z","steps":["trace[1919740318] 'agreement among raft nodes before linearized reading' (duration: 662.308168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.087502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:03.425085Z","time spent":"662.410821ms","remote":"127.0.0.1:48080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:52:04.087565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.974453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T06:52:04.087607Z","caller":"traceutil/trace.go:171","msg":"trace[1088970043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1148; }","duration":"826.057285ms","start":"2026-05-05T06:52:03.26154Z","end":"2026-05-05T06:52:04.087597Z","steps":["trace[1088970043] 'agreement among raft nodes before linearized reading' (duration: 825.713926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.087666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:03.261522Z","time spent":"826.136548ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T06:52:04.087215Z","caller":"traceutil/trace.go:171","msg":"trace[996306267] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1148; }","duration":"1.180818353s","start":"2026-05-05T06:52:02.906385Z","end":"2026-05-05T06:52:04.087204Z","steps":["trace[996306267] 'agreement among raft nodes before linearized reading' (duration: 1.180767462s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:04.08713Z","caller":"traceutil/trace.go:171","msg":"trace[333102358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1148; }","duration":"823.706495ms","start":"2026-05-05T06:52:03.263412Z","end":"2026-05-05T06:52:04.087119Z","steps":["trace[333102358] 'agreement among raft nodes before linearized reading' (duration: 823.519019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.087851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:02.906361Z","time spent":"1.181480699s","remote":"127.0.0.1:32998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:52:04.087866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:03.263369Z","time spent":"824.481464ms","remote":"127.0.0.1:47916","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-05T06:52:04.240327Z","caller":"traceutil/trace.go:171","msg":"trace[1056402475] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"147.799477ms","start":"2026-05-05T06:52:04.092354Z","end":"2026-05-05T06:52:04.240154Z","steps":["trace[1056402475] 'process raft request' (duration: 145.545508ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:04.242113Z","caller":"traceutil/trace.go:171","msg":"trace[2051470424] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1189; }","duration":"119.625121ms","start":"2026-05-05T06:52:04.122478Z","end":"2026-05-05T06:52:04.242103Z","steps":["trace[2051470424] 'read index received' (duration: 115.373231ms)","trace[2051470424] 'applied index is now lower than readState.Index' (duration: 4.25135ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:04.242117Z","caller":"traceutil/trace.go:171","msg":"trace[844298062] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"149.739957ms","start":"2026-05-05T06:52:04.092353Z","end":"2026-05-05T06:52:04.242093Z","steps":["trace[844298062] 'process raft request' (duration: 149.517061ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:04.242152Z","caller":"traceutil/trace.go:171","msg":"trace[327296333] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"149.731907ms","start":"2026-05-05T06:52:04.092391Z","end":"2026-05-05T06:52:04.242123Z","steps":["trace[327296333] 'process raft request' (duration: 149.570972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:04.242216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.724563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:04.242258Z","caller":"traceutil/trace.go:171","msg":"trace[1935421890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1153; }","duration":"119.808706ms","start":"2026-05-05T06:52:04.122442Z","end":"2026-05-05T06:52:04.242251Z","steps":["trace[1935421890] 'agreement among raft nodes before linearized reading' (duration: 119.715603ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:04.242288Z","caller":"traceutil/trace.go:171","msg":"trace[129652437] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"149.633923ms","start":"2026-05-05T06:52:04.092643Z","end":"2026-05-05T06:52:04.242277Z","steps":["trace[129652437] 'process raft request' (duration: 149.395527ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:04.242297Z","caller":"traceutil/trace.go:171","msg":"trace[1593423813] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"149.829919ms","start":"2026-05-05T06:52:04.092444Z","end":"2026-05-05T06:52:04.242274Z","steps":["trace[1593423813] 'process raft request' (duration: 149.560953ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:16.314552Z","caller":"traceutil/trace.go:171","msg":"trace[123920419] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"190.297702ms","start":"2026-05-05T06:52:16.124232Z","end":"2026-05-05T06:52:16.31453Z","steps":["trace[123920419] 'read index received' (duration: 190.119467ms)","trace[123920419] 'applied index is now lower than readState.Index' (duration: 177.345µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:16.314609Z","caller":"traceutil/trace.go:171","msg":"trace[1020283233] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"240.967196ms","start":"2026-05-05T06:52:16.073614Z","end":"2026-05-05T06:52:16.314581Z","steps":["trace[1020283233] 'process raft request' (duration: 240.786171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:16.314672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.425345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:16.314702Z","caller":"traceutil/trace.go:171","msg":"trace[39893970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"190.471556ms","start":"2026-05-05T06:52:16.124222Z","end":"2026-05-05T06:52:16.314693Z","steps":["trace[39893970] 'agreement among raft nodes before linearized reading' (duration: 190.408554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:16.61652Z","caller":"traceutil/trace.go:171","msg":"trace[969935282] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"277.287992ms","start":"2026-05-05T06:52:16.33921Z","end":"2026-05-05T06:52:16.616498Z","steps":["trace[969935282] 'read index received' (duration: 274.230923ms)","trace[969935282] 'applied index is now lower than readState.Index' (duration: 3.056189ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:16.616585Z","caller":"traceutil/trace.go:171","msg":"trace[691433681] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"294.204437ms","start":"2026-05-05T06:52:16.322361Z","end":"2026-05-05T06:52:16.616565Z","steps":["trace[691433681] 'process raft request' (duration: 291.130788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:16.616784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.556648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T06:52:16.616823Z","caller":"traceutil/trace.go:171","msg":"trace[1121399289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"277.635741ms","start":"2026-05-05T06:52:16.339176Z","end":"2026-05-05T06:52:16.616811Z","steps":["trace[1121399289] 'agreement among raft nodes before linearized reading' (duration: 277.451266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:16.617194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.644034ms","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-05T06:52:16.617232Z","caller":"traceutil/trace.go:171","msg":"trace[572032493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1189; }","duration":"276.692685ms","start":"2026-05-05T06:52:16.340524Z","end":"2026-05-05T06:52:16.617217Z","steps":["trace[572032493] 'agreement among raft nodes before linearized reading' (duration: 276.625603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:16.617384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.637129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T06:52:16.617408Z","caller":"traceutil/trace.go:171","msg":"trace[1999149859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"275.67557ms","start":"2026-05-05T06:52:16.341727Z","end":"2026-05-05T06:52:16.617402Z","steps":["trace[1999149859] 'agreement among raft nodes before linearized reading' (duration: 275.594928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:16.617529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.351948ms","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-05T06:52:16.61757Z","caller":"traceutil/trace.go:171","msg":"trace[94547418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1189; }","duration":"276.402399ms","start":"2026-05-05T06:52:16.341157Z","end":"2026-05-05T06:52:16.61756Z","steps":["trace[94547418] 'agreement among raft nodes before linearized reading' (duration: 276.334376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:16.617278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.62331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:16.617693Z","caller":"traceutil/trace.go:171","msg":"trace[571460104] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1189; }","duration":"252.099902ms","start":"2026-05-05T06:52:16.36557Z","end":"2026-05-05T06:52:16.61767Z","steps":["trace[571460104] 'agreement among raft nodes before linearized reading' (duration: 251.591499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:20.710739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.604358ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T06:52:20.711068Z","caller":"traceutil/trace.go:171","msg":"trace[260335448] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"382.122712ms","start":"2026-05-05T06:52:20.328924Z","end":"2026-05-05T06:52:20.711047Z","steps":["trace[260335448] 'process raft request' (duration: 381.913327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:20.711244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:20.328906Z","time spent":"382.236455ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:21.689587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.806016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:52:21.68966Z","caller":"traceutil/trace.go:171","msg":"trace[694683833] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1251; }","duration":"1.056568132s","start":"2026-05-05T06:52:20.63308Z","end":"2026-05-05T06:52:21.689649Z","steps":["trace[694683833] 'read index received' (duration: 60.167229ms)","trace[694683833] 'applied index is now lower than readState.Index' (duration: 996.400143ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:21.68977Z","caller":"traceutil/trace.go:171","msg":"trace[803815846] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"1.311735544s","start":"2026-05-05T06:52:20.378026Z","end":"2026-05-05T06:52:21.689762Z","steps":["trace[803815846] 'process raft request' (duration: 374.692981ms)","trace[803815846] 'compare' (duration: 936.56059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:21.689808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:20.378007Z","time spent":"1.311780424s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:21.689968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05689664s","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-05T06:52:21.690052Z","caller":"traceutil/trace.go:171","msg":"trace[1396278245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"1.057019813s","start":"2026-05-05T06:52:20.633026Z","end":"2026-05-05T06:52:21.690046Z","steps":["trace[1396278245] 'agreement among raft nodes before linearized reading' (duration: 1.056880579s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:21.690072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:20.633004Z","time spent":"1.057063154s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T06:52:22.190686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670628989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:52:22.434154Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.448062245s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T06:52:22.536846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.391336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:52:22.536952Z","caller":"traceutil/trace.go:171","msg":"trace[1169180629] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"1.550951075s","start":"2026-05-05T06:52:20.985982Z","end":"2026-05-05T06:52:22.536933Z","steps":["trace[1169180629] 'process raft request' (duration: 1.448369473s)","trace[1169180629] 'compare' (duration: 102.195741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:22.53701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:20.985963Z","time spent":"1.551019377s","remote":"127.0.0.1:47838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5500,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:22.8078Z","caller":"traceutil/trace.go:171","msg":"trace[227833412] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1254; }","duration":"1.118085887s","start":"2026-05-05T06:52:21.689677Z","end":"2026-05-05T06:52:22.807763Z","steps":["trace[227833412] 'read index received' (duration: 744.68481ms)","trace[227833412] 'applied index is now lower than readState.Index' (duration: 373.399477ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:22.807831Z","caller":"traceutil/trace.go:171","msg":"trace[802193307] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"1.114674819s","start":"2026-05-05T06:52:21.693105Z","end":"2026-05-05T06:52:22.80778Z","steps":["trace[802193307] 'process raft request' (duration: 1.047817727s)","trace[802193307] 'compare' (duration: 66.565805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:22.807932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.685277964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:22.807991Z","caller":"traceutil/trace.go:171","msg":"trace[730247087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"1.685390587s","start":"2026-05-05T06:52:21.122584Z","end":"2026-05-05T06:52:22.807975Z","steps":["trace[730247087] 'agreement among raft nodes before linearized reading' (duration: 1.685262544s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:22.808005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:21.693092Z","time spent":"1.114833023s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:22.808042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:21.122567Z","time spent":"1.685463919s","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:52:22.808217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.165019829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T06:52:22.808345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920559583s","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-05T06:52:22.808408Z","caller":"traceutil/trace.go:171","msg":"trace[154617717] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1211; }","duration":"1.920640156s","start":"2026-05-05T06:52:20.887747Z","end":"2026-05-05T06:52:22.808387Z","steps":["trace[154617717] 'agreement among raft nodes before linearized reading' (duration: 1.920562234s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:22.808265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.165153073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T06:52:22.808478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:20.887731Z","time spent":"1.920730578s","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:52:22.808387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.165108701s","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-05T06:52:22.80843Z","caller":"traceutil/trace.go:171","msg":"trace[577892018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"2.165233645s","start":"2026-05-05T06:52:20.643138Z","end":"2026-05-05T06:52:22.808371Z","steps":["trace[577892018] 'agreement among raft nodes before linearized reading' (duration: 2.164957908s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:22.808557Z","caller":"traceutil/trace.go:171","msg":"trace[788780453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"2.165443051s","start":"2026-05-05T06:52:20.643076Z","end":"2026-05-05T06:52:22.808519Z","steps":["trace[788780453] 'agreement among raft nodes before linearized reading' (duration: 2.165076142s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:22.808563Z","caller":"traceutil/trace.go:171","msg":"trace[1600207352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"2.165354838s","start":"2026-05-05T06:52:20.643194Z","end":"2026-05-05T06:52:22.808549Z","steps":["trace[1600207352] 'agreement among raft nodes before linearized reading' (duration: 2.164736042s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:22.808657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:20.643066Z","time spent":"2.165555495s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:52:22.808658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:20.643111Z","time spent":"2.165531023s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:52:22.8087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:20.643168Z","time spent":"2.165473652s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:52:22.809173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.160531405s","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-05T06:52:22.809241Z","caller":"traceutil/trace.go:171","msg":"trace[1611196843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"2.160629608s","start":"2026-05-05T06:52:20.648595Z","end":"2026-05-05T06:52:22.809224Z","steps":["trace[1611196843] 'agreement among raft nodes before linearized reading' (duration: 2.160469214s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:22.812309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:20.64858Z","time spent":"2.163719927s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T06:52:23.318617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.24326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:52:23.318717Z","caller":"traceutil/trace.go:171","msg":"trace[625309404] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"510.810086ms","start":"2026-05-05T06:52:22.807892Z","end":"2026-05-05T06:52:23.318702Z","steps":["trace[625309404] 'read index received' (duration: 200.306529ms)","trace[625309404] 'applied index is now lower than readState.Index' (duration: 310.502287ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:23.318852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.440297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:52:23.31892Z","caller":"traceutil/trace.go:171","msg":"trace[1809531119] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1212; }","duration":"743.54502ms","start":"2026-05-05T06:52:22.575359Z","end":"2026-05-05T06:52:23.318904Z","steps":["trace[1809531119] 'agreement among raft nodes before linearized reading' (duration: 743.413667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:23.318964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.040297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:23.318996Z","caller":"traceutil/trace.go:171","msg":"trace[1326431375] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1212; }","duration":"922.070567ms","start":"2026-05-05T06:52:22.396913Z","end":"2026-05-05T06:52:23.318983Z","steps":["trace[1326431375] 'agreement among raft nodes before linearized reading' (duration: 922.015806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:23.318959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:22.575344Z","time spent":"743.605732ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:52:23.319021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:22.396852Z","time spent":"922.16188ms","remote":"127.0.0.1:41152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-05T06:52:23.319191Z","caller":"traceutil/trace.go:171","msg":"trace[2131332621] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"601.672596ms","start":"2026-05-05T06:52:22.717497Z","end":"2026-05-05T06:52:23.31917Z","steps":["trace[2131332621] 'process raft request' (duration: 290.80215ms)","trace[2131332621] 'compare' (duration: 310.137528ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:23.319288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:22.717458Z","time spent":"601.766749ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:23.320174Z","caller":"traceutil/trace.go:171","msg":"trace[719327039] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"506.655769ms","start":"2026-05-05T06:52:22.813496Z","end":"2026-05-05T06:52:23.320151Z","steps":["trace[719327039] 'process raft request' (duration: 506.494725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:23.320304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:22.813483Z","time spent":"506.752111ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:23.320491Z","caller":"traceutil/trace.go:171","msg":"trace[958753551] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"506.890794ms","start":"2026-05-05T06:52:22.813581Z","end":"2026-05-05T06:52:23.320471Z","steps":["trace[958753551] 'process raft request' (duration: 506.533465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:23.32059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:22.813576Z","time spent":"506.963637ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:23.321122Z","caller":"traceutil/trace.go:171","msg":"trace[1303046206] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"506.640587ms","start":"2026-05-05T06:52:22.814464Z","end":"2026-05-05T06:52:23.321105Z","steps":["trace[1303046206] 'process raft request' (duration: 505.933109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:23.321213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:22.814453Z","time spent":"506.708709ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:23.321273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.985529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:23.321307Z","caller":"traceutil/trace.go:171","msg":"trace[282335586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"509.03661ms","start":"2026-05-05T06:52:22.812261Z","end":"2026-05-05T06:52:23.321298Z","steps":["trace[282335586] 'agreement among raft nodes before linearized reading' (duration: 508.961628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:23.321331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:22.812246Z","time spent":"509.079551ms","remote":"127.0.0.1:35962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:52:23.321549Z","caller":"traceutil/trace.go:171","msg":"trace[1339292499] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"505.603152ms","start":"2026-05-05T06:52:22.815935Z","end":"2026-05-05T06:52:23.321538Z","steps":["trace[1339292499] 'process raft request' (duration: 505.032957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:23.321617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:22.815928Z","time spent":"505.645223ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:24.452347Z","caller":"traceutil/trace.go:171","msg":"trace[1218482571] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"252.600635ms","start":"2026-05-05T06:52:24.19973Z","end":"2026-05-05T06:52:24.45233Z","steps":["trace[1218482571] 'process raft request' (duration: 252.486942ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:24.591559Z","caller":"traceutil/trace.go:171","msg":"trace[985184202] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"220.765946ms","start":"2026-05-05T06:52:24.370768Z","end":"2026-05-05T06:52:24.591534Z","steps":["trace[985184202] 'read index received' (duration: 81.55531ms)","trace[985184202] 'applied index is now lower than readState.Index' (duration: 139.209536ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:24.591714Z","caller":"traceutil/trace.go:171","msg":"trace[166454668] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"337.702658ms","start":"2026-05-05T06:52:24.253973Z","end":"2026-05-05T06:52:24.591675Z","steps":["trace[166454668] 'process raft request' (duration: 331.107249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:24.591784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.991451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:52:24.591822Z","caller":"traceutil/trace.go:171","msg":"trace[760055131] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1218; }","duration":"221.083034ms","start":"2026-05-05T06:52:24.370729Z","end":"2026-05-05T06:52:24.591812Z","steps":["trace[760055131] 'agreement among raft nodes before linearized reading' (duration: 220.94492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:24.591877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:24.253933Z","time spent":"337.861762ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:24.937746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.613554ms","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-05T06:52:24.937805Z","caller":"traceutil/trace.go:171","msg":"trace[650827942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"123.732398ms","start":"2026-05-05T06:52:24.814059Z","end":"2026-05-05T06:52:24.937791Z","steps":["trace[650827942] 'range keys from in-memory index tree' (duration: 123.392609ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:25.075931Z","caller":"traceutil/trace.go:171","msg":"trace[1993804170] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"134.883943ms","start":"2026-05-05T06:52:24.941002Z","end":"2026-05-05T06:52:25.075886Z","steps":["trace[1993804170] 'process raft request' (duration: 134.725269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:25.825834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670629009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:52:25.997543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.4064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T06:52:25.997619Z","caller":"traceutil/trace.go:171","msg":"trace[1601150201] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1219; }","duration":"827.513443ms","start":"2026-05-05T06:52:25.170082Z","end":"2026-05-05T06:52:25.997596Z","steps":["trace[1601150201] 'range keys from in-memory index tree' (duration: 827.257196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:25.997654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:25.170066Z","time spent":"827.579314ms","remote":"127.0.0.1:35994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-05-05T06:52:25.99817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.953194ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:25.998327Z","caller":"traceutil/trace.go:171","msg":"trace[1313695030] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1219; }","duration":"159.143809ms","start":"2026-05-05T06:52:25.839159Z","end":"2026-05-05T06:52:25.998303Z","steps":["trace[1313695030] 'range keys from in-memory index tree' (duration: 158.925394ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:26.246126Z","caller":"traceutil/trace.go:171","msg":"trace[1032274849] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"921.050652ms","start":"2026-05-05T06:52:25.325055Z","end":"2026-05-05T06:52:26.246106Z","steps":["trace[1032274849] 'read index received' (duration: 920.948389ms)","trace[1032274849] 'applied index is now lower than readState.Index' (duration: 100.963µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:26.246612Z","caller":"traceutil/trace.go:171","msg":"trace[1056973360] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"922.689164ms","start":"2026-05-05T06:52:25.32391Z","end":"2026-05-05T06:52:26.246599Z","steps":["trace[1056973360] 'process raft request' (duration: 922.062759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:26.246795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:25.323885Z","time spent":"922.764277ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:26.24707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.994546ms","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-05T06:52:26.247144Z","caller":"traceutil/trace.go:171","msg":"trace[862810591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"922.104999ms","start":"2026-05-05T06:52:25.325023Z","end":"2026-05-05T06:52:26.247128Z","steps":["trace[862810591] 'agreement among raft nodes before linearized reading' (duration: 921.871913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:26.247191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:25.325005Z","time spent":"922.17651ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:52:26.288398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.268809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T06:52:26.288466Z","caller":"traceutil/trace.go:171","msg":"trace[1242249649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"963.354301ms","start":"2026-05-05T06:52:25.325096Z","end":"2026-05-05T06:52:26.288451Z","steps":["trace[1242249649] 'agreement among raft nodes before linearized reading' (duration: 963.181467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:26.288504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:25.325086Z","time spent":"963.410332ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:52:26.289036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.833444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T06:52:26.289082Z","caller":"traceutil/trace.go:171","msg":"trace[1880872684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1220; }","duration":"963.886145ms","start":"2026-05-05T06:52:25.325186Z","end":"2026-05-05T06:52:26.289072Z","steps":["trace[1880872684] 'agreement among raft nodes before linearized reading' (duration: 963.780032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:26.289116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:25.325182Z","time spent":"963.926786ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:52:26.289289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.145921ms","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-05T06:52:26.289323Z","caller":"traceutil/trace.go:171","msg":"trace[599878409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"964.212163ms","start":"2026-05-05T06:52:25.325103Z","end":"2026-05-05T06:52:26.289315Z","steps":["trace[599878409] 'agreement among raft nodes before linearized reading' (duration: 964.130171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:26.28935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:25.325089Z","time spent":"964.253694ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T06:52:26.946235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.962859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:52:26.946369Z","caller":"traceutil/trace.go:171","msg":"trace[739670600] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"695.274517ms","start":"2026-05-05T06:52:26.251064Z","end":"2026-05-05T06:52:26.946339Z","steps":["trace[739670600] 'process raft request' (duration: 403.107052ms)","trace[739670600] 'compare' (duration: 291.447466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:26.946463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:26.251039Z","time spent":"695.38055ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:27.360647Z","caller":"traceutil/trace.go:171","msg":"trace[819981607] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"1.069393351s","start":"2026-05-05T06:52:26.291214Z","end":"2026-05-05T06:52:27.360608Z","steps":["trace[819981607] 'process raft request' (duration: 1.02973048s)","trace[819981607] 'compare' (duration: 39.110307ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:27.360788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:26.291198Z","time spent":"1.069511045s","remote":"127.0.0.1:35994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:27.360796Z","caller":"traceutil/trace.go:171","msg":"trace[120917158] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1267; }","duration":"280.665708ms","start":"2026-05-05T06:52:27.080105Z","end":"2026-05-05T06:52:27.360771Z","steps":["trace[120917158] 'read index received' (duration: 240.807152ms)","trace[120917158] 'applied index is now lower than readState.Index' (duration: 39.857246ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:27.361149Z","caller":"traceutil/trace.go:171","msg":"trace[1602220760] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"1.067905144s","start":"2026-05-05T06:52:26.293226Z","end":"2026-05-05T06:52:27.361131Z","steps":["trace[1602220760] 'process raft request' (duration: 1.067312158s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:27.361162Z","caller":"traceutil/trace.go:171","msg":"trace[786946052] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"1.067909115s","start":"2026-05-05T06:52:26.293241Z","end":"2026-05-05T06:52:27.36115Z","steps":["trace[786946052] 'process raft request' (duration: 1.067400861s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:27.361224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:26.293208Z","time spent":"1.067983097s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:27.361224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.124031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T06:52:27.361258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:26.293199Z","time spent":"1.067989757s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:27.36127Z","caller":"traceutil/trace.go:171","msg":"trace[991383791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1225; }","duration":"281.211734ms","start":"2026-05-05T06:52:27.080045Z","end":"2026-05-05T06:52:27.361256Z","steps":["trace[991383791] 'agreement among raft nodes before linearized reading' (duration: 281.038219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:27.361262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.254876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:27.360995Z","caller":"traceutil/trace.go:171","msg":"trace[420356580] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"1.064566197s","start":"2026-05-05T06:52:26.296411Z","end":"2026-05-05T06:52:27.360977Z","steps":["trace[420356580] 'process raft request' (duration: 1.064279949s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:27.361319Z","caller":"traceutil/trace.go:171","msg":"trace[272982546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"238.363799ms","start":"2026-05-05T06:52:27.122942Z","end":"2026-05-05T06:52:27.361305Z","steps":["trace[272982546] 'agreement among raft nodes before linearized reading' (duration: 238.257886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:27.361431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:26.296385Z","time spent":"1.064954488s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:29.071053Z","caller":"traceutil/trace.go:171","msg":"trace[1513623616] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"118.495892ms","start":"2026-05-05T06:52:28.952532Z","end":"2026-05-05T06:52:29.071028Z","steps":["trace[1513623616] 'read index received' (duration: 118.291406ms)","trace[1513623616] 'applied index is now lower than readState.Index' (duration: 203.445µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:29.07122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.21006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:52:29.071265Z","caller":"traceutil/trace.go:171","msg":"trace[1527310915] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1227; }","duration":"112.303992ms","start":"2026-05-05T06:52:28.958948Z","end":"2026-05-05T06:52:29.071252Z","steps":["trace[1527310915] 'agreement among raft nodes before linearized reading' (duration: 112.21791ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:29.071482Z","caller":"traceutil/trace.go:171","msg":"trace[535297554] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"818.430799ms","start":"2026-05-05T06:52:28.252966Z","end":"2026-05-05T06:52:29.071396Z","steps":["trace[535297554] 'process raft request' (duration: 817.888395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:29.071611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:28.252941Z","time spent":"818.598633ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:29.07159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.046156ms","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-05T06:52:29.071695Z","caller":"traceutil/trace.go:171","msg":"trace[1803010644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"119.188969ms","start":"2026-05-05T06:52:28.95249Z","end":"2026-05-05T06:52:29.071678Z","steps":["trace[1803010644] 'agreement among raft nodes before linearized reading' (duration: 118.902792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:29.742465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.781517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:52:29.742587Z","caller":"traceutil/trace.go:171","msg":"trace[1109632215] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"618.91791ms","start":"2026-05-05T06:52:29.123648Z","end":"2026-05-05T06:52:29.742566Z","steps":["trace[1109632215] 'read index received' (duration: 24.751µs)","trace[1109632215] 'applied index is now lower than readState.Index' (duration: 618.891419ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:29.742906Z","caller":"traceutil/trace.go:171","msg":"trace[1478612295] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"667.029799ms","start":"2026-05-05T06:52:29.075856Z","end":"2026-05-05T06:52:29.742885Z","steps":["trace[1478612295] 'process raft request' (duration: 40.705388ms)","trace[1478612295] 'compare' (duration: 625.525771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:29.743003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.075831Z","time spent":"667.118052ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:29.74324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.608328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:29.743286Z","caller":"traceutil/trace.go:171","msg":"trace[818349150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"619.675059ms","start":"2026-05-05T06:52:29.123597Z","end":"2026-05-05T06:52:29.743272Z","steps":["trace[818349150] 'agreement among raft nodes before linearized reading' (duration: 619.600487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:29.74332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.123586Z","time spent":"619.72675ms","remote":"127.0.0.1:35962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:52:29.933136Z","caller":"traceutil/trace.go:171","msg":"trace[1646409247] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"190.488936ms","start":"2026-05-05T06:52:29.742622Z","end":"2026-05-05T06:52:29.933111Z","steps":["trace[1646409247] 'read index received' (duration: 179.861892ms)","trace[1646409247] 'applied index is now lower than readState.Index' (duration: 10.625814ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:29.933157Z","caller":"traceutil/trace.go:171","msg":"trace[1734572093] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"611.604232ms","start":"2026-05-05T06:52:29.321523Z","end":"2026-05-05T06:52:29.933127Z","steps":["trace[1734572093] 'process raft request' (duration: 601.03222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:29.933305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.321495Z","time spent":"611.751716ms","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T06:52:29.933325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.806093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:29.933361Z","caller":"traceutil/trace.go:171","msg":"trace[1986079847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1229; }","duration":"666.903716ms","start":"2026-05-05T06:52:29.266447Z","end":"2026-05-05T06:52:29.933351Z","steps":["trace[1986079847] 'agreement among raft nodes before linearized reading' (duration: 666.819834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:29.933345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.968894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:52:29.933389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.10951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T06:52:29.933405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.266424Z","time spent":"666.973228ms","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:52:29.933419Z","caller":"traceutil/trace.go:171","msg":"trace[1738816095] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1229; }","duration":"237.064366ms","start":"2026-05-05T06:52:29.696337Z","end":"2026-05-05T06:52:29.933401Z","steps":["trace[1738816095] 'agreement among raft nodes before linearized reading' (duration: 236.84229ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:29.933431Z","caller":"traceutil/trace.go:171","msg":"trace[2060849402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1229; }","duration":"566.183533ms","start":"2026-05-05T06:52:29.367236Z","end":"2026-05-05T06:52:29.933419Z","steps":["trace[2060849402] 'agreement among raft nodes before linearized reading' (duration: 566.013538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:29.933462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.36722Z","time spent":"566.234833ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:52:29.933642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.104187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T06:52:29.934457Z","caller":"traceutil/trace.go:171","msg":"trace[407688257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1229; }","duration":"567.912337ms","start":"2026-05-05T06:52:29.366523Z","end":"2026-05-05T06:52:29.934435Z","steps":["trace[407688257] 'agreement among raft nodes before linearized reading' (duration: 566.85236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:29.934533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.366475Z","time spent":"568.03853ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:52:29.933878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.520905ms","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-05T06:52:29.934781Z","caller":"traceutil/trace.go:171","msg":"trace[1073368047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1229; }","duration":"566.429419ms","start":"2026-05-05T06:52:29.368334Z","end":"2026-05-05T06:52:29.934763Z","steps":["trace[1073368047] 'agreement among raft nodes before linearized reading' (duration: 564.799597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:29.934852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.368327Z","time spent":"566.512691ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T06:52:29.933966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.006043ms","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-05T06:52:29.934966Z","caller":"traceutil/trace.go:171","msg":"trace[603560033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1229; }","duration":"509.064881ms","start":"2026-05-05T06:52:29.425888Z","end":"2026-05-05T06:52:29.934953Z","steps":["trace[603560033] 'agreement among raft nodes before linearized reading' (duration: 507.966953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:29.935664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.425859Z","time spent":"509.220805ms","remote":"127.0.0.1:47916","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-05T06:52:30.277807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.741886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:52:30.278012Z","caller":"traceutil/trace.go:171","msg":"trace[1431303002] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"338.865298ms","start":"2026-05-05T06:52:29.939127Z","end":"2026-05-05T06:52:30.277992Z","steps":["trace[1431303002] 'process raft request' (duration: 122.857455ms)","trace[1431303002] 'compare' (duration: 215.626113ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:30.278041Z","caller":"traceutil/trace.go:171","msg":"trace[1299310347] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"337.617376ms","start":"2026-05-05T06:52:29.940407Z","end":"2026-05-05T06:52:30.278025Z","steps":["trace[1299310347] 'process raft request' (duration: 337.551964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:30.278108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.939106Z","time spent":"338.95369ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:30.278105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.940396Z","time spent":"337.679947ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:30.278121Z","caller":"traceutil/trace.go:171","msg":"trace[1108225161] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"338.075977ms","start":"2026-05-05T06:52:29.939994Z","end":"2026-05-05T06:52:30.27807Z","steps":["trace[1108225161] 'process raft request' (duration: 337.915843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:30.278281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.939973Z","time spent":"338.223281ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:30.278348Z","caller":"traceutil/trace.go:171","msg":"trace[288577535] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"337.77188ms","start":"2026-05-05T06:52:29.940569Z","end":"2026-05-05T06:52:30.278341Z","steps":["trace[288577535] 'process raft request' (duration: 337.424131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:30.27838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:29.940535Z","time spent":"337.828711ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:30.278308Z","caller":"traceutil/trace.go:171","msg":"trace[2120592211] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1275; }","duration":"251.833546ms","start":"2026-05-05T06:52:30.026453Z","end":"2026-05-05T06:52:30.278287Z","steps":["trace[2120592211] 'read index received' (duration: 35.489415ms)","trace[2120592211] 'applied index is now lower than readState.Index' (duration: 216.341551ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:30.278745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.283638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5515"} {"level":"info","ts":"2026-05-05T06:52:30.278793Z","caller":"traceutil/trace.go:171","msg":"trace[656390212] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1233; }","duration":"252.37346ms","start":"2026-05-05T06:52:30.026408Z","end":"2026-05-05T06:52:30.278781Z","steps":["trace[656390212] 'agreement among raft nodes before linearized reading' (duration: 252.100973ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:30.305218Z","caller":"traceutil/trace.go:171","msg":"trace[2080770553] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"100.457097ms","start":"2026-05-05T06:52:30.20473Z","end":"2026-05-05T06:52:30.305187Z","steps":["trace[2080770553] 'process raft request' (duration: 100.316653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:30.305384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.576987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:30.305461Z","caller":"traceutil/trace.go:171","msg":"trace[1776937595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"181.697229ms","start":"2026-05-05T06:52:30.123746Z","end":"2026-05-05T06:52:30.305443Z","steps":["trace[1776937595] 'agreement among raft nodes before linearized reading' (duration: 181.537085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:30.728315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.056287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:52:30.728854Z","caller":"traceutil/trace.go:171","msg":"trace[2041786099] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"419.660878ms","start":"2026-05-05T06:52:30.309169Z","end":"2026-05-05T06:52:30.72883Z","steps":["trace[2041786099] 'process raft request' (duration: 126.018137ms)","trace[2041786099] 'compare' (duration: 292.706607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:30.728919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:30.309152Z","time spent":"419.741491ms","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T06:52:30.753577Z","caller":"traceutil/trace.go:171","msg":"trace[2023218613] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1281; }","duration":"390.488337ms","start":"2026-05-05T06:52:30.363068Z","end":"2026-05-05T06:52:30.753557Z","steps":["trace[2023218613] 'read index received' (duration: 72.124409ms)","trace[2023218613] 'applied index is now lower than readState.Index' (duration: 318.362278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:30.753776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.692252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-05-05T06:52:30.753834Z","caller":"traceutil/trace.go:171","msg":"trace[1030127092] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1236; }","duration":"390.781155ms","start":"2026-05-05T06:52:30.363037Z","end":"2026-05-05T06:52:30.753818Z","steps":["trace[1030127092] 'agreement among raft nodes before linearized reading' (duration: 390.665332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:30.753869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:30.363022Z","time spent":"390.841457ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":689,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-05T06:52:31.715528Z","caller":"traceutil/trace.go:171","msg":"trace[1656184528] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"196.720606ms","start":"2026-05-05T06:52:31.518784Z","end":"2026-05-05T06:52:31.715504Z","steps":["trace[1656184528] 'process raft request' (duration: 196.576032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:34.029834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741879670629074,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:52:34.499235Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.350614035s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T06:52:34.500111Z","caller":"traceutil/trace.go:171","msg":"trace[1604585120] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"1.351626782s","start":"2026-05-05T06:52:33.148455Z","end":"2026-05-05T06:52:34.500081Z","steps":["trace[1604585120] 'process raft request' (duration: 1.351394996s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:34.500438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:33.148426Z","time spent":"1.351762655s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:52:35.198871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.459244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:52:35.19919Z","caller":"traceutil/trace.go:171","msg":"trace[1346238791] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"1.828715709s","start":"2026-05-05T06:52:33.370449Z","end":"2026-05-05T06:52:35.199165Z","steps":["trace[1346238791] 'process raft request' (duration: 1.175898195s)","trace[1346238791] 'compare' (duration: 652.189517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:35.199297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:33.370421Z","time spent":"1.828829662s","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T06:52:35.199504Z","caller":"traceutil/trace.go:171","msg":"trace[1942206701] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1298; }","duration":"1.669803996s","start":"2026-05-05T06:52:33.529674Z","end":"2026-05-05T06:52:35.199478Z","steps":["trace[1942206701] 'read index received' (duration: 970.027623ms)","trace[1942206701] 'applied index is now lower than readState.Index' (duration: 699.767623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:35.199776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670088903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:35.199835Z","caller":"traceutil/trace.go:171","msg":"trace[632514788] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1253; }","duration":"1.670186316s","start":"2026-05-05T06:52:33.529636Z","end":"2026-05-05T06:52:35.199822Z","steps":["trace[632514788] 'agreement among raft nodes before linearized reading' (duration: 1.669923539s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.199945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:33.52962Z","time spent":"1.670312079s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T06:52:35.199948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.299528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:52:35.200002Z","caller":"traceutil/trace.go:171","msg":"trace[175281037] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1254; }","duration":"304.40253ms","start":"2026-05-05T06:52:34.895583Z","end":"2026-05-05T06:52:35.199985Z","steps":["trace[175281037] 'agreement among raft nodes before linearized reading' (duration: 304.265947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.200041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:34.895563Z","time spent":"304.467272ms","remote":"127.0.0.1:41136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:52:35.200088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.249211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T06:52:35.200139Z","caller":"traceutil/trace.go:171","msg":"trace[530088931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1254; }","duration":"821.302633ms","start":"2026-05-05T06:52:34.378824Z","end":"2026-05-05T06:52:35.200126Z","steps":["trace[530088931] 'agreement among raft nodes before linearized reading' (duration: 821.136549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.200176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:34.378755Z","time spent":"821.408616ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:52:35.200207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.496998ms","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-05T06:52:35.200245Z","caller":"traceutil/trace.go:171","msg":"trace[161689290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"821.58323ms","start":"2026-05-05T06:52:34.378651Z","end":"2026-05-05T06:52:35.200235Z","steps":["trace[161689290] 'agreement among raft nodes before linearized reading' (duration: 821.481767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.200283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:34.378625Z","time spent":"821.651802ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T06:52:35.199972Z","caller":"traceutil/trace.go:171","msg":"trace[1536075867] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"475.099047ms","start":"2026-05-05T06:52:34.724845Z","end":"2026-05-05T06:52:35.199944Z","steps":["trace[1536075867] 'process raft request' (duration: 474.702957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.200445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.498093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T06:52:35.200465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.59509ms","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-05T06:52:35.20048Z","caller":"traceutil/trace.go:171","msg":"trace[1161723055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1254; }","duration":"834.567355ms","start":"2026-05-05T06:52:34.365903Z","end":"2026-05-05T06:52:35.200471Z","steps":["trace[1161723055] 'agreement among raft nodes before linearized reading' (duration: 834.488332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.200506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:34.365883Z","time spent":"834.617066ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T06:52:35.200505Z","caller":"traceutil/trace.go:171","msg":"trace[2018817554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1254; }","duration":"821.658882ms","start":"2026-05-05T06:52:34.378835Z","end":"2026-05-05T06:52:35.200494Z","steps":["trace[2018817554] 'agreement among raft nodes before linearized reading' (duration: 821.460687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.200609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:34.378824Z","time spent":"821.778165ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T06:52:35.20056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390164394s","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-05T06:52:35.200626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.186942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:35.200782Z","caller":"traceutil/trace.go:171","msg":"trace[867053878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"479.337056ms","start":"2026-05-05T06:52:34.721429Z","end":"2026-05-05T06:52:35.200766Z","steps":["trace[867053878] 'agreement among raft nodes before linearized reading' (duration: 479.169582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.200886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:34.721372Z","time spent":"479.50245ms","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:52:35.200702Z","caller":"traceutil/trace.go:171","msg":"trace[370549178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1254; }","duration":"1.390509232s","start":"2026-05-05T06:52:33.810175Z","end":"2026-05-05T06:52:35.200684Z","steps":["trace[370549178] 'agreement among raft nodes before linearized reading' (duration: 1.38965146s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.201098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:33.810154Z","time spent":"1.390923383s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:52:35.201948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:34.724806Z","time spent":"475.637071ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:35.435549Z","caller":"traceutil/trace.go:171","msg":"trace[935306479] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"230.805104ms","start":"2026-05-05T06:52:35.204718Z","end":"2026-05-05T06:52:35.435523Z","steps":["trace[935306479] 'process raft request' (duration: 230.557268ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:35.435587Z","caller":"traceutil/trace.go:171","msg":"trace[1103904403] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"229.673105ms","start":"2026-05-05T06:52:35.205889Z","end":"2026-05-05T06:52:35.435562Z","steps":["trace[1103904403] 'process raft request' (duration: 229.532212ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:35.435674Z","caller":"traceutil/trace.go:171","msg":"trace[2030429259] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"229.45397ms","start":"2026-05-05T06:52:35.206205Z","end":"2026-05-05T06:52:35.435659Z","steps":["trace[2030429259] 'process raft request' (duration: 229.288535ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:35.435901Z","caller":"traceutil/trace.go:171","msg":"trace[213920071] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"229.367368ms","start":"2026-05-05T06:52:35.206524Z","end":"2026-05-05T06:52:35.435891Z","steps":["trace[213920071] 'process raft request' (duration: 229.059ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:35.435924Z","caller":"traceutil/trace.go:171","msg":"trace[1915432777] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"229.039719ms","start":"2026-05-05T06:52:35.206871Z","end":"2026-05-05T06:52:35.435911Z","steps":["trace[1915432777] 'process raft request' (duration: 228.936136ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:35.435902Z","caller":"traceutil/trace.go:171","msg":"trace[1533507645] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1301; }","duration":"230.398373ms","start":"2026-05-05T06:52:35.205482Z","end":"2026-05-05T06:52:35.43588Z","steps":["trace[1533507645] 'read index received' (duration: 157.591178ms)","trace[1533507645] 'applied index is now lower than readState.Index' (duration: 72.806135ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:35.436034Z","caller":"traceutil/trace.go:171","msg":"trace[1071708429] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"229.767448ms","start":"2026-05-05T06:52:35.206252Z","end":"2026-05-05T06:52:35.436019Z","steps":["trace[1071708429] 'process raft request' (duration: 229.287296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:35.436147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.648699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:35.43618Z","caller":"traceutil/trace.go:171","msg":"trace[1901151898] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1260; }","duration":"230.720422ms","start":"2026-05-05T06:52:35.205453Z","end":"2026-05-05T06:52:35.436173Z","steps":["trace[1901151898] 'agreement among raft nodes before linearized reading' (duration: 230.622609ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:36.792062Z","caller":"traceutil/trace.go:171","msg":"trace[1389835138] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"286.96787ms","start":"2026-05-05T06:52:36.50507Z","end":"2026-05-05T06:52:36.792038Z","steps":["trace[1389835138] 'process raft request' (duration: 286.826916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:37.561159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.708784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T06:52:37.561226Z","caller":"traceutil/trace.go:171","msg":"trace[1312026610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"119.806236ms","start":"2026-05-05T06:52:37.441406Z","end":"2026-05-05T06:52:37.561213Z","steps":["trace[1312026610] 'range keys from in-memory index tree' (duration: 119.599691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:37.561241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.918999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T06:52:37.561265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.099564ms","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-05T06:52:37.56131Z","caller":"traceutil/trace.go:171","msg":"trace[1856713353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1267; }","duration":"120.037402ms","start":"2026-05-05T06:52:37.441256Z","end":"2026-05-05T06:52:37.561293Z","steps":["trace[1856713353] 'range keys from in-memory index tree' (duration: 119.725304ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:37.561325Z","caller":"traceutil/trace.go:171","msg":"trace[60803660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1267; }","duration":"120.193306ms","start":"2026-05-05T06:52:37.441115Z","end":"2026-05-05T06:52:37.561308Z","steps":["trace[60803660] 'range keys from in-memory index tree' (duration: 119.882588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:37.561354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.106164ms","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-05T06:52:37.56141Z","caller":"traceutil/trace.go:171","msg":"trace[801730563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"120.190015ms","start":"2026-05-05T06:52:37.441207Z","end":"2026-05-05T06:52:37.561397Z","steps":["trace[801730563] 'range keys from in-memory index tree' (duration: 119.883188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:37.561169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.599301ms","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-05T06:52:37.561468Z","caller":"traceutil/trace.go:171","msg":"trace[445085752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"119.95358ms","start":"2026-05-05T06:52:37.441499Z","end":"2026-05-05T06:52:37.561452Z","steps":["trace[445085752] 'range keys from in-memory index tree' (duration: 119.450437ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:38.925306Z","caller":"traceutil/trace.go:171","msg":"trace[683632891] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"125.890416ms","start":"2026-05-05T06:52:38.799393Z","end":"2026-05-05T06:52:38.925283Z","steps":["trace[683632891] 'process raft request' (duration: 125.758783ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:39.814199Z","caller":"traceutil/trace.go:171","msg":"trace[672732651] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"209.316878ms","start":"2026-05-05T06:52:39.604859Z","end":"2026-05-05T06:52:39.814176Z","steps":["trace[672732651] 'process raft request' (duration: 209.285387ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:39.814407Z","caller":"traceutil/trace.go:171","msg":"trace[379743062] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"210.654552ms","start":"2026-05-05T06:52:39.603704Z","end":"2026-05-05T06:52:39.814358Z","steps":["trace[379743062] 'process raft request' (duration: 210.264082ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:39.81454Z","caller":"traceutil/trace.go:171","msg":"trace[1837318132] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"210.218411ms","start":"2026-05-05T06:52:39.604283Z","end":"2026-05-05T06:52:39.814501Z","steps":["trace[1837318132] 'process raft request' (duration: 209.809451ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:39.814518Z","caller":"traceutil/trace.go:171","msg":"trace[1384693776] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"210.122209ms","start":"2026-05-05T06:52:39.604333Z","end":"2026-05-05T06:52:39.814456Z","steps":["trace[1384693776] 'process raft request' (duration: 209.787ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:41.230633Z","caller":"traceutil/trace.go:171","msg":"trace[598659097] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"252.892641ms","start":"2026-05-05T06:52:40.977718Z","end":"2026-05-05T06:52:41.230611Z","steps":["trace[598659097] 'read index received' (duration: 252.714156ms)","trace[598659097] 'applied index is now lower than readState.Index' (duration: 177.505µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:52:41.230819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.716576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:52:41.230787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.056846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:52:41.230888Z","caller":"traceutil/trace.go:171","msg":"trace[1988180691] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1293; }","duration":"253.177409ms","start":"2026-05-05T06:52:40.9777Z","end":"2026-05-05T06:52:41.230877Z","steps":["trace[1988180691] 'agreement among raft nodes before linearized reading' (duration: 253.029945ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:41.23089Z","caller":"traceutil/trace.go:171","msg":"trace[521942244] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1293; }","duration":"131.833299ms","start":"2026-05-05T06:52:41.099038Z","end":"2026-05-05T06:52:41.230872Z","steps":["trace[521942244] 'agreement among raft nodes before linearized reading' (duration: 131.724196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:41.230956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.869172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:41.231022Z","caller":"traceutil/trace.go:171","msg":"trace[322042376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"107.984684ms","start":"2026-05-05T06:52:41.123023Z","end":"2026-05-05T06:52:41.231007Z","steps":["trace[322042376] 'agreement among raft nodes before linearized reading' (duration: 107.843521ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:41.231111Z","caller":"traceutil/trace.go:171","msg":"trace[1299724603] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"300.787157ms","start":"2026-05-05T06:52:40.929959Z","end":"2026-05-05T06:52:41.230746Z","steps":["trace[1299724603] 'process raft request' (duration: 300.52141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:41.231993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:52:40.929939Z","time spent":"301.918476ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:48.023948Z","caller":"traceutil/trace.go:171","msg":"trace[141653664] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"113.706183ms","start":"2026-05-05T06:52:47.910219Z","end":"2026-05-05T06:52:48.023925Z","steps":["trace[141653664] 'process raft request' (duration: 108.374705ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:48.024303Z","caller":"traceutil/trace.go:171","msg":"trace[730252242] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"114.043521ms","start":"2026-05-05T06:52:47.910218Z","end":"2026-05-05T06:52:48.024262Z","steps":["trace[730252242] 'process raft request' (duration: 113.667812ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:50.213301Z","caller":"traceutil/trace.go:171","msg":"trace[316290848] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"141.144879ms","start":"2026-05-05T06:52:50.072125Z","end":"2026-05-05T06:52:50.21327Z","steps":["trace[316290848] 'process raft request' (duration: 140.936603ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:50.220562Z","caller":"traceutil/trace.go:171","msg":"trace[1567420150] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"147.73296ms","start":"2026-05-05T06:52:50.072774Z","end":"2026-05-05T06:52:50.220507Z","steps":["trace[1567420150] 'process raft request' (duration: 147.33964ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:11.690715Z","caller":"traceutil/trace.go:171","msg":"trace[1713590479] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"122.54152ms","start":"2026-05-05T06:53:11.568137Z","end":"2026-05-05T06:53:11.690678Z","steps":["trace[1713590479] 'process raft request' (duration: 122.327405ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:14.082227Z","caller":"traceutil/trace.go:171","msg":"trace[785375070] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"149.71643ms","start":"2026-05-05T06:53:13.932488Z","end":"2026-05-05T06:53:14.082204Z","steps":["trace[785375070] 'process raft request' (duration: 145.493672ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:24.358386Z","caller":"traceutil/trace.go:171","msg":"trace[81872683] linearizableReadLoop","detail":"{readStateIndex:1565; appliedIndex:1564; }","duration":"132.105056ms","start":"2026-05-05T06:53:24.226261Z","end":"2026-05-05T06:53:24.358366Z","steps":["trace[81872683] 'read index received' (duration: 84.864258ms)","trace[81872683] 'applied index is now lower than readState.Index' (duration: 47.239728ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:53:24.358437Z","caller":"traceutil/trace.go:171","msg":"trace[636673660] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"154.072843ms","start":"2026-05-05T06:53:24.20434Z","end":"2026-05-05T06:53:24.358413Z","steps":["trace[636673660] 'process raft request' (duration: 106.871725ms)","trace[636673660] 'compare' (duration: 47.034353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:53:24.358718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.451485ms","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-05T06:53:24.358756Z","caller":"traceutil/trace.go:171","msg":"trace[333403388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1508; }","duration":"132.542458ms","start":"2026-05-05T06:53:24.226201Z","end":"2026-05-05T06:53:24.358744Z","steps":["trace[333403388] 'agreement among raft nodes before linearized reading' (duration: 132.246441ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:30.638558Z","caller":"traceutil/trace.go:171","msg":"trace[1912400356] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"119.358638ms","start":"2026-05-05T06:53:30.519182Z","end":"2026-05-05T06:53:30.638541Z","steps":["trace[1912400356] 'process raft request' (duration: 114.564614ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:30.638718Z","caller":"traceutil/trace.go:171","msg":"trace[771415972] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"109.111484ms","start":"2026-05-05T06:53:30.529581Z","end":"2026-05-05T06:53:30.638693Z","steps":["trace[771415972] 'process raft request' (duration: 108.932459ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:56.313567Z","caller":"traceutil/trace.go:171","msg":"trace[560518113] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"101.393415ms","start":"2026-05-05T06:53:56.212091Z","end":"2026-05-05T06:53:56.313484Z","steps":["trace[560518113] 'process raft request' (duration: 101.239941ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:57.126563Z","caller":"traceutil/trace.go:171","msg":"trace[278798779] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"157.113005ms","start":"2026-05-05T06:53:56.96943Z","end":"2026-05-05T06:53:57.126543Z","steps":["trace[278798779] 'process raft request' (duration: 156.850578ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:00.347783Z","caller":"traceutil/trace.go:171","msg":"trace[1905694741] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"238.455627ms","start":"2026-05-05T06:56:00.108998Z","end":"2026-05-05T06:56:00.347454Z","steps":["trace[1905694741] 'process raft request' (duration: 238.329744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:56:08.400523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.311997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1140"} {"level":"info","ts":"2026-05-05T06:56:08.400634Z","caller":"traceutil/trace.go:171","msg":"trace[1965063275] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:2294; }","duration":"134.488852ms","start":"2026-05-05T06:56:08.266124Z","end":"2026-05-05T06:56:08.400613Z","steps":["trace[1965063275] 'range keys from in-memory index tree' (duration: 134.200265ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:09.085354Z","caller":"traceutil/trace.go:171","msg":"trace[841262784] transaction","detail":"{read_only:false; response_revision:2297; number_of_response:1; }","duration":"141.680188ms","start":"2026-05-05T06:56:08.943642Z","end":"2026-05-05T06:56:09.085322Z","steps":["trace[841262784] 'process raft request' (duration: 141.479653ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:09.317432Z","caller":"traceutil/trace.go:171","msg":"trace[803817256] linearizableReadLoop","detail":"{readStateIndex:2390; appliedIndex:2389; }","duration":"220.744452ms","start":"2026-05-05T06:56:09.096646Z","end":"2026-05-05T06:56:09.31739Z","steps":["trace[803817256] 'read index received' (duration: 136.330638ms)","trace[803817256] 'applied index is now lower than readState.Index' (duration: 84.412504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:56:09.317878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.556939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/csi-8c4e74070c0f05d3d95f718216b46360540803be8167ba445b13a6abb8ca90a8\" ","response":"range_response_count:1 size:651"} {"level":"warn","ts":"2026-05-05T06:56:09.317969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.605013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:841"} {"level":"info","ts":"2026-05-05T06:56:09.318004Z","caller":"traceutil/trace.go:171","msg":"trace[1733236749] range","detail":"{range_begin:/registry/volumeattachments/csi-8c4e74070c0f05d3d95f718216b46360540803be8167ba445b13a6abb8ca90a8; range_end:; response_count:1; response_revision:2299; }","duration":"224.726834ms","start":"2026-05-05T06:56:09.093255Z","end":"2026-05-05T06:56:09.317982Z","steps":["trace[1733236749] 'agreement among raft nodes before linearized reading' (duration: 224.407996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:09.31806Z","caller":"traceutil/trace.go:171","msg":"trace[1811699817] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:2299; }","duration":"118.742166ms","start":"2026-05-05T06:56:09.199297Z","end":"2026-05-05T06:56:09.318039Z","steps":["trace[1811699817] 'agreement among raft nodes before linearized reading' (duration: 118.644084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:56:09.317871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.862741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:56:09.318197Z","caller":"traceutil/trace.go:171","msg":"trace[1549412002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2299; }","duration":"196.20043ms","start":"2026-05-05T06:56:09.121965Z","end":"2026-05-05T06:56:09.318165Z","steps":["trace[1549412002] 'agreement among raft nodes before linearized reading' (duration: 195.80794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:56:09.317886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.532548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-05-05T06:56:09.318358Z","caller":"traceutil/trace.go:171","msg":"trace[1120459967] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:2299; }","duration":"154.9934ms","start":"2026-05-05T06:56:09.163295Z","end":"2026-05-05T06:56:09.318289Z","steps":["trace[1120459967] 'agreement among raft nodes before linearized reading' (duration: 154.495207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:56:09.318999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.452413ms","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-05T06:56:09.319076Z","caller":"traceutil/trace.go:171","msg":"trace[400075956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2299; }","duration":"171.582246ms","start":"2026-05-05T06:56:09.147477Z","end":"2026-05-05T06:56:09.319059Z","steps":["trace[400075956] 'agreement among raft nodes before linearized reading' (duration: 170.471278ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:09.319605Z","caller":"traceutil/trace.go:171","msg":"trace[171192672] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"225.494225ms","start":"2026-05-05T06:56:09.092124Z","end":"2026-05-05T06:56:09.317618Z","steps":["trace[171192672] 'process raft request' (duration: 140.974059ms)","trace[171192672] 'compare' (duration: 84.075525ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:56:09.553993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.616655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:56:09.554101Z","caller":"traceutil/trace.go:171","msg":"trace[967515343] linearizableReadLoop","detail":"{readStateIndex:2393; appliedIndex:2392; }","duration":"148.77966ms","start":"2026-05-05T06:56:09.405304Z","end":"2026-05-05T06:56:09.554084Z","steps":["trace[967515343] 'read index received' (duration: 20.893598ms)","trace[967515343] 'applied index is now lower than readState.Index' (duration: 127.884712ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:56:09.554175Z","caller":"traceutil/trace.go:171","msg":"trace[1999513462] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"190.688168ms","start":"2026-05-05T06:56:09.363454Z","end":"2026-05-05T06:56:09.554142Z","steps":["trace[1999513462] 'process raft request' (duration: 62.818927ms)","trace[1999513462] 'compare' (duration: 127.406789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:56:09.554238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.638462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:841"} {"level":"info","ts":"2026-05-05T06:56:09.55427Z","caller":"traceutil/trace.go:171","msg":"trace[1595527448] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:2302; }","duration":"170.710944ms","start":"2026-05-05T06:56:09.383548Z","end":"2026-05-05T06:56:09.554258Z","steps":["trace[1595527448] 'agreement among raft nodes before linearized reading' (duration: 170.632152ms)"],"step_count":1}