{"level":"warn","ts":"2026-05-20T11:28:39.840121Z","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-20T11:28:39.840307Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-20T11:28:39.840515Z","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-20T11:28:39.840557Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-20T11:28:39.840596Z","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-20T11:28:39.841865Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-05-20T11:28:39.841981Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-20T11:28:39.849641Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.288693ms"} {"level":"info","ts":"2026-05-20T11:28:39.857864Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-05-20T11:28:39.857963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T11:28:39.85799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-05-20T11:28:39.858004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T11:28:39.858014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-05-20T11:28:39.858053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-05-20T11:28:39.868232Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T11:28:39.869918Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T11:28:39.882417Z","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-20T11:28:39.886119Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T11:28:39.88653Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T11:28:39.887058Z","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-20T11:28:39.892803Z","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-20T11:28:39.892816Z","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-20T11:28:39.893323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-05-20T11:28:39.895772Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-20T11:28:39.898582Z","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-20T11:28:39.898793Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-20T11:28:39.898855Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-20T11:28:39.898966Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T11:28:39.899009Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T11:28:40.158313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T11:28:40.158406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T11:28:40.158473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-05-20T11:28:40.158513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-05-20T11:28:40.158531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-20T11:28:40.158548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-05-20T11:28:40.158568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-20T11:28:40.166362Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T11:28:40.166417Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T11:28:40.166559Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T11:28:40.166391Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T11:28:40.167862Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T11:28:40.168116Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T11:28:40.168181Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T11:28:40.168925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-05-20T11:28:40.169061Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T11:28:40.169138Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T11:28:40.178428Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T11:28:46.518801Z","caller":"traceutil/trace.go:171","msg":"trace[2003863371] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"137.882737ms","start":"2026-05-20T11:28:46.380883Z","end":"2026-05-20T11:28:46.518766Z","steps":["trace[2003863371] 'process raft request' (duration: 137.656661ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:28:46.545047Z","caller":"traceutil/trace.go:171","msg":"trace[880226920] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:327; }","duration":"143.346822ms","start":"2026-05-20T11:28:46.401666Z","end":"2026-05-20T11:28:46.545013Z","steps":["trace[880226920] 'read index received' (duration: 117.01804ms)","trace[880226920] 'applied index is now lower than readState.Index' (duration: 26.327452ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:28:46.545381Z","caller":"traceutil/trace.go:171","msg":"trace[252565047] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"163.851919ms","start":"2026-05-20T11:28:46.381515Z","end":"2026-05-20T11:28:46.545367Z","steps":["trace[252565047] 'process raft request' (duration: 163.199612ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:28:46.545587Z","caller":"traceutil/trace.go:171","msg":"trace[52758397] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"111.216026ms","start":"2026-05-20T11:28:46.434363Z","end":"2026-05-20T11:28:46.545579Z","steps":["trace[52758397] 'process raft request' (duration: 110.502527ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:28:46.545658Z","caller":"traceutil/trace.go:171","msg":"trace[934131067] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"109.450569ms","start":"2026-05-20T11:28:46.436173Z","end":"2026-05-20T11:28:46.545624Z","steps":["trace[934131067] 'process raft request' (duration: 108.813512ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:28:46.545732Z","caller":"traceutil/trace.go:171","msg":"trace[373494101] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"110.118247ms","start":"2026-05-20T11:28:46.435561Z","end":"2026-05-20T11:28:46.545679Z","steps":["trace[373494101] 'process raft request' (duration: 109.335096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:28:46.545783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.106763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-20T11:28:46.545947Z","caller":"traceutil/trace.go:171","msg":"trace[1940012560] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"110.232709ms","start":"2026-05-20T11:28:46.435701Z","end":"2026-05-20T11:28:46.545934Z","steps":["trace[1940012560] 'process raft request' (duration: 109.215412ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:28:46.54599Z","caller":"traceutil/trace.go:171","msg":"trace[320639458] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:318; }","duration":"144.37252ms","start":"2026-05-20T11:28:46.401604Z","end":"2026-05-20T11:28:46.545976Z","steps":["trace[320639458] 'agreement among raft nodes before linearized reading' (duration: 144.113493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:28:46.547785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.639471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-20T11:28:46.547835Z","caller":"traceutil/trace.go:171","msg":"trace[688581783] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:319; }","duration":"113.754544ms","start":"2026-05-20T11:28:46.434069Z","end":"2026-05-20T11:28:46.547823Z","steps":["trace[688581783] 'agreement among raft nodes before linearized reading' (duration: 113.075176ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:12.046768Z","caller":"traceutil/trace.go:171","msg":"trace[866305851] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"128.155219ms","start":"2026-05-20T11:29:11.91858Z","end":"2026-05-20T11:29:12.046736Z","steps":["trace[866305851] 'process raft request' (duration: 127.968784ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:12.054048Z","caller":"traceutil/trace.go:171","msg":"trace[1049226137] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"118.657177ms","start":"2026-05-20T11:29:11.935358Z","end":"2026-05-20T11:29:12.054015Z","steps":["trace[1049226137] 'process raft request' (duration: 118.178435ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:12.054293Z","caller":"traceutil/trace.go:171","msg":"trace[601956187] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"118.35614ms","start":"2026-05-20T11:29:11.935838Z","end":"2026-05-20T11:29:12.054194Z","steps":["trace[601956187] 'process raft request' (duration: 117.812136ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:12.054429Z","caller":"traceutil/trace.go:171","msg":"trace[31562538] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"111.401387ms","start":"2026-05-20T11:29:11.943012Z","end":"2026-05-20T11:29:12.054413Z","steps":["trace[31562538] 'process raft request' (duration: 110.683638ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:12.054969Z","caller":"traceutil/trace.go:171","msg":"trace[1059359566] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"108.1004ms","start":"2026-05-20T11:29:11.946858Z","end":"2026-05-20T11:29:12.054958Z","steps":["trace[1059359566] 'process raft request' (duration: 106.894668ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:12.538819Z","caller":"traceutil/trace.go:171","msg":"trace[300674771] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"481.559825ms","start":"2026-05-20T11:29:12.057239Z","end":"2026-05-20T11:29:12.538798Z","steps":["trace[300674771] 'process raft request' (duration: 480.363803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.539611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.057225Z","time spent":"481.661839ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:29:12.545613Z","caller":"traceutil/trace.go:171","msg":"trace[1795924233] transaction","detail":"{read_only:false; number_of_response:1; response_revision:528; }","duration":"488.328323ms","start":"2026-05-20T11:29:12.057243Z","end":"2026-05-20T11:29:12.545571Z","steps":["trace[1795924233] 'process raft request' (duration: 487.981044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.545849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.057222Z","time spent":"488.494659ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:29:12.546162Z","caller":"traceutil/trace.go:171","msg":"trace[62380425] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"478.968626ms","start":"2026-05-20T11:29:12.067137Z","end":"2026-05-20T11:29:12.546106Z","steps":["trace[62380425] 'process raft request' (duration: 478.35438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.546342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.067111Z","time spent":"479.167452ms","remote":"127.0.0.1:44680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:29:12.546739Z","caller":"traceutil/trace.go:171","msg":"trace[209575864] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"474.852158ms","start":"2026-05-20T11:29:12.071875Z","end":"2026-05-20T11:29:12.546727Z","steps":["trace[209575864] 'process raft request' (duration: 474.099408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.546805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.07187Z","time spent":"474.897039ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:29:12.546875Z","caller":"traceutil/trace.go:171","msg":"trace[1931527583] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"473.694367ms","start":"2026-05-20T11:29:12.073169Z","end":"2026-05-20T11:29:12.546863Z","steps":["trace[1931527583] 'process raft request' (duration: 473.03552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.546921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.073164Z","time spent":"473.739738ms","remote":"127.0.0.1:44680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:29:12.547019Z","caller":"traceutil/trace.go:171","msg":"trace[195511830] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"474.14902ms","start":"2026-05-20T11:29:12.072861Z","end":"2026-05-20T11:29:12.54701Z","steps":["trace[195511830] 'process raft request' (duration: 473.309958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.547066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.072854Z","time spent":"474.195451ms","remote":"127.0.0.1:44680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:29:12.547077Z","caller":"traceutil/trace.go:171","msg":"trace[789348827] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"478.669828ms","start":"2026-05-20T11:29:12.068379Z","end":"2026-05-20T11:29:12.547049Z","steps":["trace[789348827] 'process raft request' (duration: 477.188339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.547233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.068359Z","time spent":"478.802803ms","remote":"127.0.0.1:44680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:29:12.547285Z","caller":"traceutil/trace.go:171","msg":"trace[1548180066] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"469.977461ms","start":"2026-05-20T11:29:12.077299Z","end":"2026-05-20T11:29:12.547277Z","steps":["trace[1548180066] 'process raft request' (duration: 469.19735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.547339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.077293Z","time spent":"470.020262ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:29:12.54737Z","caller":"traceutil/trace.go:171","msg":"trace[993864468] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"468.641625ms","start":"2026-05-20T11:29:12.078715Z","end":"2026-05-20T11:29:12.547357Z","steps":["trace[993864468] 'process raft request' (duration: 467.901925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.547448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.078707Z","time spent":"468.699096ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:29:12.547556Z","caller":"traceutil/trace.go:171","msg":"trace[32713593] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"466.320033ms","start":"2026-05-20T11:29:12.081228Z","end":"2026-05-20T11:29:12.547548Z","steps":["trace[32713593] 'process raft request' (duration: 465.443471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.547618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.081201Z","time spent":"466.381135ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:29:12.547679Z","caller":"traceutil/trace.go:171","msg":"trace[262998250] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"417.588518ms","start":"2026-05-20T11:29:12.130083Z","end":"2026-05-20T11:29:12.547671Z","steps":["trace[262998250] 'process raft request' (duration: 416.654504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.547713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.130055Z","time spent":"417.64165ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T11:29:12.547847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.445401ms","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-20T11:29:12.547881Z","caller":"traceutil/trace.go:171","msg":"trace[361882828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"146.517693ms","start":"2026-05-20T11:29:12.401356Z","end":"2026-05-20T11:29:12.547873Z","steps":["trace[361882828] 'agreement among raft nodes before linearized reading' (duration: 146.377169ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:12.547645Z","caller":"traceutil/trace.go:171","msg":"trace[1745761965] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:547; }","duration":"146.203985ms","start":"2026-05-20T11:29:12.401398Z","end":"2026-05-20T11:29:12.547602Z","steps":["trace[1745761965] 'read index received' (duration: 136.292744ms)","trace[1745761965] 'applied index is now lower than readState.Index' (duration: 9.909831ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:12.550267Z","caller":"traceutil/trace.go:171","msg":"trace[140869130] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"476.04608ms","start":"2026-05-20T11:29:12.071658Z","end":"2026-05-20T11:29:12.547704Z","steps":["trace[140869130] 'process raft request' (duration: 473.946095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:12.55045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:12.071651Z","time spent":"478.671949ms","remote":"127.0.0.1:44416","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-20T11:29:12.883059Z","caller":"traceutil/trace.go:171","msg":"trace[1262981429] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"136.091847ms","start":"2026-05-20T11:29:12.746935Z","end":"2026-05-20T11:29:12.883027Z","steps":["trace[1262981429] 'process raft request' (duration: 128.426625ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:12.884254Z","caller":"traceutil/trace.go:171","msg":"trace[869315902] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"136.935ms","start":"2026-05-20T11:29:12.74726Z","end":"2026-05-20T11:29:12.884195Z","steps":["trace[869315902] 'process raft request' (duration: 135.907244ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:12.88563Z","caller":"traceutil/trace.go:171","msg":"trace[1852114141] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"109.881577ms","start":"2026-05-20T11:29:12.775733Z","end":"2026-05-20T11:29:12.885615Z","steps":["trace[1852114141] 'process raft request' (duration: 108.306765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.864116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258049363,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T11:29:19.869126Z","caller":"traceutil/trace.go:171","msg":"trace[711626095] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"505.054854ms","start":"2026-05-20T11:29:19.363994Z","end":"2026-05-20T11:29:19.869049Z","steps":["trace[711626095] 'read index received' (duration: 503.945455ms)","trace[711626095] 'applied index is now lower than readState.Index' (duration: 1.108419ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:19.869258Z","caller":"traceutil/trace.go:171","msg":"trace[364475138] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"509.43463ms","start":"2026-05-20T11:29:19.359762Z","end":"2026-05-20T11:29:19.869196Z","steps":["trace[364475138] 'process raft request' (duration: 508.614959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.86934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.253299ms","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-20T11:29:19.869382Z","caller":"traceutil/trace.go:171","msg":"trace[1648491931] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"505.338192ms","start":"2026-05-20T11:29:19.364032Z","end":"2026-05-20T11:29:19.86937Z","steps":["trace[1648491931] 'agreement among raft nodes before linearized reading' (duration: 505.231199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.869407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.354702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:29:19.869484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.105905ms","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":"warn","ts":"2026-05-20T11:29:19.869494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.359728Z","time spent":"509.654226ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:29:19.869511Z","caller":"traceutil/trace.go:171","msg":"trace[792158802] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"505.525517ms","start":"2026-05-20T11:29:19.363948Z","end":"2026-05-20T11:29:19.869474Z","steps":["trace[792158802] 'agreement among raft nodes before linearized reading' (duration: 505.307341ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:19.869529Z","caller":"traceutil/trace.go:171","msg":"trace[1314223274] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"464.202088ms","start":"2026-05-20T11:29:19.405318Z","end":"2026-05-20T11:29:19.869521Z","steps":["trace[1314223274] 'agreement among raft nodes before linearized reading' (duration: 464.140777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.869573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.405288Z","time spent":"464.27784ms","remote":"127.0.0.1:49260","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-20T11:29:19.869577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.363925Z","time spent":"505.6352ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-20T11:29:19.869609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.51872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:19.869775Z","caller":"traceutil/trace.go:171","msg":"trace[394577246] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"463.738805ms","start":"2026-05-20T11:29:19.405989Z","end":"2026-05-20T11:29:19.869728Z","steps":["trace[394577246] 'agreement among raft nodes before linearized reading' (duration: 463.442648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.869433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.364028Z","time spent":"505.394064ms","remote":"127.0.0.1:49246","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-20T11:29:19.869865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.405974Z","time spent":"463.877318ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-05-20T11:29:19.869837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.522064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:29:19.869924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.563407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:19.869958Z","caller":"traceutil/trace.go:171","msg":"trace[630561035] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"464.650229ms","start":"2026-05-20T11:29:19.4053Z","end":"2026-05-20T11:29:19.86995Z","steps":["trace[630561035] 'agreement among raft nodes before linearized reading' (duration: 464.596507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.86999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.405273Z","time spent":"464.709841ms","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-20T11:29:19.870136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.370624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:29:19.870146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.878565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:19.870191Z","caller":"traceutil/trace.go:171","msg":"trace[1468188902] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:627; }","duration":"318.444885ms","start":"2026-05-20T11:29:19.551731Z","end":"2026-05-20T11:29:19.870176Z","steps":["trace[1468188902] 'agreement among raft nodes before linearized reading' (duration: 318.343443ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:19.870204Z","caller":"traceutil/trace.go:171","msg":"trace[106549621] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:627; }","duration":"380.028559ms","start":"2026-05-20T11:29:19.490157Z","end":"2026-05-20T11:29:19.870185Z","steps":["trace[106549621] 'agreement among raft nodes before linearized reading' (duration: 379.935446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.870315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.490133Z","time spent":"380.166453ms","remote":"127.0.0.1:49272","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-20T11:29:19.869791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.523029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:29:19.870366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.175088ms","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-20T11:29:19.870416Z","caller":"traceutil/trace.go:171","msg":"trace[156642478] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:627; }","duration":"409.233889ms","start":"2026-05-20T11:29:19.461165Z","end":"2026-05-20T11:29:19.870399Z","steps":["trace[156642478] 'agreement among raft nodes before linearized reading' (duration: 409.159657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.870462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.461162Z","time spent":"409.28595ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:29:19.870467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.579698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fxzp7\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-20T11:29:19.87027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.551722Z","time spent":"318.533797ms","remote":"127.0.0.1:49296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-05-20T11:29:19.870503Z","caller":"traceutil/trace.go:171","msg":"trace[1046795896] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fxzp7; range_end:; response_count:1; response_revision:627; }","duration":"307.64871ms","start":"2026-05-20T11:29:19.562844Z","end":"2026-05-20T11:29:19.870493Z","steps":["trace[1046795896] 'agreement among raft nodes before linearized reading' (duration: 307.463226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.870496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.634707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:29:19.870529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.562835Z","time spent":"307.688981ms","remote":"127.0.0.1:44344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-fxzp7\" "} {"level":"info","ts":"2026-05-20T11:29:19.870525Z","caller":"traceutil/trace.go:171","msg":"trace[1092206515] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:627; }","duration":"408.373555ms","start":"2026-05-20T11:29:19.462125Z","end":"2026-05-20T11:29:19.870499Z","steps":["trace[1092206515] 'agreement among raft nodes before linearized reading' (duration: 407.276647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.870943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.532123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:29:19.870964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.700374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:19.87099Z","caller":"traceutil/trace.go:171","msg":"trace[1183099169] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:627; }","duration":"355.588014ms","start":"2026-05-20T11:29:19.515391Z","end":"2026-05-20T11:29:19.870979Z","steps":["trace[1183099169] 'agreement among raft nodes before linearized reading' (duration: 355.527453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.871023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.515382Z","time spent":"355.632345ms","remote":"127.0.0.1:49280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-20T11:29:19.871023Z","caller":"traceutil/trace.go:171","msg":"trace[1440502080] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:627; }","duration":"334.800376ms","start":"2026-05-20T11:29:19.536207Z","end":"2026-05-20T11:29:19.871007Z","steps":["trace[1440502080] 'agreement among raft nodes before linearized reading' (duration: 334.708744ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:19.87054Z","caller":"traceutil/trace.go:171","msg":"trace[1614201376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"360.721639ms","start":"2026-05-20T11:29:19.509807Z","end":"2026-05-20T11:29:19.870529Z","steps":["trace[1614201376] 'agreement among raft nodes before linearized reading' (duration: 360.635517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.871076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.536184Z","time spent":"334.876717ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:29:19.871131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.509792Z","time spent":"361.322796ms","remote":"127.0.0.1:44196","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-20T11:29:19.870705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.562257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:19.871244Z","caller":"traceutil/trace.go:171","msg":"trace[1159308794] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:627; }","duration":"410.134833ms","start":"2026-05-20T11:29:19.461096Z","end":"2026-05-20T11:29:19.87123Z","steps":["trace[1159308794] 'agreement among raft nodes before linearized reading' (duration: 409.569808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.874833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.461074Z","time spent":"413.736937ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-20T11:29:19.871331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.143579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:29:19.87101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.462118Z","time spent":"408.869099ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"info","ts":"2026-05-20T11:29:19.876784Z","caller":"traceutil/trace.go:171","msg":"trace[868804139] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:627; }","duration":"361.612962ms","start":"2026-05-20T11:29:19.515148Z","end":"2026-05-20T11:29:19.876761Z","steps":["trace[868804139] 'agreement among raft nodes before linearized reading' (duration: 354.167626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.876851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.51514Z","time spent":"361.691944ms","remote":"127.0.0.1:49280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-05-20T11:29:19.87074Z","caller":"traceutil/trace.go:171","msg":"trace[127339492] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:627; }","duration":"379.197247ms","start":"2026-05-20T11:29:19.491178Z","end":"2026-05-20T11:29:19.870375Z","steps":["trace[127339492] 'agreement among raft nodes before linearized reading' (duration: 378.271323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.877236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.491167Z","time spent":"385.994586ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-05-20T11:29:19.870807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.64211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:19.877554Z","caller":"traceutil/trace.go:171","msg":"trace[1053567806] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:627; }","duration":"325.41766ms","start":"2026-05-20T11:29:19.552109Z","end":"2026-05-20T11:29:19.877527Z","steps":["trace[1053567806] 'agreement among raft nodes before linearized reading' (duration: 318.63071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.877597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.552096Z","time spent":"325.490301ms","remote":"127.0.0.1:49296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:29:19.87086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.526799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:19.877949Z","caller":"traceutil/trace.go:171","msg":"trace[27991663] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:627; }","duration":"341.628736ms","start":"2026-05-20T11:29:19.536309Z","end":"2026-05-20T11:29:19.877937Z","steps":["trace[27991663] 'agreement among raft nodes before linearized reading' (duration: 334.514579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.878017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.536304Z","time spent":"341.697118ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-20T11:29:19.87086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.607044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:29:19.870926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.228582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:19.879034Z","caller":"traceutil/trace.go:171","msg":"trace[1065771274] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:627; }","duration":"342.339735ms","start":"2026-05-20T11:29:19.536684Z","end":"2026-05-20T11:29:19.879024Z","steps":["trace[1065771274] 'agreement among raft nodes before linearized reading' (duration: 334.214511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.879214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.53666Z","time spent":"342.512401ms","remote":"127.0.0.1:49280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-05-20T11:29:19.870718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.453565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:19.879422Z","caller":"traceutil/trace.go:171","msg":"trace[658449837] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:627; }","duration":"327.156184ms","start":"2026-05-20T11:29:19.552249Z","end":"2026-05-20T11:29:19.879405Z","steps":["trace[658449837] 'agreement among raft nodes before linearized reading' (duration: 318.443944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.879484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.552246Z","time spent":"327.224546ms","remote":"127.0.0.1:49296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-05-20T11:29:19.878981Z","caller":"traceutil/trace.go:171","msg":"trace[1783519253] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:627; }","duration":"388.725407ms","start":"2026-05-20T11:29:19.49024Z","end":"2026-05-20T11:29:19.878965Z","steps":["trace[1783519253] 'agreement among raft nodes before linearized reading' (duration: 380.584734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:19.881038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.490133Z","time spent":"390.851564ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-05-20T11:29:20.08558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.469077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:20.085698Z","caller":"traceutil/trace.go:171","msg":"trace[1316022420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"205.633521ms","start":"2026-05-20T11:29:19.880034Z","end":"2026-05-20T11:29:20.085667Z","steps":["trace[1316022420] 'range keys from in-memory index tree' (duration: 205.1936ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:20.166752Z","caller":"traceutil/trace.go:171","msg":"trace[41594023] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"267.921962ms","start":"2026-05-20T11:29:19.898796Z","end":"2026-05-20T11:29:20.166718Z","steps":["trace[41594023] 'process raft request' (duration: 267.655996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:20.663762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.100273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:20.663925Z","caller":"traceutil/trace.go:171","msg":"trace[2146283198] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"715.565875ms","start":"2026-05-20T11:29:19.948342Z","end":"2026-05-20T11:29:20.663907Z","steps":["trace[2146283198] 'process raft request' (duration: 586.124543ms)","trace[2146283198] 'compare' (duration: 128.9506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:20.663999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:19.948312Z","time spent":"715.645796ms","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T11:29:21.178721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258049371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:29:21.679314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258049371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:29:22.180019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258049371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:29:22.681286Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258049371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:29:22.684649Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.004262257s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-20T11:29:23.08599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.069373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:23.086099Z","caller":"traceutil/trace.go:171","msg":"trace[559591626] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"2.40795284s","start":"2026-05-20T11:29:20.678128Z","end":"2026-05-20T11:29:23.086081Z","steps":["trace[559591626] 'read index received' (duration: 2.0066449s)","trace[559591626] 'applied index is now lower than readState.Index' (duration: 401.30668ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:23.086368Z","caller":"traceutil/trace.go:171","msg":"trace[1074539466] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"2.41591458s","start":"2026-05-20T11:29:20.670444Z","end":"2026-05-20T11:29:23.086359Z","steps":["trace[1074539466] 'process raft request' (duration: 2.014382434s)","trace[1074539466] 'compare' (duration: 400.95813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:23.086445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:20.670431Z","time spent":"2.415967381s","remote":"127.0.0.1:44416","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-20T11:29:23.0867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.408568996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:23.08673Z","caller":"traceutil/trace.go:171","msg":"trace[775058752] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:631; }","duration":"2.408610547s","start":"2026-05-20T11:29:20.678108Z","end":"2026-05-20T11:29:23.086719Z","steps":["trace[775058752] 'agreement among raft nodes before linearized reading' (duration: 2.408562695s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:23.087092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:20.6781Z","time spent":"2.408981717s","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-20T11:29:23.181245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.240397542s","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-20T11:29:23.181289Z","caller":"traceutil/trace.go:171","msg":"trace[1212832084] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"293.791336ms","start":"2026-05-20T11:29:22.887453Z","end":"2026-05-20T11:29:23.181245Z","steps":["trace[1212832084] 'process raft request' (duration: 293.362495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:23.181319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.274266ms","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-20T11:29:23.181431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675524581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:23.18143Z","caller":"traceutil/trace.go:171","msg":"trace[302520341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"498.46395ms","start":"2026-05-20T11:29:22.682937Z","end":"2026-05-20T11:29:23.181401Z","steps":["trace[302520341] 'agreement among raft nodes before linearized reading' (duration: 498.105332ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:23.181469Z","caller":"traceutil/trace.go:171","msg":"trace[1937876476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.675611833s","start":"2026-05-20T11:29:21.505846Z","end":"2026-05-20T11:29:23.181458Z","steps":["trace[1937876476] 'agreement among raft nodes before linearized reading' (duration: 1.675549131s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:23.181491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:22.682912Z","time spent":"498.566323ms","remote":"127.0.0.1:44416","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-20T11:29:23.181519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:21.50579Z","time spent":"1.675712007s","remote":"127.0.0.1:44184","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-20T11:29:23.181626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.234314303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:29:23.181362Z","caller":"traceutil/trace.go:171","msg":"trace[1117380763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"2.240577977s","start":"2026-05-20T11:29:20.940762Z","end":"2026-05-20T11:29:23.18134Z","steps":["trace[1117380763] 'agreement among raft nodes before linearized reading' (duration: 2.240258389s)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:23.181673Z","caller":"traceutil/trace.go:171","msg":"trace[1115101028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"2.234377425s","start":"2026-05-20T11:29:20.94728Z","end":"2026-05-20T11:29:23.181657Z","steps":["trace[1115101028] 'agreement among raft nodes before linearized reading' (duration: 2.234234141s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:23.181702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:20.940739Z","time spent":"2.240950728s","remote":"127.0.0.1:44416","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-20T11:29:23.181721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:20.947268Z","time spent":"2.234441806s","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-20T11:29:23.476933Z","caller":"traceutil/trace.go:171","msg":"trace[1173863739] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"291.634727ms","start":"2026-05-20T11:29:23.185273Z","end":"2026-05-20T11:29:23.476907Z","steps":["trace[1173863739] 'process raft request' (duration: 291.017292ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:23.477197Z","caller":"traceutil/trace.go:171","msg":"trace[737580917] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:659; }","duration":"286.728149ms","start":"2026-05-20T11:29:23.190454Z","end":"2026-05-20T11:29:23.477182Z","steps":["trace[737580917] 'read index received' (duration: 285.856417ms)","trace[737580917] 'applied index is now lower than readState.Index' (duration: 871.112µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:23.477332Z","caller":"traceutil/trace.go:171","msg":"trace[107728796] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"291.945386ms","start":"2026-05-20T11:29:23.185347Z","end":"2026-05-20T11:29:23.477292Z","steps":["trace[107728796] 'process raft request' (duration: 291.739361ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:23.477423Z","caller":"traceutil/trace.go:171","msg":"trace[1874399907] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"290.841977ms","start":"2026-05-20T11:29:23.186572Z","end":"2026-05-20T11:29:23.477414Z","steps":["trace[1874399907] 'process raft request' (duration: 290.57694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:23.478242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.728085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:23.4783Z","caller":"traceutil/trace.go:171","msg":"trace[1979422495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"287.861759ms","start":"2026-05-20T11:29:23.190427Z","end":"2026-05-20T11:29:23.478288Z","steps":["trace[1979422495] 'agreement among raft nodes before linearized reading' (duration: 286.815902ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:28.859819Z","caller":"traceutil/trace.go:171","msg":"trace[967142467] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"111.919571ms","start":"2026-05-20T11:29:28.747866Z","end":"2026-05-20T11:29:28.859785Z","steps":["trace[967142467] 'read index received' (duration: 64.027238ms)","trace[967142467] 'applied index is now lower than readState.Index' (duration: 47.891283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:28.860203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.310741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-20T11:29:28.86033Z","caller":"traceutil/trace.go:171","msg":"trace[434709672] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:716; }","duration":"112.493695ms","start":"2026-05-20T11:29:28.747809Z","end":"2026-05-20T11:29:28.860303Z","steps":["trace[434709672] 'agreement among raft nodes before linearized reading' (duration: 112.174808ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:28.860564Z","caller":"traceutil/trace.go:171","msg":"trace[1972638942] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"115.491655ms","start":"2026-05-20T11:29:28.745059Z","end":"2026-05-20T11:29:28.86055Z","steps":["trace[1972638942] 'process raft request' (duration: 66.928695ms)","trace[1972638942] 'compare' (duration: 47.574444ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:29.049601Z","caller":"traceutil/trace.go:171","msg":"trace[890485888] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"106.852486ms","start":"2026-05-20T11:29:28.942718Z","end":"2026-05-20T11:29:29.049571Z","steps":["trace[890485888] 'process raft request' (duration: 104.063652ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:29.093844Z","caller":"traceutil/trace.go:171","msg":"trace[662444180] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"119.08165ms","start":"2026-05-20T11:29:28.974729Z","end":"2026-05-20T11:29:29.09381Z","steps":["trace[662444180] 'process raft request' (duration: 118.883154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:35.588149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.567523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:35.588418Z","caller":"traceutil/trace.go:171","msg":"trace[455452201] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"307.332713ms","start":"2026-05-20T11:29:35.281048Z","end":"2026-05-20T11:29:35.588381Z","steps":["trace[455452201] 'process raft request' (duration: 162.34535ms)","trace[455452201] 'compare' (duration: 144.291285ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:35.5886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:35.281017Z","time spent":"307.485557ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:29:35.849953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.250545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:35.850269Z","caller":"traceutil/trace.go:171","msg":"trace[1317520396] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"256.615665ms","start":"2026-05-20T11:29:35.593578Z","end":"2026-05-20T11:29:35.850194Z","steps":["trace[1317520396] 'process raft request' (duration: 139.96554ms)","trace[1317520396] 'compare' (duration: 116.011628ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:35.850383Z","caller":"traceutil/trace.go:171","msg":"trace[1973292335] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"256.581284ms","start":"2026-05-20T11:29:35.593768Z","end":"2026-05-20T11:29:35.850349Z","steps":["trace[1973292335] 'process raft request' (duration: 256.338818ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:35.850449Z","caller":"traceutil/trace.go:171","msg":"trace[741378141] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"255.888357ms","start":"2026-05-20T11:29:35.594547Z","end":"2026-05-20T11:29:35.850436Z","steps":["trace[741378141] 'process raft request' (duration: 255.754573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:35.850773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.071682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-hrrpk\" ","response":"range_response_count:1 size:4741"} {"level":"info","ts":"2026-05-20T11:29:35.850893Z","caller":"traceutil/trace.go:171","msg":"trace[1473840823] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-hrrpk; range_end:; response_count:1; response_revision:772; }","duration":"198.233217ms","start":"2026-05-20T11:29:35.652629Z","end":"2026-05-20T11:29:35.850862Z","steps":["trace[1473840823] 'agreement among raft nodes before linearized reading' (duration: 197.897318ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:35.850383Z","caller":"traceutil/trace.go:171","msg":"trace[804461484] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:801; }","duration":"197.693491ms","start":"2026-05-20T11:29:35.652674Z","end":"2026-05-20T11:29:35.850367Z","steps":["trace[804461484] 'read index received' (duration: 80.888902ms)","trace[804461484] 'applied index is now lower than readState.Index' (duration: 116.803469ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:36.112937Z","caller":"traceutil/trace.go:171","msg":"trace[1763656101] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"253.954664ms","start":"2026-05-20T11:29:35.858953Z","end":"2026-05-20T11:29:36.112907Z","steps":["trace[1763656101] 'process raft request' (duration: 253.740679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:36.409542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.740064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:36.40967Z","caller":"traceutil/trace.go:171","msg":"trace[930867280] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"290.096648ms","start":"2026-05-20T11:29:36.119553Z","end":"2026-05-20T11:29:36.40965Z","steps":["trace[930867280] 'process raft request' (duration: 106.143958ms)","trace[930867280] 'compare' (duration: 183.439807ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:36.434911Z","caller":"traceutil/trace.go:171","msg":"trace[1062465584] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"314.608265ms","start":"2026-05-20T11:29:36.120273Z","end":"2026-05-20T11:29:36.434881Z","steps":["trace[1062465584] 'process raft request' (duration: 314.339779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:36.435093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:36.120253Z","time spent":"314.749589ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:29:36.435112Z","caller":"traceutil/trace.go:171","msg":"trace[311024072] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"310.335663ms","start":"2026-05-20T11:29:36.124722Z","end":"2026-05-20T11:29:36.435058Z","steps":["trace[311024072] 'process raft request' (duration: 310.084495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:36.435308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:36.124698Z","time spent":"310.511047ms","remote":"127.0.0.1:44344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4854,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:29:36.504304Z","caller":"traceutil/trace.go:171","msg":"trace[1889648429] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"172.92095ms","start":"2026-05-20T11:29:36.331315Z","end":"2026-05-20T11:29:36.504236Z","steps":["trace[1889648429] 'process raft request' (duration: 172.509249ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:36.571961Z","caller":"traceutil/trace.go:171","msg":"trace[928856312] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"131.197088ms","start":"2026-05-20T11:29:36.440687Z","end":"2026-05-20T11:29:36.571884Z","steps":["trace[928856312] 'process raft request' (duration: 129.39439ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:36.57199Z","caller":"traceutil/trace.go:171","msg":"trace[1616688065] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"129.490244ms","start":"2026-05-20T11:29:36.442469Z","end":"2026-05-20T11:29:36.571959Z","steps":["trace[1616688065] 'process raft request' (duration: 129.284859ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:36.572315Z","caller":"traceutil/trace.go:171","msg":"trace[1986182829] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"125.385015ms","start":"2026-05-20T11:29:36.446898Z","end":"2026-05-20T11:29:36.572283Z","steps":["trace[1986182829] 'process raft request' (duration: 124.933773ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:36.747041Z","caller":"traceutil/trace.go:171","msg":"trace[1888205785] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"164.921788ms","start":"2026-05-20T11:29:36.582079Z","end":"2026-05-20T11:29:36.747001Z","steps":["trace[1888205785] 'process raft request' (duration: 113.779279ms)","trace[1888205785] 'compare' (duration: 50.972945ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:40.062753Z","caller":"traceutil/trace.go:171","msg":"trace[142881318] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"178.771473ms","start":"2026-05-20T11:29:39.883945Z","end":"2026-05-20T11:29:40.062717Z","steps":["trace[142881318] 'process raft request' (duration: 178.549978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:40.354449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.88254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:40.355026Z","caller":"traceutil/trace.go:171","msg":"trace[661905686] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"468.938253ms","start":"2026-05-20T11:29:39.886056Z","end":"2026-05-20T11:29:40.354994Z","steps":["trace[661905686] 'process raft request' (duration: 468.847791ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:40.35507Z","caller":"traceutil/trace.go:171","msg":"trace[1344691069] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"470.931946ms","start":"2026-05-20T11:29:39.884092Z","end":"2026-05-20T11:29:40.355024Z","steps":["trace[1344691069] 'process raft request' (duration: 226.352277ms)","trace[1344691069] 'compare' (duration: 243.556591ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:40.355144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:39.886021Z","time spent":"469.078267ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:29:40.35528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:39.884077Z","time spent":"471.09404ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:29:41.615797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.51055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:41.616126Z","caller":"traceutil/trace.go:171","msg":"trace[85057312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"108.947423ms","start":"2026-05-20T11:29:41.507154Z","end":"2026-05-20T11:29:41.616101Z","steps":["trace[85057312] 'range keys from in-memory index tree' (duration: 108.379028ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:42.379667Z","caller":"traceutil/trace.go:171","msg":"trace[848574930] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"305.312789ms","start":"2026-05-20T11:29:42.07431Z","end":"2026-05-20T11:29:42.379623Z","steps":["trace[848574930] 'process raft request' (duration: 305.01829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:42.379948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:42.074287Z","time spent":"305.541645ms","remote":"127.0.0.1:44416","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-20T11:29:43.51796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062760288s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:43.518178Z","caller":"traceutil/trace.go:171","msg":"trace[1784868556] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.133168474s","start":"2026-05-20T11:29:42.384961Z","end":"2026-05-20T11:29:43.51813Z","steps":["trace[1784868556] 'process raft request' (duration: 69.824291ms)","trace[1784868556] 'compare' (duration: 1.062361357s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:43.518328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.069091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T11:29:43.518135Z","caller":"traceutil/trace.go:171","msg":"trace[1386329682] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"651.831815ms","start":"2026-05-20T11:29:42.866247Z","end":"2026-05-20T11:29:43.518079Z","steps":["trace[1386329682] 'read index received' (duration: 82.642µs)","trace[1386329682] 'applied index is now lower than readState.Index' (duration: 651.747483ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:43.518416Z","caller":"traceutil/trace.go:171","msg":"trace[390899062] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:796; }","duration":"652.236075ms","start":"2026-05-20T11:29:42.866161Z","end":"2026-05-20T11:29:43.518397Z","steps":["trace[390899062] 'agreement among raft nodes before linearized reading' (duration: 652.041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:43.518443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:42.384916Z","time spent":"1.13340973s","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:29:43.518463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:42.866135Z","time spent":"652.320078ms","remote":"127.0.0.1:44356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-20T11:29:43.914868Z","caller":"traceutil/trace.go:171","msg":"trace[1391071142] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"158.705184ms","start":"2026-05-20T11:29:43.756132Z","end":"2026-05-20T11:29:43.914837Z","steps":["trace[1391071142] 'process raft request' (duration: 158.504408ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:45.684511Z","caller":"traceutil/trace.go:171","msg":"trace[609687192] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"137.102295ms","start":"2026-05-20T11:29:45.547375Z","end":"2026-05-20T11:29:45.684478Z","steps":["trace[609687192] 'read index received' (duration: 136.880919ms)","trace[609687192] 'applied index is now lower than readState.Index' (duration: 220.196µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:45.684638Z","caller":"traceutil/trace.go:171","msg":"trace[1201959024] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"155.615343ms","start":"2026-05-20T11:29:45.528984Z","end":"2026-05-20T11:29:45.684599Z","steps":["trace[1201959024] 'process raft request' (duration: 155.254154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:45.684857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.457725ms","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-20T11:29:45.684906Z","caller":"traceutil/trace.go:171","msg":"trace[1653802124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:803; }","duration":"137.616539ms","start":"2026-05-20T11:29:45.547277Z","end":"2026-05-20T11:29:45.684894Z","steps":["trace[1653802124] 'agreement among raft nodes before linearized reading' (duration: 137.330371ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:45.896538Z","caller":"traceutil/trace.go:171","msg":"trace[342360849] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"123.937817ms","start":"2026-05-20T11:29:45.772556Z","end":"2026-05-20T11:29:45.896494Z","steps":["trace[342360849] 'read index received' (duration: 120.151198ms)","trace[342360849] 'applied index is now lower than readState.Index' (duration: 3.785459ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:45.896582Z","caller":"traceutil/trace.go:171","msg":"trace[1887681149] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"207.019198ms","start":"2026-05-20T11:29:45.689512Z","end":"2026-05-20T11:29:45.896531Z","steps":["trace[1887681149] 'process raft request' (duration: 203.362522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:45.896922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.345638ms","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-20T11:29:45.897032Z","caller":"traceutil/trace.go:171","msg":"trace[1163025215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"124.522103ms","start":"2026-05-20T11:29:45.772489Z","end":"2026-05-20T11:29:45.897011Z","steps":["trace[1163025215] 'agreement among raft nodes before linearized reading' (duration: 124.126822ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:46.609283Z","caller":"traceutil/trace.go:171","msg":"trace[768027471] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"189.226628ms","start":"2026-05-20T11:29:46.420013Z","end":"2026-05-20T11:29:46.60924Z","steps":["trace[768027471] 'process raft request' (duration: 188.9131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:46.609525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.607194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:29:46.609583Z","caller":"traceutil/trace.go:171","msg":"trace[135697415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"182.730577ms","start":"2026-05-20T11:29:46.426838Z","end":"2026-05-20T11:29:46.609569Z","steps":["trace[135697415] 'agreement among raft nodes before linearized reading' (duration: 182.49519ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:46.609209Z","caller":"traceutil/trace.go:171","msg":"trace[38633] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"182.253175ms","start":"2026-05-20T11:29:46.426886Z","end":"2026-05-20T11:29:46.609139Z","steps":["trace[38633] 'read index received' (duration: 182.042449ms)","trace[38633] 'applied index is now lower than readState.Index' (duration: 209.266µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:46.872926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.547831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T11:29:46.873039Z","caller":"traceutil/trace.go:171","msg":"trace[976351637] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:808; }","duration":"141.718986ms","start":"2026-05-20T11:29:46.73129Z","end":"2026-05-20T11:29:46.873009Z","steps":["trace[976351637] 'count revisions from in-memory index tree' (duration: 141.424859ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:47.22932Z","caller":"traceutil/trace.go:171","msg":"trace[544734804] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"183.775005ms","start":"2026-05-20T11:29:47.045502Z","end":"2026-05-20T11:29:47.229277Z","steps":["trace[544734804] 'process raft request' (duration: 183.457236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:47.631493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.473258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:47.63159Z","caller":"traceutil/trace.go:171","msg":"trace[1988843748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"125.643252ms","start":"2026-05-20T11:29:47.505922Z","end":"2026-05-20T11:29:47.631565Z","steps":["trace[1988843748] 'range keys from in-memory index tree' (duration: 125.344265ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:47.86423Z","caller":"traceutil/trace.go:171","msg":"trace[2146965799] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"170.512306ms","start":"2026-05-20T11:29:47.693688Z","end":"2026-05-20T11:29:47.8642Z","steps":["trace[2146965799] 'process raft request' (duration: 170.32182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:48.094176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.97038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:48.094362Z","caller":"traceutil/trace.go:171","msg":"trace[1595290046] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"136.998112ms","start":"2026-05-20T11:29:47.957339Z","end":"2026-05-20T11:29:48.094337Z","steps":["trace[1595290046] 'read index received' (duration: 56.882µs)","trace[1595290046] 'applied index is now lower than readState.Index' (duration: 136.93967ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:48.094419Z","caller":"traceutil/trace.go:171","msg":"trace[596735968] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"189.086806ms","start":"2026-05-20T11:29:47.905281Z","end":"2026-05-20T11:29:48.094368Z","steps":["trace[596735968] 'process raft request' (duration: 48.788127ms)","trace[596735968] 'compare' (duration: 139.748035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:48.094646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.30977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-20T11:29:48.094647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.595133ms","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-20T11:29:48.094741Z","caller":"traceutil/trace.go:171","msg":"trace[2070100115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:811; }","duration":"137.440364ms","start":"2026-05-20T11:29:47.95725Z","end":"2026-05-20T11:29:48.094691Z","steps":["trace[2070100115] 'agreement among raft nodes before linearized reading' (duration: 137.209808ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:48.094775Z","caller":"traceutil/trace.go:171","msg":"trace[875755997] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:811; }","duration":"100.772128ms","start":"2026-05-20T11:29:47.993969Z","end":"2026-05-20T11:29:48.094742Z","steps":["trace[875755997] 'agreement among raft nodes before linearized reading' (duration: 100.568913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:48.527546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.752988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:48.527678Z","caller":"traceutil/trace.go:171","msg":"trace[461772774] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"428.24035ms","start":"2026-05-20T11:29:48.099415Z","end":"2026-05-20T11:29:48.527655Z","steps":["trace[461772774] 'process raft request' (duration: 311.276877ms)","trace[461772774] 'compare' (duration: 116.245364ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:48.52775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:48.099388Z","time spent":"428.332932ms","remote":"127.0.0.1:44416","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-20T11:29:48.74673Z","caller":"traceutil/trace.go:171","msg":"trace[884499746] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"108.582923ms","start":"2026-05-20T11:29:48.638106Z","end":"2026-05-20T11:29:48.746689Z","steps":["trace[884499746] 'read index received' (duration: 40.304313ms)","trace[884499746] 'applied index is now lower than readState.Index' (duration: 68.27658ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:48.746907Z","caller":"traceutil/trace.go:171","msg":"trace[1657179144] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"126.487185ms","start":"2026-05-20T11:29:48.620367Z","end":"2026-05-20T11:29:48.746854Z","steps":["trace[1657179144] 'process raft request' (duration: 58.148303ms)","trace[1657179144] 'compare' (duration: 67.989663ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:48.747172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.941593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:29:48.74728Z","caller":"traceutil/trace.go:171","msg":"trace[175047327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"109.21653ms","start":"2026-05-20T11:29:48.638049Z","end":"2026-05-20T11:29:48.747265Z","steps":["trace[175047327] 'agreement among raft nodes before linearized reading' (duration: 108.8097ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:50.678925Z","caller":"traceutil/trace.go:171","msg":"trace[1509880840] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"141.263054ms","start":"2026-05-20T11:29:50.537624Z","end":"2026-05-20T11:29:50.678887Z","steps":["trace[1509880840] 'process raft request' (duration: 140.959857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:50.883492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.612923ms","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-20T11:29:50.883581Z","caller":"traceutil/trace.go:171","msg":"trace[692358921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:817; }","duration":"130.788347ms","start":"2026-05-20T11:29:50.752771Z","end":"2026-05-20T11:29:50.883559Z","steps":["trace[692358921] 'range keys from in-memory index tree' (duration: 129.971787ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:51.037678Z","caller":"traceutil/trace.go:171","msg":"trace[930506006] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"149.571654ms","start":"2026-05-20T11:29:50.88808Z","end":"2026-05-20T11:29:51.037652Z","steps":["trace[930506006] 'process raft request' (duration: 149.520393ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:51.037732Z","caller":"traceutil/trace.go:171","msg":"trace[1707049935] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"149.627135ms","start":"2026-05-20T11:29:50.888063Z","end":"2026-05-20T11:29:51.03769Z","steps":["trace[1707049935] 'process raft request' (duration: 149.335337ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:52.044122Z","caller":"traceutil/trace.go:171","msg":"trace[977877325] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"106.544348ms","start":"2026-05-20T11:29:51.937461Z","end":"2026-05-20T11:29:52.044006Z","steps":["trace[977877325] 'process raft request' (duration: 106.325593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:52.471202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.972638ms","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-20T11:29:52.471324Z","caller":"traceutil/trace.go:171","msg":"trace[1434896133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:820; }","duration":"333.148712ms","start":"2026-05-20T11:29:52.138155Z","end":"2026-05-20T11:29:52.471304Z","steps":["trace[1434896133] 'range keys from in-memory index tree' (duration: 332.684041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:52.471377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:52.138132Z","time spent":"333.230356ms","remote":"127.0.0.1:44416","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-20T11:29:53.224804Z","caller":"traceutil/trace.go:171","msg":"trace[527152994] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"177.404638ms","start":"2026-05-20T11:29:53.047367Z","end":"2026-05-20T11:29:53.224771Z","steps":["trace[527152994] 'process raft request' (duration: 177.143961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:53.672746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.317677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:53.673052Z","caller":"traceutil/trace.go:171","msg":"trace[1657963765] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"625.447671ms","start":"2026-05-20T11:29:53.047554Z","end":"2026-05-20T11:29:53.673002Z","steps":["trace[1657963765] 'process raft request' (duration: 288.757663ms)","trace[1657963765] 'compare' (duration: 335.706081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:53.673135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:53.047507Z","time spent":"625.586425ms","remote":"127.0.0.1:44416","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-20T11:29:54.082895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:53.716865Z","time spent":"366.020839ms","remote":"127.0.0.1:44204","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-20T11:29:54.294054Z","caller":"traceutil/trace.go:171","msg":"trace[356634194] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"143.727739ms","start":"2026-05-20T11:29:54.150297Z","end":"2026-05-20T11:29:54.294024Z","steps":["trace[356634194] 'read index received' (duration: 95.807606ms)","trace[356634194] 'applied index is now lower than readState.Index' (duration: 47.919143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:29:54.294271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.914123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-20T11:29:54.294304Z","caller":"traceutil/trace.go:171","msg":"trace[696152573] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:827; }","duration":"144.011656ms","start":"2026-05-20T11:29:54.150281Z","end":"2026-05-20T11:29:54.294293Z","steps":["trace[696152573] 'agreement among raft nodes before linearized reading' (duration: 143.856302ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:54.294141Z","caller":"traceutil/trace.go:171","msg":"trace[1520235806] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"194.879787ms","start":"2026-05-20T11:29:54.099219Z","end":"2026-05-20T11:29:54.294099Z","steps":["trace[1520235806] 'process raft request' (duration: 146.960634ms)","trace[1520235806] 'compare' (duration: 47.700937ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:55.322301Z","caller":"traceutil/trace.go:171","msg":"trace[706636357] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"407.526295ms","start":"2026-05-20T11:29:54.914676Z","end":"2026-05-20T11:29:55.322202Z","steps":["trace[706636357] 'process raft request' (duration: 407.293508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:55.322547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:29:54.914645Z","time spent":"407.768411ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:29:55.613014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.502965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:55.613122Z","caller":"traceutil/trace.go:171","msg":"trace[1529243514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:830; }","duration":"107.689039ms","start":"2026-05-20T11:29:55.505409Z","end":"2026-05-20T11:29:55.613098Z","steps":["trace[1529243514] 'range keys from in-memory index tree' (duration: 107.334999ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:56.479712Z","caller":"traceutil/trace.go:171","msg":"trace[49414246] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"174.706906ms","start":"2026-05-20T11:29:56.304974Z","end":"2026-05-20T11:29:56.479681Z","steps":["trace[49414246] 'process raft request' (duration: 174.46857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:56.648995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.295817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:29:56.64912Z","caller":"traceutil/trace.go:171","msg":"trace[645776689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"143.490523ms","start":"2026-05-20T11:29:56.505603Z","end":"2026-05-20T11:29:56.649093Z","steps":["trace[645776689] 'range keys from in-memory index tree' (duration: 143.194405ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:56.796088Z","caller":"traceutil/trace.go:171","msg":"trace[11088689] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"130.124191ms","start":"2026-05-20T11:29:56.665924Z","end":"2026-05-20T11:29:56.796048Z","steps":["trace[11088689] 'process raft request' (duration: 129.854533ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:29:57.59134Z","caller":"traceutil/trace.go:171","msg":"trace[1748113064] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"256.276977ms","start":"2026-05-20T11:29:57.335033Z","end":"2026-05-20T11:29:57.59131Z","steps":["trace[1748113064] 'read index received' (duration: 255.956578ms)","trace[1748113064] 'applied index is now lower than readState.Index' (duration: 319.259µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:57.591518Z","caller":"traceutil/trace.go:171","msg":"trace[1394624459] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"259.327047ms","start":"2026-05-20T11:29:57.332151Z","end":"2026-05-20T11:29:57.591478Z","steps":["trace[1394624459] 'process raft request' (duration: 258.927506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:57.591607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.536293ms","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-20T11:29:57.591643Z","caller":"traceutil/trace.go:171","msg":"trace[961144209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:835; }","duration":"256.617565ms","start":"2026-05-20T11:29:57.335014Z","end":"2026-05-20T11:29:57.591631Z","steps":["trace[961144209] 'agreement among raft nodes before linearized reading' (duration: 256.411939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:29:57.766695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.172005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:29:57.767261Z","caller":"traceutil/trace.go:171","msg":"trace[481823382] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"170.565737ms","start":"2026-05-20T11:29:57.596628Z","end":"2026-05-20T11:29:57.767194Z","steps":["trace[481823382] 'process raft request' (duration: 48.52903ms)","trace[481823382] 'compare' (duration: 120.956939ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:29:57.952489Z","caller":"traceutil/trace.go:171","msg":"trace[1071455106] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"180.395356ms","start":"2026-05-20T11:29:57.772049Z","end":"2026-05-20T11:29:57.952444Z","steps":["trace[1071455106] 'process raft request' (duration: 108.701466ms)","trace[1071455106] 'compare' (duration: 71.524075ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:02.19622Z","caller":"traceutil/trace.go:171","msg":"trace[479556213] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"179.625145ms","start":"2026-05-20T11:30:02.016562Z","end":"2026-05-20T11:30:02.196187Z","steps":["trace[479556213] 'process raft request' (duration: 179.43007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:02.37605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.404641ms","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-20T11:30:02.376155Z","caller":"traceutil/trace.go:171","msg":"trace[58813127] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:847; }","duration":"107.581205ms","start":"2026-05-20T11:30:02.268549Z","end":"2026-05-20T11:30:02.37613Z","steps":["trace[58813127] 'count revisions from in-memory index tree' (duration: 107.249217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:02.738403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.983846ms","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-20T11:30:02.738507Z","caller":"traceutil/trace.go:171","msg":"trace[872986303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:848; }","duration":"144.162131ms","start":"2026-05-20T11:30:02.594321Z","end":"2026-05-20T11:30:02.738483Z","steps":["trace[872986303] 'range keys from in-memory index tree' (duration: 143.743349ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:04.072761Z","caller":"traceutil/trace.go:171","msg":"trace[1913620998] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"166.181211ms","start":"2026-05-20T11:30:03.906539Z","end":"2026-05-20T11:30:04.07272Z","steps":["trace[1913620998] 'process raft request' (duration: 105.843834ms)","trace[1913620998] 'compare' (duration: 60.147401ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:04.269004Z","caller":"traceutil/trace.go:171","msg":"trace[25231604] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"186.916366ms","start":"2026-05-20T11:30:04.082037Z","end":"2026-05-20T11:30:04.268953Z","steps":["trace[25231604] 'process raft request' (duration: 183.403242ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:04.415178Z","caller":"traceutil/trace.go:171","msg":"trace[1691830951] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"139.009273ms","start":"2026-05-20T11:30:04.276133Z","end":"2026-05-20T11:30:04.415142Z","steps":["trace[1691830951] 'process raft request' (duration: 104.433937ms)","trace[1691830951] 'compare' (duration: 34.400481ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:04.653052Z","caller":"traceutil/trace.go:171","msg":"trace[1362055193] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"147.664235ms","start":"2026-05-20T11:30:04.505356Z","end":"2026-05-20T11:30:04.65302Z","steps":["trace[1362055193] 'read index received' (duration: 81.426631ms)","trace[1362055193] 'applied index is now lower than readState.Index' (duration: 66.236414ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:04.653099Z","caller":"traceutil/trace.go:171","msg":"trace[1928159638] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"226.656761ms","start":"2026-05-20T11:30:04.426413Z","end":"2026-05-20T11:30:04.65307Z","steps":["trace[1928159638] 'process raft request' (duration: 160.291113ms)","trace[1928159638] 'compare' (duration: 66.11538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:30:04.653295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.894231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:30:04.653421Z","caller":"traceutil/trace.go:171","msg":"trace[16506502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:863; }","duration":"148.088415ms","start":"2026-05-20T11:30:04.505293Z","end":"2026-05-20T11:30:04.653381Z","steps":["trace[16506502] 'agreement among raft nodes before linearized reading' (duration: 147.85675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:05.491749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258049908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:30:05.992983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258049908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:30:06.375765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589093956s","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-20T11:30:06.375893Z","caller":"traceutil/trace.go:171","msg":"trace[247440510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:863; }","duration":"1.589287841s","start":"2026-05-20T11:30:04.786577Z","end":"2026-05-20T11:30:06.375865Z","steps":["trace[247440510] 'range keys from in-memory index tree' (duration: 1.588783417s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:06.375952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:04.786551Z","time spent":"1.589385603s","remote":"127.0.0.1:44416","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-20T11:30:06.493818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258049908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:30:06.53999Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.72402288s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T11:30:06.54051Z","caller":"traceutil/trace.go:171","msg":"trace[1922873631] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"1.724760559s","start":"2026-05-20T11:30:04.815721Z","end":"2026-05-20T11:30:06.540481Z","steps":["trace[1922873631] 'process raft request' (duration: 1.724573204s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:06.54077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:04.815685Z","time spent":"1.724911533s","remote":"127.0.0.1:44384","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-20T11:30:06.541598Z","caller":"traceutil/trace.go:171","msg":"trace[1951877533] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:902; }","duration":"1.550146202s","start":"2026-05-20T11:30:04.991437Z","end":"2026-05-20T11:30:06.541583Z","steps":["trace[1951877533] 'read index received' (duration: 1.548842967s)","trace[1951877533] 'applied index is now lower than readState.Index' (duration: 1.301865ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:30:06.541774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.636412ms","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-20T11:30:06.541805Z","caller":"traceutil/trace.go:171","msg":"trace[906563295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:865; }","duration":"812.720044ms","start":"2026-05-20T11:30:05.729075Z","end":"2026-05-20T11:30:06.541795Z","steps":["trace[906563295] 'agreement among raft nodes before linearized reading' (duration: 812.600431ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:06.541773Z","caller":"traceutil/trace.go:171","msg":"trace[1624187631] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"156.516491ms","start":"2026-05-20T11:30:06.385219Z","end":"2026-05-20T11:30:06.541736Z","steps":["trace[1624187631] 'process raft request' (duration: 156.298636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:06.541827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:05.72905Z","time spent":"812.771846ms","remote":"127.0.0.1:44416","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-20T11:30:06.541999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.513301ms","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-20T11:30:06.541975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550473681s","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-20T11:30:06.542079Z","caller":"traceutil/trace.go:171","msg":"trace[1363083340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:865; }","duration":"1.550675337s","start":"2026-05-20T11:30:04.991374Z","end":"2026-05-20T11:30:06.54205Z","steps":["trace[1363083340] 'agreement among raft nodes before linearized reading' (duration: 1.550306677s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:06.542113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.545554ms","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-20T11:30:06.542145Z","caller":"traceutil/trace.go:171","msg":"trace[971227891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:865; }","duration":"171.627986ms","start":"2026-05-20T11:30:06.370507Z","end":"2026-05-20T11:30:06.542135Z","steps":["trace[971227891] 'agreement among raft nodes before linearized reading' (duration: 171.527624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:06.542143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:04.991353Z","time spent":"1.550778759s","remote":"127.0.0.1:44416","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-20T11:30:06.542108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.734502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T11:30:06.54202Z","caller":"traceutil/trace.go:171","msg":"trace[1987865348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:865; }","duration":"613.585982ms","start":"2026-05-20T11:30:05.928429Z","end":"2026-05-20T11:30:06.542015Z","steps":["trace[1987865348] 'agreement among raft nodes before linearized reading' (duration: 613.525781ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:06.542245Z","caller":"traceutil/trace.go:171","msg":"trace[718750420] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:865; }","duration":"437.920158ms","start":"2026-05-20T11:30:06.104277Z","end":"2026-05-20T11:30:06.542198Z","steps":["trace[718750420] 'agreement among raft nodes before linearized reading' (duration: 437.665691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:06.542264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:05.9284Z","time spent":"613.857619ms","remote":"127.0.0.1:44416","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-20T11:30:06.542302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:06.10425Z","time spent":"438.04095ms","remote":"127.0.0.1:44372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:30:10.047365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.786039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:30:10.047618Z","caller":"traceutil/trace.go:171","msg":"trace[145029149] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"158.890076ms","start":"2026-05-20T11:30:09.888703Z","end":"2026-05-20T11:30:10.047593Z","steps":["trace[145029149] 'read index received' (duration: 51.745486ms)","trace[145029149] 'applied index is now lower than readState.Index' (duration: 107.14244ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:10.047669Z","caller":"traceutil/trace.go:171","msg":"trace[1376285639] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"214.32615ms","start":"2026-05-20T11:30:09.83331Z","end":"2026-05-20T11:30:10.047636Z","steps":["trace[1376285639] 'process raft request' (duration: 107.126719ms)","trace[1376285639] 'compare' (duration: 106.555494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:30:10.048124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.40877ms","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-20T11:30:10.048174Z","caller":"traceutil/trace.go:171","msg":"trace[418208690] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:881; }","duration":"159.514782ms","start":"2026-05-20T11:30:09.888647Z","end":"2026-05-20T11:30:10.048162Z","steps":["trace[418208690] 'agreement among raft nodes before linearized reading' (duration: 159.306137ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:10.262695Z","caller":"traceutil/trace.go:171","msg":"trace[1530146807] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"207.769555ms","start":"2026-05-20T11:30:10.054903Z","end":"2026-05-20T11:30:10.262672Z","steps":["trace[1530146807] 'process raft request' (duration: 164.717552ms)","trace[1530146807] 'compare' (duration: 42.926979ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:10.387533Z","caller":"traceutil/trace.go:171","msg":"trace[1593798302] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"112.3608ms","start":"2026-05-20T11:30:10.275135Z","end":"2026-05-20T11:30:10.387496Z","steps":["trace[1593798302] 'process raft request' (duration: 90.612867ms)","trace[1593798302] 'compare' (duration: 21.586898ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:10.698321Z","caller":"traceutil/trace.go:171","msg":"trace[1486268042] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"126.95508ms","start":"2026-05-20T11:30:10.571321Z","end":"2026-05-20T11:30:10.698276Z","steps":["trace[1486268042] 'process raft request' (duration: 43.402853ms)","trace[1486268042] 'compare' (duration: 83.359902ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:10.759409Z","caller":"traceutil/trace.go:171","msg":"trace[2070028015] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"187.287216ms","start":"2026-05-20T11:30:10.572062Z","end":"2026-05-20T11:30:10.759349Z","steps":["trace[2070028015] 'process raft request' (duration: 186.956097ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:10.75953Z","caller":"traceutil/trace.go:171","msg":"trace[1403035318] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"186.827333ms","start":"2026-05-20T11:30:10.572671Z","end":"2026-05-20T11:30:10.759498Z","steps":["trace[1403035318] 'process raft request' (duration: 186.773162ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:10.759638Z","caller":"traceutil/trace.go:171","msg":"trace[1140687865] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"187.039079ms","start":"2026-05-20T11:30:10.572424Z","end":"2026-05-20T11:30:10.759463Z","steps":["trace[1140687865] 'process raft request' (duration: 186.762961ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:10.759867Z","caller":"traceutil/trace.go:171","msg":"trace[1344971680] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"187.358807ms","start":"2026-05-20T11:30:10.572473Z","end":"2026-05-20T11:30:10.759832Z","steps":["trace[1344971680] 'process raft request' (duration: 186.866784ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:10.760023Z","caller":"traceutil/trace.go:171","msg":"trace[2085942982] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"187.43689ms","start":"2026-05-20T11:30:10.572569Z","end":"2026-05-20T11:30:10.760006Z","steps":["trace[2085942982] 'process raft request' (duration: 186.824154ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:10.760059Z","caller":"traceutil/trace.go:171","msg":"trace[197436974] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"187.585213ms","start":"2026-05-20T11:30:10.572467Z","end":"2026-05-20T11:30:10.760052Z","steps":["trace[197436974] 'process raft request' (duration: 186.835393ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:10.896216Z","caller":"traceutil/trace.go:171","msg":"trace[73318088] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"131.09642ms","start":"2026-05-20T11:30:10.765084Z","end":"2026-05-20T11:30:10.896181Z","steps":["trace[73318088] 'process raft request' (duration: 131.033359ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:10.896623Z","caller":"traceutil/trace.go:171","msg":"trace[560567766] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"132.117778ms","start":"2026-05-20T11:30:10.764454Z","end":"2026-05-20T11:30:10.896572Z","steps":["trace[560567766] 'process raft request' (duration: 94.795498ms)","trace[560567766] 'compare' (duration: 36.628082ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:11.186991Z","caller":"traceutil/trace.go:171","msg":"trace[1981229294] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"251.29396ms","start":"2026-05-20T11:30:10.935656Z","end":"2026-05-20T11:30:11.18695Z","steps":["trace[1981229294] 'process raft request' (duration: 227.611907ms)","trace[1981229294] 'compare' (duration: 23.464558ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:11.33687Z","caller":"traceutil/trace.go:171","msg":"trace[138078649] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"142.480506ms","start":"2026-05-20T11:30:11.194349Z","end":"2026-05-20T11:30:11.336829Z","steps":["trace[138078649] 'process raft request' (duration: 94.10436ms)","trace[138078649] 'compare' (duration: 48.201421ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:11.387289Z","caller":"traceutil/trace.go:171","msg":"trace[404968636] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"192.892417ms","start":"2026-05-20T11:30:11.194369Z","end":"2026-05-20T11:30:11.387261Z","steps":["trace[404968636] 'process raft request' (duration: 192.824854ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:11.387324Z","caller":"traceutil/trace.go:171","msg":"trace[652610007] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"192.925267ms","start":"2026-05-20T11:30:11.194369Z","end":"2026-05-20T11:30:11.387294Z","steps":["trace[652610007] 'process raft request' (duration: 192.67945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:11.69683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.069011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:30:11.697041Z","caller":"traceutil/trace.go:171","msg":"trace[865226077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"190.363849ms","start":"2026-05-20T11:30:11.506648Z","end":"2026-05-20T11:30:11.697012Z","steps":["trace[865226077] 'agreement among raft nodes before linearized reading' (duration: 76.098379ms)","trace[865226077] 'range keys from in-memory index tree' (duration: 113.984793ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:11.7378Z","caller":"traceutil/trace.go:171","msg":"trace[2099300054] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"146.066131ms","start":"2026-05-20T11:30:11.591702Z","end":"2026-05-20T11:30:11.737768Z","steps":["trace[2099300054] 'read index received' (duration: 145.765533ms)","trace[2099300054] 'applied index is now lower than readState.Index' (duration: 299.088µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:11.738036Z","caller":"traceutil/trace.go:171","msg":"trace[1884583945] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"152.411742ms","start":"2026-05-20T11:30:11.585598Z","end":"2026-05-20T11:30:11.73801Z","steps":["trace[1884583945] 'process raft request' (duration: 151.962499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:11.738793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.078199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-05-20T11:30:11.738893Z","caller":"traceutil/trace.go:171","msg":"trace[528826393] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:905; }","duration":"147.227654ms","start":"2026-05-20T11:30:11.591644Z","end":"2026-05-20T11:30:11.738871Z","steps":["trace[528826393] 'agreement among raft nodes before linearized reading' (duration: 146.881814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:11.974456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.707891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:30:11.974583Z","caller":"traceutil/trace.go:171","msg":"trace[1855878719] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"228.156639ms","start":"2026-05-20T11:30:11.746407Z","end":"2026-05-20T11:30:11.974563Z","steps":["trace[1855878719] 'process raft request' (duration: 88.257494ms)","trace[1855878719] 'compare' (duration: 139.546886ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:12.015619Z","caller":"traceutil/trace.go:171","msg":"trace[368760753] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"268.236743ms","start":"2026-05-20T11:30:11.747354Z","end":"2026-05-20T11:30:12.015591Z","steps":["trace[368760753] 'process raft request' (duration: 268.135791ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:12.015628Z","caller":"traceutil/trace.go:171","msg":"trace[505179804] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"269.141937ms","start":"2026-05-20T11:30:11.746445Z","end":"2026-05-20T11:30:12.015587Z","steps":["trace[505179804] 'process raft request' (duration: 268.827599ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:12.01616Z","caller":"traceutil/trace.go:171","msg":"trace[1955595161] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"265.328245ms","start":"2026-05-20T11:30:11.750817Z","end":"2026-05-20T11:30:12.016145Z","steps":["trace[1955595161] 'process raft request' (duration: 264.720199ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:12.019781Z","caller":"traceutil/trace.go:171","msg":"trace[1047831412] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"218.505222ms","start":"2026-05-20T11:30:11.801241Z","end":"2026-05-20T11:30:12.019746Z","steps":["trace[1047831412] 'process raft request' (duration: 216.218781ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:12.274136Z","caller":"traceutil/trace.go:171","msg":"trace[1055434502] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"234.291833ms","start":"2026-05-20T11:30:12.039821Z","end":"2026-05-20T11:30:12.274113Z","steps":["trace[1055434502] 'process raft request' (duration: 187.633245ms)","trace[1055434502] 'compare' (duration: 45.998311ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:12.274401Z","caller":"traceutil/trace.go:171","msg":"trace[1183553549] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"227.788641ms","start":"2026-05-20T11:30:12.046564Z","end":"2026-05-20T11:30:12.274352Z","steps":["trace[1183553549] 'process raft request' (duration: 227.41831ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:12.274096Z","caller":"traceutil/trace.go:171","msg":"trace[1102956183] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:951; }","duration":"140.699358ms","start":"2026-05-20T11:30:12.133362Z","end":"2026-05-20T11:30:12.274061Z","steps":["trace[1102956183] 'read index received' (duration: 94.107871ms)","trace[1102956183] 'applied index is now lower than readState.Index' (duration: 46.588887ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:30:12.275992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.603729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050256"} {"level":"info","ts":"2026-05-20T11:30:12.276828Z","caller":"traceutil/trace.go:171","msg":"trace[216127730] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:913; }","duration":"143.38764ms","start":"2026-05-20T11:30:12.133306Z","end":"2026-05-20T11:30:12.276693Z","steps":["trace[216127730] 'agreement among raft nodes before linearized reading' (duration: 141.247343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:12.492924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.846929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:30:12.493317Z","caller":"traceutil/trace.go:171","msg":"trace[200205518] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"206.625054ms","start":"2026-05-20T11:30:12.286655Z","end":"2026-05-20T11:30:12.49328Z","steps":["trace[200205518] 'process raft request' (duration: 95.271212ms)","trace[200205518] 'compare' (duration: 110.654433ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:12.929326Z","caller":"traceutil/trace.go:171","msg":"trace[1030163692] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"164.546986ms","start":"2026-05-20T11:30:12.764744Z","end":"2026-05-20T11:30:12.929291Z","steps":["trace[1030163692] 'read index received' (duration: 105.786582ms)","trace[1030163692] 'applied index is now lower than readState.Index' (duration: 58.758834ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:12.929528Z","caller":"traceutil/trace.go:171","msg":"trace[526501979] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"184.315065ms","start":"2026-05-20T11:30:12.745192Z","end":"2026-05-20T11:30:12.929507Z","steps":["trace[526501979] 'process raft request' (duration: 125.381127ms)","trace[526501979] 'compare' (duration: 58.403575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:30:12.929948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.826524ms","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-20T11:30:12.929995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.208034ms","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-20T11:30:12.930159Z","caller":"traceutil/trace.go:171","msg":"trace[533396431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"165.44379ms","start":"2026-05-20T11:30:12.764683Z","end":"2026-05-20T11:30:12.930127Z","steps":["trace[533396431] 'agreement among raft nodes before linearized reading' (duration: 164.977048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:12.930219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.998751ms","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-20T11:30:12.930301Z","caller":"traceutil/trace.go:171","msg":"trace[1433637356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:916; }","duration":"164.110715ms","start":"2026-05-20T11:30:12.766179Z","end":"2026-05-20T11:30:12.93029Z","steps":["trace[1433637356] 'agreement among raft nodes before linearized reading' (duration: 163.93911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:12.930297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.851907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:30:12.930364Z","caller":"traceutil/trace.go:171","msg":"trace[81033734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"164.101255ms","start":"2026-05-20T11:30:12.766244Z","end":"2026-05-20T11:30:12.930345Z","steps":["trace[81033734] 'agreement among raft nodes before linearized reading' (duration: 163.827217ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:12.930066Z","caller":"traceutil/trace.go:171","msg":"trace[1326213925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:916; }","duration":"164.988248ms","start":"2026-05-20T11:30:12.765041Z","end":"2026-05-20T11:30:12.930029Z","steps":["trace[1326213925] 'agreement among raft nodes before linearized reading' (duration: 164.634929ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:13.05326Z","caller":"traceutil/trace.go:171","msg":"trace[885253015] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"118.260937ms","start":"2026-05-20T11:30:12.934968Z","end":"2026-05-20T11:30:13.053229Z","steps":["trace[885253015] 'process raft request' (duration: 113.449648ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:13.055374Z","caller":"traceutil/trace.go:171","msg":"trace[491773786] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"118.570705ms","start":"2026-05-20T11:30:12.936785Z","end":"2026-05-20T11:30:13.055355Z","steps":["trace[491773786] 'process raft request' (duration: 118.289508ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:13.05541Z","caller":"traceutil/trace.go:171","msg":"trace[1579463329] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"116.958192ms","start":"2026-05-20T11:30:12.938419Z","end":"2026-05-20T11:30:13.055377Z","steps":["trace[1579463329] 'process raft request' (duration: 116.834878ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:13.05556Z","caller":"traceutil/trace.go:171","msg":"trace[1208654979] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"114.882937ms","start":"2026-05-20T11:30:12.940539Z","end":"2026-05-20T11:30:13.055422Z","steps":["trace[1208654979] 'process raft request' (duration: 114.755344ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:13.055605Z","caller":"traceutil/trace.go:171","msg":"trace[1739141417] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"114.318852ms","start":"2026-05-20T11:30:12.941235Z","end":"2026-05-20T11:30:13.055553Z","steps":["trace[1739141417] 'process raft request' (duration: 114.092045ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:13.490711Z","caller":"traceutil/trace.go:171","msg":"trace[277217842] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"103.935653ms","start":"2026-05-20T11:30:13.386749Z","end":"2026-05-20T11:30:13.490685Z","steps":["trace[277217842] 'process raft request' (duration: 103.766878ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:13.982387Z","caller":"traceutil/trace.go:171","msg":"trace[151124302] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"248.403312ms","start":"2026-05-20T11:30:13.733953Z","end":"2026-05-20T11:30:13.982356Z","steps":["trace[151124302] 'process raft request' (duration: 248.30705ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:13.982949Z","caller":"traceutil/trace.go:171","msg":"trace[1996947068] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"250.447808ms","start":"2026-05-20T11:30:13.732451Z","end":"2026-05-20T11:30:13.982899Z","steps":["trace[1996947068] 'process raft request' (duration: 163.150049ms)","trace[1996947068] 'compare' (duration: 86.439336ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:14.856408Z","caller":"traceutil/trace.go:171","msg":"trace[1602321371] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"138.630442ms","start":"2026-05-20T11:30:14.717741Z","end":"2026-05-20T11:30:14.856371Z","steps":["trace[1602321371] 'read index received' (duration: 115.257616ms)","trace[1602321371] 'applied index is now lower than readState.Index' (duration: 23.371056ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:14.856547Z","caller":"traceutil/trace.go:171","msg":"trace[1551577555] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"140.896683ms","start":"2026-05-20T11:30:14.7156Z","end":"2026-05-20T11:30:14.856496Z","steps":["trace[1551577555] 'process raft request' (duration: 117.339212ms)","trace[1551577555] 'compare' (duration: 23.231592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:30:14.856942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.165198ms","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-20T11:30:14.85701Z","caller":"traceutil/trace.go:171","msg":"trace[139047885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"139.295821ms","start":"2026-05-20T11:30:14.717695Z","end":"2026-05-20T11:30:14.856991Z","steps":["trace[139047885] 'agreement among raft nodes before linearized reading' (duration: 138.934771ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:15.009666Z","caller":"traceutil/trace.go:171","msg":"trace[107889316] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:969; }","duration":"140.444932ms","start":"2026-05-20T11:30:14.869198Z","end":"2026-05-20T11:30:15.009643Z","steps":["trace[107889316] 'read index received' (duration: 55.177608ms)","trace[107889316] 'applied index is now lower than readState.Index' (duration: 85.266314ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:15.009761Z","caller":"traceutil/trace.go:171","msg":"trace[539042217] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"145.135298ms","start":"2026-05-20T11:30:14.864583Z","end":"2026-05-20T11:30:15.009719Z","steps":["trace[539042217] 'process raft request' (duration: 144.981314ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:15.010001Z","caller":"traceutil/trace.go:171","msg":"trace[759313299] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"145.400144ms","start":"2026-05-20T11:30:14.864556Z","end":"2026-05-20T11:30:15.009956Z","steps":["trace[759313299] 'process raft request' (duration: 125.349397ms)","trace[759313299] 'compare' (duration: 19.456081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:30:15.010122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.900463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-05-20T11:30:15.010159Z","caller":"traceutil/trace.go:171","msg":"trace[844492999] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:933; }","duration":"141.008766ms","start":"2026-05-20T11:30:14.869138Z","end":"2026-05-20T11:30:15.010147Z","steps":["trace[844492999] 'agreement among raft nodes before linearized reading' (duration: 140.603706ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:15.01036Z","caller":"traceutil/trace.go:171","msg":"trace[1754327081] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"145.785005ms","start":"2026-05-20T11:30:14.864556Z","end":"2026-05-20T11:30:15.010341Z","steps":["trace[1754327081] 'process raft request' (duration: 144.962243ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:15.576497Z","caller":"traceutil/trace.go:171","msg":"trace[1841402510] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"182.007184ms","start":"2026-05-20T11:30:15.39445Z","end":"2026-05-20T11:30:15.576457Z","steps":["trace[1841402510] 'process raft request' (duration: 163.625712ms)","trace[1841402510] 'compare' (duration: 18.185257ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:15.629444Z","caller":"traceutil/trace.go:171","msg":"trace[2116386823] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"234.49741ms","start":"2026-05-20T11:30:15.39491Z","end":"2026-05-20T11:30:15.629408Z","steps":["trace[2116386823] 'process raft request' (duration: 234.15522ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:15.629512Z","caller":"traceutil/trace.go:171","msg":"trace[2044916128] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:981; }","duration":"124.126684ms","start":"2026-05-20T11:30:15.505361Z","end":"2026-05-20T11:30:15.629488Z","steps":["trace[2044916128] 'read index received' (duration: 52.65935ms)","trace[2044916128] 'applied index is now lower than readState.Index' (duration: 71.464434ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:15.629755Z","caller":"traceutil/trace.go:171","msg":"trace[1576322350] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"232.085905ms","start":"2026-05-20T11:30:15.397662Z","end":"2026-05-20T11:30:15.629748Z","steps":["trace[1576322350] 'process raft request' (duration: 231.548882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:15.62993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.557005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:30:15.629954Z","caller":"traceutil/trace.go:171","msg":"trace[1709103974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"124.657899ms","start":"2026-05-20T11:30:15.505289Z","end":"2026-05-20T11:30:15.629947Z","steps":["trace[1709103974] 'agreement among raft nodes before linearized reading' (duration: 124.596857ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:15.747635Z","caller":"traceutil/trace.go:171","msg":"trace[813057214] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"113.075299ms","start":"2026-05-20T11:30:15.634531Z","end":"2026-05-20T11:30:15.747606Z","steps":["trace[813057214] 'process raft request' (duration: 112.919045ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:15.934742Z","caller":"traceutil/trace.go:171","msg":"trace[1038092253] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"123.332093ms","start":"2026-05-20T11:30:15.811384Z","end":"2026-05-20T11:30:15.934716Z","steps":["trace[1038092253] 'read index received' (duration: 120.575109ms)","trace[1038092253] 'applied index is now lower than readState.Index' (duration: 2.756114ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:15.934839Z","caller":"traceutil/trace.go:171","msg":"trace[241486834] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"297.184468ms","start":"2026-05-20T11:30:15.637616Z","end":"2026-05-20T11:30:15.9348Z","steps":["trace[241486834] 'process raft request' (duration: 294.433625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:15.934916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.509027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-20T11:30:15.934938Z","caller":"traceutil/trace.go:171","msg":"trace[1083094467] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:946; }","duration":"123.60858ms","start":"2026-05-20T11:30:15.811324Z","end":"2026-05-20T11:30:15.934932Z","steps":["trace[1083094467] 'agreement among raft nodes before linearized reading' (duration: 123.523587ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:16.872784Z","caller":"traceutil/trace.go:171","msg":"trace[1691448331] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"101.587961ms","start":"2026-05-20T11:30:16.77116Z","end":"2026-05-20T11:30:16.872748Z","steps":["trace[1691448331] 'process raft request' (duration: 101.376295ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:17.378378Z","caller":"traceutil/trace.go:171","msg":"trace[1315623792] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"143.406361ms","start":"2026-05-20T11:30:17.234939Z","end":"2026-05-20T11:30:17.378345Z","steps":["trace[1315623792] 'process raft request' (duration: 143.150854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:43.646547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258050366,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T11:30:44.045981Z","caller":"traceutil/trace.go:171","msg":"trace[1583322018] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"900.422225ms","start":"2026-05-20T11:30:43.145514Z","end":"2026-05-20T11:30:44.045936Z","steps":["trace[1583322018] 'read index received' (duration: 900.078056ms)","trace[1583322018] 'applied index is now lower than readState.Index' (duration: 342.769µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:30:44.046144Z","caller":"traceutil/trace.go:171","msg":"trace[952573864] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"904.939316ms","start":"2026-05-20T11:30:43.141166Z","end":"2026-05-20T11:30:44.046105Z","steps":["trace[952573864] 'process raft request' (duration: 904.539645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:44.046303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.759484ms","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-20T11:30:44.046368Z","caller":"traceutil/trace.go:171","msg":"trace[258387308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1085; }","duration":"900.875497ms","start":"2026-05-20T11:30:43.145479Z","end":"2026-05-20T11:30:44.046354Z","steps":["trace[258387308] 'agreement among raft nodes before linearized reading' (duration: 900.643701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:44.046358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:43.141133Z","time spent":"905.121581ms","remote":"127.0.0.1:44416","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-20T11:30:44.046406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:43.145469Z","time spent":"900.929378ms","remote":"127.0.0.1:44416","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-20T11:30:44.046425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.983016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:30:44.046475Z","caller":"traceutil/trace.go:171","msg":"trace[643451469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1085; }","duration":"641.050238ms","start":"2026-05-20T11:30:43.405408Z","end":"2026-05-20T11:30:44.046459Z","steps":["trace[643451469] 'agreement among raft nodes before linearized reading' (duration: 640.930875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:44.046303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.912527ms","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-20T11:30:44.046525Z","caller":"traceutil/trace.go:171","msg":"trace[297485856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1085; }","duration":"752.205104ms","start":"2026-05-20T11:30:43.294313Z","end":"2026-05-20T11:30:44.046519Z","steps":["trace[297485856] 'agreement among raft nodes before linearized reading' (duration: 751.837295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:44.046553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:43.294292Z","time spent":"752.254146ms","remote":"127.0.0.1:44416","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-20T11:30:44.046521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:43.4054Z","time spent":"641.109909ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T11:30:44.046628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.592669ms","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-20T11:30:44.046363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.68054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:30:44.04667Z","caller":"traceutil/trace.go:171","msg":"trace[1865034491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1085; }","duration":"541.03938ms","start":"2026-05-20T11:30:43.505621Z","end":"2026-05-20T11:30:44.046661Z","steps":["trace[1865034491] 'agreement among raft nodes before linearized reading' (duration: 540.69042ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:30:44.046668Z","caller":"traceutil/trace.go:171","msg":"trace[1122910161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1085; }","duration":"642.682842ms","start":"2026-05-20T11:30:43.403973Z","end":"2026-05-20T11:30:44.046655Z","steps":["trace[1122910161] 'agreement among raft nodes before linearized reading' (duration: 642.570678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:44.04669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:43.50558Z","time spent":"541.104812ms","remote":"127.0.0.1:44196","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-20T11:30:44.046705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:43.403944Z","time spent":"642.751605ms","remote":"127.0.0.1:44416","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-20T11:30:44.046722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.471427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T11:30:44.046768Z","caller":"traceutil/trace.go:171","msg":"trace[613704749] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:1085; }","duration":"328.57469ms","start":"2026-05-20T11:30:43.718178Z","end":"2026-05-20T11:30:44.046753Z","steps":["trace[613704749] 'agreement among raft nodes before linearized reading' (duration: 328.452806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:44.046817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:43.718139Z","time spent":"328.662733ms","remote":"127.0.0.1:44204","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.169\" "} {"level":"warn","ts":"2026-05-20T11:30:44.04691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.094298ms","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-20T11:30:44.046954Z","caller":"traceutil/trace.go:171","msg":"trace[1415527216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1085; }","duration":"747.158569ms","start":"2026-05-20T11:30:43.299781Z","end":"2026-05-20T11:30:44.04694Z","steps":["trace[1415527216] 'agreement among raft nodes before linearized reading' (duration: 747.022896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:30:44.046993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:30:43.299771Z","time spent":"747.211491ms","remote":"127.0.0.1:44416","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-20T11:31:00.286418Z","caller":"traceutil/trace.go:171","msg":"trace[1451050218] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"143.211756ms","start":"2026-05-20T11:31:00.14317Z","end":"2026-05-20T11:31:00.286382Z","steps":["trace[1451050218] 'read index received' (duration: 95.898539ms)","trace[1451050218] 'applied index is now lower than readState.Index' (duration: 47.311367ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:00.286489Z","caller":"traceutil/trace.go:171","msg":"trace[1266620301] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"161.054643ms","start":"2026-05-20T11:31:00.125382Z","end":"2026-05-20T11:31:00.286437Z","steps":["trace[1266620301] 'process raft request' (duration: 113.686544ms)","trace[1266620301] 'compare' (duration: 46.925036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:31:00.286626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.430901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:31:00.286723Z","caller":"traceutil/trace.go:171","msg":"trace[2074659915] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1140; }","duration":"143.615446ms","start":"2026-05-20T11:31:00.143083Z","end":"2026-05-20T11:31:00.286699Z","steps":["trace[2074659915] 'agreement among raft nodes before linearized reading' (duration: 143.447422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:00.286767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.230889ms","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-20T11:31:00.28682Z","caller":"traceutil/trace.go:171","msg":"trace[1801231322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1140; }","duration":"142.352043ms","start":"2026-05-20T11:31:00.144449Z","end":"2026-05-20T11:31:00.286801Z","steps":["trace[1801231322] 'agreement among raft nodes before linearized reading' (duration: 142.134086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:00.286809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.579714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-20T11:31:00.286889Z","caller":"traceutil/trace.go:171","msg":"trace[145732813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1140; }","duration":"131.686807ms","start":"2026-05-20T11:31:00.155182Z","end":"2026-05-20T11:31:00.286869Z","steps":["trace[145732813] 'agreement among raft nodes before linearized reading' (duration: 131.432729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:00.286895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.591335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-20T11:31:00.286993Z","caller":"traceutil/trace.go:171","msg":"trace[78549122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1140; }","duration":"137.726069ms","start":"2026-05-20T11:31:00.149241Z","end":"2026-05-20T11:31:00.286967Z","steps":["trace[78549122] 'agreement among raft nodes before linearized reading' (duration: 137.469492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:00.287135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.527586ms","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-20T11:31:00.287184Z","caller":"traceutil/trace.go:171","msg":"trace[1670234058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1140; }","duration":"139.642069ms","start":"2026-05-20T11:31:00.147529Z","end":"2026-05-20T11:31:00.287171Z","steps":["trace[1670234058] 'agreement among raft nodes before linearized reading' (duration: 139.404843ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:00.398288Z","caller":"traceutil/trace.go:171","msg":"trace[1428587071] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"105.98861ms","start":"2026-05-20T11:31:00.292271Z","end":"2026-05-20T11:31:00.39826Z","steps":["trace[1428587071] 'process raft request' (duration: 105.900107ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:00.398394Z","caller":"traceutil/trace.go:171","msg":"trace[516575294] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"105.962318ms","start":"2026-05-20T11:31:00.292422Z","end":"2026-05-20T11:31:00.398384Z","steps":["trace[516575294] 'process raft request' (duration: 105.790454ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:00.398289Z","caller":"traceutil/trace.go:171","msg":"trace[1724610888] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"106.227826ms","start":"2026-05-20T11:31:00.292031Z","end":"2026-05-20T11:31:00.398259Z","steps":["trace[1724610888] 'process raft request' (duration: 102.537197ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:04.544307Z","caller":"traceutil/trace.go:171","msg":"trace[536621318] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"126.624661ms","start":"2026-05-20T11:31:04.417648Z","end":"2026-05-20T11:31:04.544273Z","steps":["trace[536621318] 'process raft request' (duration: 126.420595ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:04.546139Z","caller":"traceutil/trace.go:171","msg":"trace[2108653556] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"124.929565ms","start":"2026-05-20T11:31:04.421148Z","end":"2026-05-20T11:31:04.546078Z","steps":["trace[2108653556] 'process raft request' (duration: 124.762861ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:04.546323Z","caller":"traceutil/trace.go:171","msg":"trace[389447747] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"123.96447ms","start":"2026-05-20T11:31:04.422341Z","end":"2026-05-20T11:31:04.546305Z","steps":["trace[389447747] 'process raft request' (duration: 123.646951ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:08.774884Z","caller":"traceutil/trace.go:171","msg":"trace[961172271] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"212.899098ms","start":"2026-05-20T11:31:08.561954Z","end":"2026-05-20T11:31:08.774853Z","steps":["trace[961172271] 'process raft request' (duration: 212.725453ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:08.850944Z","caller":"traceutil/trace.go:171","msg":"trace[527693165] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1220; }","duration":"282.715731ms","start":"2026-05-20T11:31:08.568192Z","end":"2026-05-20T11:31:08.850908Z","steps":["trace[527693165] 'read index received' (duration: 206.442575ms)","trace[527693165] 'applied index is now lower than readState.Index' (duration: 76.271556ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:08.850989Z","caller":"traceutil/trace.go:171","msg":"trace[1062508914] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"284.88675ms","start":"2026-05-20T11:31:08.566057Z","end":"2026-05-20T11:31:08.850944Z","steps":["trace[1062508914] 'process raft request' (duration: 253.391603ms)","trace[1062508914] 'compare' (duration: 31.23961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:31:08.851268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.03794ms","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-20T11:31:08.851311Z","caller":"traceutil/trace.go:171","msg":"trace[2073443716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"283.111991ms","start":"2026-05-20T11:31:08.568185Z","end":"2026-05-20T11:31:08.851296Z","steps":["trace[2073443716] 'agreement among raft nodes before linearized reading' (duration: 282.858395ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:08.851304Z","caller":"traceutil/trace.go:171","msg":"trace[1373085778] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"121.890414ms","start":"2026-05-20T11:31:08.729367Z","end":"2026-05-20T11:31:08.851258Z","steps":["trace[1373085778] 'process raft request' (duration: 121.480903ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:10.290902Z","caller":"traceutil/trace.go:171","msg":"trace[1425167951] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"136.67577ms","start":"2026-05-20T11:31:10.154193Z","end":"2026-05-20T11:31:10.290868Z","steps":["trace[1425167951] 'process raft request' (duration: 136.448005ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:11.21238Z","caller":"traceutil/trace.go:171","msg":"trace[374220563] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"304.926899ms","start":"2026-05-20T11:31:10.907419Z","end":"2026-05-20T11:31:11.212346Z","steps":["trace[374220563] 'read index received' (duration: 298.497769ms)","trace[374220563] 'applied index is now lower than readState.Index' (duration: 6.42722ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:11.21244Z","caller":"traceutil/trace.go:171","msg":"trace[912395101] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"350.50743ms","start":"2026-05-20T11:31:10.861892Z","end":"2026-05-20T11:31:11.212399Z","steps":["trace[912395101] 'process raft request' (duration: 344.10362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:11.212609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.156486ms","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-20T11:31:11.212639Z","caller":"traceutil/trace.go:171","msg":"trace[121757135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"305.215857ms","start":"2026-05-20T11:31:10.907413Z","end":"2026-05-20T11:31:11.212628Z","steps":["trace[121757135] 'agreement among raft nodes before linearized reading' (duration: 305.037583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:11.212646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:10.861833Z","time spent":"350.687275ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:31:11.212665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:10.907351Z","time spent":"305.307219ms","remote":"127.0.0.1:44416","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-20T11:31:12.633219Z","caller":"traceutil/trace.go:171","msg":"trace[1536937027] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"276.495185ms","start":"2026-05-20T11:31:12.356688Z","end":"2026-05-20T11:31:12.633183Z","steps":["trace[1536937027] 'read index received' (duration: 276.270699ms)","trace[1536937027] 'applied index is now lower than readState.Index' (duration: 219.846µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:12.633352Z","caller":"traceutil/trace.go:171","msg":"trace[1398550175] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"336.481329ms","start":"2026-05-20T11:31:12.296818Z","end":"2026-05-20T11:31:12.633299Z","steps":["trace[1398550175] 'process raft request' (duration: 336.13696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:12.633556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:12.296755Z","time spent":"336.686103ms","remote":"127.0.0.1:44416","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-20T11:31:12.633611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.890975ms","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-20T11:31:12.633717Z","caller":"traceutil/trace.go:171","msg":"trace[190604234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1178; }","duration":"277.141213ms","start":"2026-05-20T11:31:12.356553Z","end":"2026-05-20T11:31:12.633694Z","steps":["trace[190604234] 'agreement among raft nodes before linearized reading' (duration: 276.828644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:12.63373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.051478ms","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-20T11:31:12.63378Z","caller":"traceutil/trace.go:171","msg":"trace[1271639217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"260.182541ms","start":"2026-05-20T11:31:12.373585Z","end":"2026-05-20T11:31:12.633767Z","steps":["trace[1271639217] 'agreement among raft nodes before linearized reading' (duration: 259.943114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:12.993718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.235375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:31:12.994061Z","caller":"traceutil/trace.go:171","msg":"trace[464263833] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1232; }","duration":"170.901959ms","start":"2026-05-20T11:31:12.823131Z","end":"2026-05-20T11:31:12.994033Z","steps":["trace[464263833] 'read index received' (duration: 168.244699ms)","trace[464263833] 'applied index is now lower than readState.Index' (duration: 2.65508ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:31:12.994412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.280759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:31:12.994425Z","caller":"traceutil/trace.go:171","msg":"trace[546999768] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"355.949115ms","start":"2026-05-20T11:31:12.6384Z","end":"2026-05-20T11:31:12.994349Z","steps":["trace[546999768] 'process raft request' (duration: 112.91349ms)","trace[546999768] 'compare' (duration: 241.809772ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:12.994476Z","caller":"traceutil/trace.go:171","msg":"trace[570291797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1180; }","duration":"171.386282ms","start":"2026-05-20T11:31:12.823072Z","end":"2026-05-20T11:31:12.994459Z","steps":["trace[570291797] 'agreement among raft nodes before linearized reading' (duration: 171.047843ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:12.994551Z","caller":"traceutil/trace.go:171","msg":"trace[1283447816] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"355.867632ms","start":"2026-05-20T11:31:12.638634Z","end":"2026-05-20T11:31:12.994501Z","steps":["trace[1283447816] 'process raft request' (duration: 355.261296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:12.99461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:12.63837Z","time spent":"356.14293ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:31:12.994764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:12.638623Z","time spent":"356.031566ms","remote":"127.0.0.1:44416","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-20T11:31:13.3822Z","caller":"traceutil/trace.go:171","msg":"trace[1674581043] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"104.480716ms","start":"2026-05-20T11:31:13.277679Z","end":"2026-05-20T11:31:13.38216Z","steps":["trace[1674581043] 'process raft request' (duration: 86.418426ms)","trace[1674581043] 'compare' (duration: 17.819513ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:15.535129Z","caller":"traceutil/trace.go:171","msg":"trace[913127213] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"143.514772ms","start":"2026-05-20T11:31:15.391589Z","end":"2026-05-20T11:31:15.535103Z","steps":["trace[913127213] 'process raft request' (duration: 143.220704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:37.964909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.483157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T11:31:37.965108Z","caller":"traceutil/trace.go:171","msg":"trace[2005462148] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1310; }","duration":"534.794255ms","start":"2026-05-20T11:31:37.430282Z","end":"2026-05-20T11:31:37.965076Z","steps":["trace[2005462148] 'count revisions from in-memory index tree' (duration: 534.317673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:37.965862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.18177ms","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-20T11:31:37.965954Z","caller":"traceutil/trace.go:171","msg":"trace[1074364973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"784.315714ms","start":"2026-05-20T11:31:37.181617Z","end":"2026-05-20T11:31:37.965933Z","steps":["trace[1074364973] 'range keys from in-memory index tree' (duration: 783.987175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:37.966011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:37.181596Z","time spent":"784.400237ms","remote":"127.0.0.1:44416","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-20T11:31:37.965874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.297943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-20T11:31:37.966526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.096354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-20T11:31:37.966554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.587376ms","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-20T11:31:37.9666Z","caller":"traceutil/trace.go:171","msg":"trace[1470253072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"532.223788ms","start":"2026-05-20T11:31:37.434352Z","end":"2026-05-20T11:31:37.966576Z","steps":["trace[1470253072] 'range keys from in-memory index tree' (duration: 531.674432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:37.966486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.270963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:31:37.966642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:37.434321Z","time spent":"532.311941ms","remote":"127.0.0.1:44416","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-20T11:31:37.966687Z","caller":"traceutil/trace.go:171","msg":"trace[737016511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"461.573421ms","start":"2026-05-20T11:31:37.505092Z","end":"2026-05-20T11:31:37.966666Z","steps":["trace[737016511] 'range keys from in-memory index tree' (duration: 460.964814ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:37.966609Z","caller":"traceutil/trace.go:171","msg":"trace[1922018531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"852.68293ms","start":"2026-05-20T11:31:37.113911Z","end":"2026-05-20T11:31:37.966594Z","steps":["trace[1922018531] 'range keys from in-memory index tree' (duration: 851.716704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:37.966756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:37.505068Z","time spent":"461.674453ms","remote":"127.0.0.1:44196","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-20T11:31:37.966545Z","caller":"traceutil/trace.go:171","msg":"trace[834073753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"854.989951ms","start":"2026-05-20T11:31:37.111523Z","end":"2026-05-20T11:31:37.966513Z","steps":["trace[834073753] 'range keys from in-memory index tree' (duration: 854.034805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:37.966856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:37.111504Z","time spent":"855.333271ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T11:31:37.966864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:37.11389Z","time spent":"852.881795ms","remote":"127.0.0.1:44416","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-20T11:31:37.967065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:37.430241Z","time spent":"534.91351ms","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:31:37.967332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.077275ms","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-20T11:31:37.967445Z","caller":"traceutil/trace.go:171","msg":"trace[1597358244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"328.241659ms","start":"2026-05-20T11:31:37.639193Z","end":"2026-05-20T11:31:37.967435Z","steps":["trace[1597358244] 'range keys from in-memory index tree' (duration: 325.618909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:37.967512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:37.639167Z","time spent":"328.336351ms","remote":"127.0.0.1:44416","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-20T11:31:38.240165Z","caller":"traceutil/trace.go:171","msg":"trace[2075933866] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1371; }","duration":"237.391076ms","start":"2026-05-20T11:31:38.002628Z","end":"2026-05-20T11:31:38.240019Z","steps":["trace[2075933866] 'read index received' (duration: 236.259115ms)","trace[2075933866] 'applied index is now lower than readState.Index' (duration: 1.130731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:31:38.240719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.010862ms","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-20T11:31:38.240811Z","caller":"traceutil/trace.go:171","msg":"trace[269174339] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1315; }","duration":"238.140536ms","start":"2026-05-20T11:31:38.002618Z","end":"2026-05-20T11:31:38.240759Z","steps":["trace[269174339] 'agreement among raft nodes before linearized reading' (duration: 237.695094ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:38.241891Z","caller":"traceutil/trace.go:171","msg":"trace[1600702482] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"269.016786ms","start":"2026-05-20T11:31:37.972855Z","end":"2026-05-20T11:31:38.241872Z","steps":["trace[1600702482] 'process raft request' (duration: 266.295063ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:38.2427Z","caller":"traceutil/trace.go:171","msg":"trace[1865959969] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"268.80267ms","start":"2026-05-20T11:31:37.973845Z","end":"2026-05-20T11:31:38.242648Z","steps":["trace[1865959969] 'process raft request' (duration: 265.705747ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:38.242985Z","caller":"traceutil/trace.go:171","msg":"trace[7767294] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"269.072717ms","start":"2026-05-20T11:31:37.973892Z","end":"2026-05-20T11:31:38.242965Z","steps":["trace[7767294] 'process raft request' (duration: 265.810891ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:38.24324Z","caller":"traceutil/trace.go:171","msg":"trace[1573141405] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"269.236362ms","start":"2026-05-20T11:31:37.973988Z","end":"2026-05-20T11:31:38.243224Z","steps":["trace[1573141405] 'process raft request' (duration: 265.825181ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:38.243456Z","caller":"traceutil/trace.go:171","msg":"trace[1692957061] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"267.468994ms","start":"2026-05-20T11:31:37.975976Z","end":"2026-05-20T11:31:38.243445Z","steps":["trace[1692957061] 'process raft request' (duration: 263.94579ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:56.77029Z","caller":"traceutil/trace.go:171","msg":"trace[1687995895] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"393.639356ms","start":"2026-05-20T11:31:56.37662Z","end":"2026-05-20T11:31:56.770259Z","steps":["trace[1687995895] 'read index received' (duration: 393.227376ms)","trace[1687995895] 'applied index is now lower than readState.Index' (duration: 410.88µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:56.770455Z","caller":"traceutil/trace.go:171","msg":"trace[1366283437] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"416.999857ms","start":"2026-05-20T11:31:56.353395Z","end":"2026-05-20T11:31:56.770395Z","steps":["trace[1366283437] 'process raft request' (duration: 416.540905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:56.770634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.138793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:31:56.770687Z","caller":"traceutil/trace.go:171","msg":"trace[1733480862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1390; }","duration":"265.266817ms","start":"2026-05-20T11:31:56.505404Z","end":"2026-05-20T11:31:56.770671Z","steps":["trace[1733480862] 'agreement among raft nodes before linearized reading' (duration: 265.155254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:56.770705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:56.353371Z","time spent":"417.191822ms","remote":"127.0.0.1:44416","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-20T11:31:56.770835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.1776ms","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-20T11:31:56.770866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.578225ms","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-20T11:31:56.770957Z","caller":"traceutil/trace.go:171","msg":"trace[1591350994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1390; }","duration":"390.721258ms","start":"2026-05-20T11:31:56.380214Z","end":"2026-05-20T11:31:56.770935Z","steps":["trace[1591350994] 'agreement among raft nodes before linearized reading' (duration: 390.348119ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:56.770957Z","caller":"traceutil/trace.go:171","msg":"trace[1582945371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1390; }","duration":"394.321654ms","start":"2026-05-20T11:31:56.376602Z","end":"2026-05-20T11:31:56.770923Z","steps":["trace[1582945371] 'agreement among raft nodes before linearized reading' (duration: 393.948455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:56.771001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:56.380196Z","time spent":"390.796531ms","remote":"127.0.0.1:44416","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-20T11:31:56.771157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.827923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-20T11:31:56.771208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.911155ms","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-20T11:31:56.771211Z","caller":"traceutil/trace.go:171","msg":"trace[1527865310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1390; }","duration":"390.939725ms","start":"2026-05-20T11:31:56.380257Z","end":"2026-05-20T11:31:56.771196Z","steps":["trace[1527865310] 'agreement among raft nodes before linearized reading' (duration: 390.78768ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:56.771248Z","caller":"traceutil/trace.go:171","msg":"trace[1469863037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1390; }","duration":"390.951676ms","start":"2026-05-20T11:31:56.380284Z","end":"2026-05-20T11:31:56.771236Z","steps":["trace[1469863037] 'agreement among raft nodes before linearized reading' (duration: 390.792121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:56.771281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:56.380204Z","time spent":"391.067779ms","remote":"127.0.0.1:44416","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-20T11:31:56.771278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:56.38023Z","time spent":"391.032688ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T11:31:56.771792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:56.376527Z","time spent":"395.198757ms","remote":"127.0.0.1:44416","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-20T11:31:57.010315Z","caller":"traceutil/trace.go:171","msg":"trace[944487813] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"148.554156ms","start":"2026-05-20T11:31:56.861732Z","end":"2026-05-20T11:31:57.010287Z","steps":["trace[944487813] 'process raft request' (duration: 137.572004ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:58.735337Z","caller":"traceutil/trace.go:171","msg":"trace[1091647220] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"131.439462ms","start":"2026-05-20T11:31:58.603853Z","end":"2026-05-20T11:31:58.735292Z","steps":["trace[1091647220] 'process raft request' (duration: 131.244687ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:58.735291Z","caller":"traceutil/trace.go:171","msg":"trace[648056043] linearizableReadLoop","detail":"{readStateIndex:1465; appliedIndex:1464; }","duration":"107.413443ms","start":"2026-05-20T11:31:58.627846Z","end":"2026-05-20T11:31:58.73526Z","steps":["trace[648056043] 'read index received' (duration: 107.130096ms)","trace[648056043] 'applied index is now lower than readState.Index' (duration: 282.247µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:31:58.736227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.359027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-20T11:31:58.736288Z","caller":"traceutil/trace.go:171","msg":"trace[665372961] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1400; }","duration":"108.483411ms","start":"2026-05-20T11:31:58.627792Z","end":"2026-05-20T11:31:58.736275Z","steps":["trace[665372961] 'agreement among raft nodes before linearized reading' (duration: 107.609787ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:58.902171Z","caller":"traceutil/trace.go:171","msg":"trace[191934540] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"273.361971ms","start":"2026-05-20T11:31:58.628772Z","end":"2026-05-20T11:31:58.902134Z","steps":["trace[191934540] 'process raft request' (duration: 257.070518ms)","trace[191934540] 'compare' (duration: 16.091988ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:58.923533Z","caller":"traceutil/trace.go:171","msg":"trace[35542200] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1465; }","duration":"147.21414ms","start":"2026-05-20T11:31:58.776289Z","end":"2026-05-20T11:31:58.923503Z","steps":["trace[35542200] 'read index received' (duration: 109.507318ms)","trace[35542200] 'applied index is now lower than readState.Index' (duration: 37.705722ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:58.923581Z","caller":"traceutil/trace.go:171","msg":"trace[1781089155] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"184.887081ms","start":"2026-05-20T11:31:58.738651Z","end":"2026-05-20T11:31:58.923538Z","steps":["trace[1781089155] 'process raft request' (duration: 184.566953ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:58.923712Z","caller":"traceutil/trace.go:171","msg":"trace[1953076931] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"183.411372ms","start":"2026-05-20T11:31:58.74028Z","end":"2026-05-20T11:31:58.923691Z","steps":["trace[1953076931] 'process raft request' (duration: 183.143355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:58.92401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.273558ms","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-20T11:31:58.92405Z","caller":"traceutil/trace.go:171","msg":"trace[2064976411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1403; }","duration":"122.385292ms","start":"2026-05-20T11:31:58.801656Z","end":"2026-05-20T11:31:58.924041Z","steps":["trace[2064976411] 'agreement among raft nodes before linearized reading' (duration: 122.229137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:58.924011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.390631ms","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-20T11:31:58.924159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.456013ms","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-20T11:31:58.924179Z","caller":"traceutil/trace.go:171","msg":"trace[1629758527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1403; }","duration":"122.493234ms","start":"2026-05-20T11:31:58.80168Z","end":"2026-05-20T11:31:58.924173Z","steps":["trace[1629758527] 'agreement among raft nodes before linearized reading' (duration: 122.432622ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:58.924238Z","caller":"traceutil/trace.go:171","msg":"trace[1898624451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1403; }","duration":"122.603196ms","start":"2026-05-20T11:31:58.801553Z","end":"2026-05-20T11:31:58.924156Z","steps":["trace[1898624451] 'agreement among raft nodes before linearized reading' (duration: 122.273317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:58.924249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.93285ms","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-20T11:31:58.924497Z","caller":"traceutil/trace.go:171","msg":"trace[1591313535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1403; }","duration":"148.254067ms","start":"2026-05-20T11:31:58.776223Z","end":"2026-05-20T11:31:58.924477Z","steps":["trace[1591313535] 'agreement among raft nodes before linearized reading' (duration: 147.879768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:58.924285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.564625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:31:58.924701Z","caller":"traceutil/trace.go:171","msg":"trace[608859788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1403; }","duration":"123.024248ms","start":"2026-05-20T11:31:58.801656Z","end":"2026-05-20T11:31:58.92468Z","steps":["trace[608859788] 'agreement among raft nodes before linearized reading' (duration: 122.218186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:59.283713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.326524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:31:59.284086Z","caller":"traceutil/trace.go:171","msg":"trace[974318968] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"267.800874ms","start":"2026-05-20T11:31:59.016261Z","end":"2026-05-20T11:31:59.284062Z","steps":["trace[974318968] 'process raft request' (duration: 267.755823ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:59.284021Z","caller":"traceutil/trace.go:171","msg":"trace[2108862553] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"354.738743ms","start":"2026-05-20T11:31:58.9292Z","end":"2026-05-20T11:31:59.283939Z","steps":["trace[2108862553] 'process raft request' (duration: 178.08182ms)","trace[2108862553] 'compare' (duration: 176.15659ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:31:59.284208Z","caller":"traceutil/trace.go:171","msg":"trace[2097789458] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"353.700096ms","start":"2026-05-20T11:31:58.930489Z","end":"2026-05-20T11:31:59.284189Z","steps":["trace[2097789458] 'process raft request' (duration: 353.46793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:59.284315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:58.930462Z","time spent":"353.783547ms","remote":"127.0.0.1:44416","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-20T11:31:59.284367Z","caller":"traceutil/trace.go:171","msg":"trace[104715758] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"353.681675ms","start":"2026-05-20T11:31:58.930674Z","end":"2026-05-20T11:31:59.284356Z","steps":["trace[104715758] 'process raft request' (duration: 353.314446ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:31:59.284369Z","caller":"traceutil/trace.go:171","msg":"trace[868329826] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"353.917931ms","start":"2026-05-20T11:31:58.930427Z","end":"2026-05-20T11:31:59.284345Z","steps":["trace[868329826] 'process raft request' (duration: 353.48491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:59.284445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:58.930666Z","time spent":"353.724816ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:31:59.284468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:58.930403Z","time spent":"354.017353ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:31:59.284864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:58.929172Z","time spent":"355.032171ms","remote":"127.0.0.1:44416","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-20T11:31:59.284105Z","caller":"traceutil/trace.go:171","msg":"trace[597302552] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"354.188998ms","start":"2026-05-20T11:31:58.929873Z","end":"2026-05-20T11:31:59.284062Z","steps":["trace[597302552] 'process raft request' (duration: 353.984413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:31:59.286148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:31:58.92984Z","time spent":"356.156341ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:32:02.617877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.619622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:32:02.618019Z","caller":"traceutil/trace.go:171","msg":"trace[1250452217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1430; }","duration":"112.827737ms","start":"2026-05-20T11:32:02.505162Z","end":"2026-05-20T11:32:02.61799Z","steps":["trace[1250452217] 'range keys from in-memory index tree' (duration: 112.510188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:03.835269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828773258051183,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T11:32:04.058695Z","caller":"traceutil/trace.go:171","msg":"trace[1360883553] linearizableReadLoop","detail":"{readStateIndex:1497; appliedIndex:1496; }","duration":"724.28807ms","start":"2026-05-20T11:32:03.334376Z","end":"2026-05-20T11:32:04.058665Z","steps":["trace[1360883553] 'read index received' (duration: 724.025263ms)","trace[1360883553] 'applied index is now lower than readState.Index' (duration: 261.487µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:32:04.058748Z","caller":"traceutil/trace.go:171","msg":"trace[1678507010] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"725.769599ms","start":"2026-05-20T11:32:03.332953Z","end":"2026-05-20T11:32:04.058722Z","steps":["trace[1678507010] 'process raft request' (duration: 725.477562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.058915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.332915Z","time spent":"725.891522ms","remote":"127.0.0.1:44416","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-20T11:32:04.059002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.895072ms","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-20T11:32:04.059048Z","caller":"traceutil/trace.go:171","msg":"trace[147384964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1431; }","duration":"722.966414ms","start":"2026-05-20T11:32:03.33607Z","end":"2026-05-20T11:32:04.059037Z","steps":["trace[147384964] 'agreement among raft nodes before linearized reading' (duration: 722.760589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.059081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.336061Z","time spent":"723.012635ms","remote":"127.0.0.1:44416","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-20T11:32:04.059097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.679449ms","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-20T11:32:04.059208Z","caller":"traceutil/trace.go:171","msg":"trace[743923915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1431; }","duration":"724.850525ms","start":"2026-05-20T11:32:03.33433Z","end":"2026-05-20T11:32:04.059181Z","steps":["trace[743923915] 'agreement among raft nodes before linearized reading' (duration: 724.483344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.059249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.101608ms","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-20T11:32:04.059258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.954234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-20T11:32:04.059266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.334261Z","time spent":"724.993818ms","remote":"127.0.0.1:44416","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-20T11:32:04.059284Z","caller":"traceutil/trace.go:171","msg":"trace[1271833958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1431; }","duration":"723.161179ms","start":"2026-05-20T11:32:03.336114Z","end":"2026-05-20T11:32:04.059275Z","steps":["trace[1271833958] 'agreement among raft nodes before linearized reading' (duration: 723.069697ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:32:04.059297Z","caller":"traceutil/trace.go:171","msg":"trace[409034292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1431; }","duration":"723.014495ms","start":"2026-05-20T11:32:03.336271Z","end":"2026-05-20T11:32:04.059285Z","steps":["trace[409034292] 'agreement among raft nodes before linearized reading' (duration: 722.806569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.059316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.336106Z","time spent":"723.204161ms","remote":"127.0.0.1:44416","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-20T11:32:04.059328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.336261Z","time spent":"723.060867ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T11:32:04.059281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.887116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T11:32:04.059391Z","caller":"traceutil/trace.go:171","msg":"trace[937440721] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:1431; }","duration":"335.116872ms","start":"2026-05-20T11:32:03.724251Z","end":"2026-05-20T11:32:04.059368Z","steps":["trace[937440721] 'agreement among raft nodes before linearized reading' (duration: 334.642749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.059439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.724229Z","time spent":"335.202554ms","remote":"127.0.0.1:44204","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.169\" "} {"level":"warn","ts":"2026-05-20T11:32:04.059451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.13969ms","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":"warn","ts":"2026-05-20T11:32:04.059453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.561407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-20T11:32:04.059476Z","caller":"traceutil/trace.go:171","msg":"trace[366517679] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1431; }","duration":"187.215403ms","start":"2026-05-20T11:32:03.872253Z","end":"2026-05-20T11:32:04.059468Z","steps":["trace[366517679] 'agreement among raft nodes before linearized reading' (duration: 187.169062ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:32:04.059481Z","caller":"traceutil/trace.go:171","msg":"trace[84268703] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1431; }","duration":"130.643121ms","start":"2026-05-20T11:32:03.928828Z","end":"2026-05-20T11:32:04.059471Z","steps":["trace[84268703] 'agreement among raft nodes before linearized reading' (duration: 130.569739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.059537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.798378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:32:04.059566Z","caller":"traceutil/trace.go:171","msg":"trace[1907926800] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1431; }","duration":"309.831039ms","start":"2026-05-20T11:32:03.749725Z","end":"2026-05-20T11:32:04.059556Z","steps":["trace[1907926800] 'agreement among raft nodes before linearized reading' (duration: 309.781968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.059636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.708051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T11:32:04.059809Z","caller":"traceutil/trace.go:171","msg":"trace[1384653238] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1431; }","duration":"424.984489ms","start":"2026-05-20T11:32:03.634796Z","end":"2026-05-20T11:32:04.059781Z","steps":["trace[1384653238] 'agreement among raft nodes before linearized reading' (duration: 424.608159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.059888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.634783Z","time spent":"425.088452ms","remote":"127.0.0.1:44400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:32:04.059958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.749694Z","time spent":"310.245481ms","remote":"127.0.0.1:51292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:32:04.059653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.230841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T11:32:04.060627Z","caller":"traceutil/trace.go:171","msg":"trace[942238710] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1431; }","duration":"433.254368ms","start":"2026-05-20T11:32:03.627354Z","end":"2026-05-20T11:32:04.060608Z","steps":["trace[942238710] 'agreement among raft nodes before linearized reading' (duration: 432.240901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.060677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.627324Z","time spent":"433.337151ms","remote":"127.0.0.1:44372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:32:04.06026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.830777ms","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-20T11:32:04.060775Z","caller":"traceutil/trace.go:171","msg":"trace[1892714964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1431; }","duration":"724.357441ms","start":"2026-05-20T11:32:03.336402Z","end":"2026-05-20T11:32:04.060759Z","steps":["trace[1892714964] 'agreement among raft nodes before linearized reading' (duration: 722.504412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:32:04.060846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:32:03.336389Z","time spent":"724.442454ms","remote":"127.0.0.1:44416","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-20T11:34:00.411534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.603637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:34:00.411634Z","caller":"traceutil/trace.go:171","msg":"trace[11361865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1981; }","duration":"781.730271ms","start":"2026-05-20T11:33:59.629882Z","end":"2026-05-20T11:34:00.411612Z","steps":["trace[11361865] 'range keys from in-memory index tree' (duration: 781.532586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:34:00.411673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:33:59.629872Z","time spent":"781.792132ms","remote":"127.0.0.1:44196","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-20T11:34:00.411846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.865536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:34:00.411883Z","caller":"traceutil/trace.go:171","msg":"trace[1717528951] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1981; }","duration":"810.940918ms","start":"2026-05-20T11:33:59.600932Z","end":"2026-05-20T11:34:00.411873Z","steps":["trace[1717528951] 'count revisions from in-memory index tree' (duration: 810.778874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:34:00.411912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:33:59.600911Z","time spent":"810.993589ms","remote":"127.0.0.1:49216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-05-20T11:34:00.412251Z","caller":"traceutil/trace.go:171","msg":"trace[1795901238] linearizableReadLoop","detail":"{readStateIndex:2072; appliedIndex:2071; }","duration":"136.494523ms","start":"2026-05-20T11:34:00.275678Z","end":"2026-05-20T11:34:00.412173Z","steps":["trace[1795901238] 'read index received' (duration: 133.640036ms)","trace[1795901238] 'applied index is now lower than readState.Index' (duration: 2.852467ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:34:00.41237Z","caller":"traceutil/trace.go:171","msg":"trace[1560569857] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"671.415195ms","start":"2026-05-20T11:33:59.740937Z","end":"2026-05-20T11:34:00.412352Z","steps":["trace[1560569857] 'process raft request' (duration: 668.465555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:34:00.412531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:33:59.740898Z","time spent":"671.506067ms","remote":"127.0.0.1:44416","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-20T11:34:00.412636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.939144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-20T11:34:00.412846Z","caller":"traceutil/trace.go:171","msg":"trace[1262666661] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1982; }","duration":"137.384608ms","start":"2026-05-20T11:34:00.275442Z","end":"2026-05-20T11:34:00.412826Z","steps":["trace[1262666661] 'agreement among raft nodes before linearized reading' (duration: 136.959736ms)"],"step_count":1}