{"level":"warn","ts":"2026-04-02T16:39:20.139401Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T16:39:20.139526Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-02T16:39:20.139621Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T16:39:20.139634Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-02T16:39:20.139665Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T16:39:20.14063Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-04-02T16:39:20.140734Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-02T16:39:20.145695Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.564945ms"} {"level":"info","ts":"2026-04-02T16:39:20.1533Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-04-02T16:39:20.153395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T16:39:20.153422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-04-02T16:39:20.153437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T16:39:20.153446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-04-02T16:39:20.153492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-04-02T16:39:20.167344Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T16:39:20.168603Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T16:39:20.16959Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-02T16:39:20.171778Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T16:39:20.174982Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T16:39:20.17512Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T16:39:20.175186Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T16:39:20.175198Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T16:39:20.175793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-04-02T16:39:20.176629Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-02T16:39:20.178909Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T16:39:20.179089Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-02T16:39:20.179255Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-02T16:39:20.17913Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T16:39:20.179162Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T16:39:21.05476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T16:39:21.054847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T16:39:21.054876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-04-02T16:39:21.054893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-04-02T16:39:21.054903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-02T16:39:21.054917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-04-02T16:39:21.054935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-02T16:39:21.085111Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:39:21.088936Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T16:39:21.088999Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:39:21.089029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:39:21.089313Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T16:39:21.089392Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T16:39:21.089405Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:39:21.089555Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:39:21.089615Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:39:21.092586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-04-02T16:39:21.093219Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T16:39:25.268982Z","caller":"traceutil/trace.go:171","msg":"trace[1541427641] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:219; }","duration":"148.52897ms","start":"2026-04-02T16:39:25.120417Z","end":"2026-04-02T16:39:25.268946Z","steps":["trace[1541427641] 'read index received' (duration: 111.635031ms)","trace[1541427641] 'applied index is now lower than readState.Index' (duration: 36.892669ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:25.269033Z","caller":"traceutil/trace.go:171","msg":"trace[979170596] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"158.481348ms","start":"2026-04-02T16:39:25.110503Z","end":"2026-04-02T16:39:25.268984Z","steps":["trace[979170596] 'process raft request' (duration: 121.65024ms)","trace[979170596] 'compare' (duration: 36.620973ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:25.269708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.251707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-02T16:39:25.270125Z","caller":"traceutil/trace.go:171","msg":"trace[704941356] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:214; }","duration":"149.678777ms","start":"2026-04-02T16:39:25.120403Z","end":"2026-04-02T16:39:25.270082Z","steps":["trace[704941356] 'agreement among raft nodes before linearized reading' (duration: 148.670343ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:25.88102Z","caller":"traceutil/trace.go:171","msg":"trace[2107355616] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"158.08949ms","start":"2026-04-02T16:39:25.722887Z","end":"2026-04-02T16:39:25.880977Z","steps":["trace[2107355616] 'process raft request' (duration: 129.938912ms)","trace[2107355616] 'compare' (duration: 27.983874ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:25.881049Z","caller":"traceutil/trace.go:171","msg":"trace[892869747] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"157.309021ms","start":"2026-04-02T16:39:25.723714Z","end":"2026-04-02T16:39:25.881023Z","steps":["trace[892869747] 'process raft request' (duration: 157.24963ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:25.881065Z","caller":"traceutil/trace.go:171","msg":"trace[369263841] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:245; }","duration":"157.424244ms","start":"2026-04-02T16:39:25.723603Z","end":"2026-04-02T16:39:25.881028Z","steps":["trace[369263841] 'read index received' (duration: 129.325637ms)","trace[369263841] 'applied index is now lower than readState.Index' (duration: 28.095267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:25.881311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.645379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:39:25.881373Z","caller":"traceutil/trace.go:171","msg":"trace[741941378] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:241; }","duration":"157.777542ms","start":"2026-04-02T16:39:25.72358Z","end":"2026-04-02T16:39:25.881357Z","steps":["trace[741941378] 'agreement among raft nodes before linearized reading' (duration: 157.547496ms)"],"step_count":1} 2026/04/02 16:39:26 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-02T16:39:26.684818Z","caller":"traceutil/trace.go:171","msg":"trace[784072590] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:291; }","duration":"208.74116ms","start":"2026-04-02T16:39:26.476055Z","end":"2026-04-02T16:39:26.684797Z","steps":["trace[784072590] 'read index received' (duration: 192.11106ms)","trace[784072590] 'applied index is now lower than readState.Index' (duration: 16.629ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:26.684845Z","caller":"traceutil/trace.go:171","msg":"trace[731232770] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"246.413913ms","start":"2026-04-02T16:39:26.438416Z","end":"2026-04-02T16:39:26.68483Z","steps":["trace[731232770] 'process raft request' (duration: 246.29739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:26.684911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.841713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-02T16:39:26.684934Z","caller":"traceutil/trace.go:171","msg":"trace[655034054] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:284; }","duration":"208.936295ms","start":"2026-04-02T16:39:26.475989Z","end":"2026-04-02T16:39:26.684926Z","steps":["trace[655034054] 'agreement among raft nodes before linearized reading' (duration: 208.881334ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:26.684875Z","caller":"traceutil/trace.go:171","msg":"trace[1096506416] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"247.356025ms","start":"2026-04-02T16:39:26.437421Z","end":"2026-04-02T16:39:26.684777Z","steps":["trace[1096506416] 'process raft request' (duration: 230.725956ms)","trace[1096506416] 'compare' (duration: 15.695437ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:27.560175Z","caller":"traceutil/trace.go:171","msg":"trace[927938531] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"108.464725ms","start":"2026-04-02T16:39:27.451679Z","end":"2026-04-02T16:39:27.560144Z","steps":["trace[927938531] 'process raft request' (duration: 34.311329ms)","trace[927938531] 'compare' (duration: 73.679455ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:27.795755Z","caller":"traceutil/trace.go:171","msg":"trace[1438389785] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"120.201104ms","start":"2026-04-02T16:39:27.675528Z","end":"2026-04-02T16:39:27.795729Z","steps":["trace[1438389785] 'read index received' (duration: 62.278175ms)","trace[1438389785] 'applied index is now lower than readState.Index' (duration: 57.921739ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:27.795877Z","caller":"traceutil/trace.go:171","msg":"trace[1306565045] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"150.71258ms","start":"2026-04-02T16:39:27.645137Z","end":"2026-04-02T16:39:27.795849Z","steps":["trace[1306565045] 'process raft request' (duration: 92.657288ms)","trace[1306565045] 'compare' (duration: 57.814606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:27.796146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.573333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-02T16:39:27.79627Z","caller":"traceutil/trace.go:171","msg":"trace[1327327220] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:301; }","duration":"120.726046ms","start":"2026-04-02T16:39:27.675524Z","end":"2026-04-02T16:39:27.79625Z","steps":["trace[1327327220] 'agreement among raft nodes before linearized reading' (duration: 120.519081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:28.201054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.68736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-02T16:39:28.201141Z","caller":"traceutil/trace.go:171","msg":"trace[1379238617] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:304; }","duration":"124.825003ms","start":"2026-04-02T16:39:28.076296Z","end":"2026-04-02T16:39:28.201121Z","steps":["trace[1379238617] 'range keys from in-memory index tree' (duration: 124.518275ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:29.66667Z","caller":"traceutil/trace.go:171","msg":"trace[1715849607] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"130.267244ms","start":"2026-04-02T16:39:29.536365Z","end":"2026-04-02T16:39:29.666632Z","steps":["trace[1715849607] 'process raft request' (duration: 129.990418ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:30.078313Z","caller":"traceutil/trace.go:171","msg":"trace[1258602472] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:329; }","duration":"172.685616ms","start":"2026-04-02T16:39:29.905595Z","end":"2026-04-02T16:39:30.07828Z","steps":["trace[1258602472] 'read index received' (duration: 44.332911ms)","trace[1258602472] 'applied index is now lower than readState.Index' (duration: 128.351605ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:30.078428Z","caller":"traceutil/trace.go:171","msg":"trace[643997294] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"177.226937ms","start":"2026-04-02T16:39:29.901127Z","end":"2026-04-02T16:39:30.078354Z","steps":["trace[643997294] 'process raft request' (duration: 90.633989ms)","trace[643997294] 'compare' (duration: 86.379233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:30.078555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.942342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-02T16:39:30.07862Z","caller":"traceutil/trace.go:171","msg":"trace[1981673275] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:321; }","duration":"173.047725ms","start":"2026-04-02T16:39:29.905556Z","end":"2026-04-02T16:39:30.078604Z","steps":["trace[1981673275] 'agreement among raft nodes before linearized reading' (duration: 172.873511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:30.091744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.395281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-02T16:39:30.091766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.681419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-02T16:39:30.091747Z","caller":"traceutil/trace.go:171","msg":"trace[1315126378] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"131.944839ms","start":"2026-04-02T16:39:29.959678Z","end":"2026-04-02T16:39:30.091623Z","steps":["trace[1315126378] 'process raft request' (duration: 131.762604ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:30.091828Z","caller":"traceutil/trace.go:171","msg":"trace[1325240662] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:322; }","duration":"165.809171ms","start":"2026-04-02T16:39:29.926003Z","end":"2026-04-02T16:39:30.091813Z","steps":["trace[1325240662] 'agreement among raft nodes before linearized reading' (duration: 165.692569ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:30.091842Z","caller":"traceutil/trace.go:171","msg":"trace[1720754113] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:322; }","duration":"115.536035ms","start":"2026-04-02T16:39:29.97629Z","end":"2026-04-02T16:39:30.091826Z","steps":["trace[1720754113] 'agreement among raft nodes before linearized reading' (duration: 115.403932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:30.49139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.557761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:39:30.491527Z","caller":"traceutil/trace.go:171","msg":"trace[1193222236] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:333; }","duration":"364.921555ms","start":"2026-04-02T16:39:30.126592Z","end":"2026-04-02T16:39:30.491514Z","steps":["trace[1193222236] 'read index received' (duration: 225.958764ms)","trace[1193222236] 'applied index is now lower than readState.Index' (duration: 138.960751ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:30.491593Z","caller":"traceutil/trace.go:171","msg":"trace[265962346] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"385.674823ms","start":"2026-04-02T16:39:30.105912Z","end":"2026-04-02T16:39:30.491587Z","steps":["trace[265962346] 'process raft request' (duration: 385.55515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:30.491634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:30.105898Z","time spent":"385.721375ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:39:30.491847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.099545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-02T16:39:30.491891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.285784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-02T16:39:30.491976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.883374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-02T16:39:30.491989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.530864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-02T16:39:30.492022Z","caller":"traceutil/trace.go:171","msg":"trace[1968526407] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:325; }","duration":"215.655227ms","start":"2026-04-02T16:39:30.27636Z","end":"2026-04-02T16:39:30.492015Z","steps":["trace[1968526407] 'agreement among raft nodes before linearized reading' (duration: 215.589165ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:30.49203Z","caller":"traceutil/trace.go:171","msg":"trace[1673627359] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:325; }","duration":"165.964266ms","start":"2026-04-02T16:39:30.326047Z","end":"2026-04-02T16:39:30.492012Z","steps":["trace[1673627359] 'agreement among raft nodes before linearized reading' (duration: 165.892554ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:30.491851Z","caller":"traceutil/trace.go:171","msg":"trace[167865360] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"393.766845ms","start":"2026-04-02T16:39:30.097834Z","end":"2026-04-02T16:39:30.491601Z","steps":["trace[167865360] 'process raft request' (duration: 254.65482ms)","trace[167865360] 'compare' (duration: 138.436718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:30.492158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.62107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-02T16:39:30.492286Z","caller":"traceutil/trace.go:171","msg":"trace[35327454] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:325; }","duration":"264.787644ms","start":"2026-04-02T16:39:30.227484Z","end":"2026-04-02T16:39:30.492272Z","steps":["trace[35327454] 'agreement among raft nodes before linearized reading' (duration: 264.501197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:30.492162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:30.097826Z","time spent":"394.277068ms","remote":"127.0.0.1:52464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:30.491934Z","caller":"traceutil/trace.go:171","msg":"trace[732282529] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:325; }","duration":"115.255579ms","start":"2026-04-02T16:39:30.376653Z","end":"2026-04-02T16:39:30.491909Z","steps":["trace[732282529] 'agreement among raft nodes before linearized reading' (duration: 115.060634ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:30.492007Z","caller":"traceutil/trace.go:171","msg":"trace[1552836168] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:325; }","duration":"365.445959ms","start":"2026-04-02T16:39:30.12652Z","end":"2026-04-02T16:39:30.491966Z","steps":["trace[1552836168] 'agreement among raft nodes before linearized reading' (duration: 365.246754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:30.492559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:30.126504Z","time spent":"366.039263ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-02T16:39:30.491893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.188358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-02T16:39:30.492684Z","caller":"traceutil/trace.go:171","msg":"trace[180360127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:325; }","duration":"317.046119ms","start":"2026-04-02T16:39:30.175627Z","end":"2026-04-02T16:39:30.492673Z","steps":["trace[180360127] 'agreement among raft nodes before linearized reading' (duration: 316.084025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:30.492739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:30.175604Z","time spent":"317.111181ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-04-02T16:39:36.474221Z","caller":"traceutil/trace.go:171","msg":"trace[1702894048] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"148.855058ms","start":"2026-04-02T16:39:36.325336Z","end":"2026-04-02T16:39:36.474191Z","steps":["trace[1702894048] 'process raft request' (duration: 66.514765ms)","trace[1702894048] 'compare' (duration: 82.20478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:45.856893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339764475878611,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T16:39:46.169236Z","caller":"traceutil/trace.go:171","msg":"trace[1129296797] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:456; }","duration":"813.182914ms","start":"2026-04-02T16:39:45.356028Z","end":"2026-04-02T16:39:46.169211Z","steps":["trace[1129296797] 'read index received' (duration: 812.984578ms)","trace[1129296797] 'applied index is now lower than readState.Index' (duration: 197.446µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:46.169563Z","caller":"traceutil/trace.go:171","msg":"trace[224539247] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"914.153353ms","start":"2026-04-02T16:39:45.255395Z","end":"2026-04-02T16:39:46.169548Z","steps":["trace[224539247] 'process raft request' (duration: 913.692079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:46.169928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.934317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:39:46.169977Z","caller":"traceutil/trace.go:171","msg":"trace[418937762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:442; }","duration":"168.011779ms","start":"2026-04-02T16:39:46.001952Z","end":"2026-04-02T16:39:46.169964Z","steps":["trace[418937762] 'agreement among raft nodes before linearized reading' (duration: 167.916186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:46.170154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.136662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:39:46.170191Z","caller":"traceutil/trace.go:171","msg":"trace[2006729194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"814.205594ms","start":"2026-04-02T16:39:45.355976Z","end":"2026-04-02T16:39:46.170181Z","steps":["trace[2006729194] 'agreement among raft nodes before linearized reading' (duration: 814.156442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:46.170219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:45.355937Z","time spent":"814.274097ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:39:46.171062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:45.255375Z","time spent":"914.228744ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:39:46.535806Z","caller":"traceutil/trace.go:171","msg":"trace[1947844225] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:457; }","duration":"178.479428ms","start":"2026-04-02T16:39:46.357301Z","end":"2026-04-02T16:39:46.53578Z","steps":["trace[1947844225] 'read index received' (duration: 176.097395ms)","trace[1947844225] 'applied index is now lower than readState.Index' (duration: 2.380963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:46.535941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.624631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:39:46.535974Z","caller":"traceutil/trace.go:171","msg":"trace[1909820763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:443; }","duration":"178.721945ms","start":"2026-04-02T16:39:46.357241Z","end":"2026-04-02T16:39:46.535963Z","steps":["trace[1909820763] 'agreement among raft nodes before linearized reading' (duration: 178.640443ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:46.536107Z","caller":"traceutil/trace.go:171","msg":"trace[1692997846] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"361.497292ms","start":"2026-04-02T16:39:46.174578Z","end":"2026-04-02T16:39:46.536075Z","steps":["trace[1692997846] 'process raft request' (duration: 358.81552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:46.536268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:46.174557Z","time spent":"361.620075ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:39:51.150852Z","caller":"traceutil/trace.go:171","msg":"trace[1815014652] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"469.690919ms","start":"2026-04-02T16:39:50.681112Z","end":"2026-04-02T16:39:51.150803Z","steps":["trace[1815014652] 'process raft request' (duration: 469.489013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:51.15105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.681086Z","time spent":"469.878525ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:51.160053Z","caller":"traceutil/trace.go:171","msg":"trace[544677059] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"474.967659ms","start":"2026-04-02T16:39:50.685052Z","end":"2026-04-02T16:39:51.160019Z","steps":["trace[544677059] 'process raft request' (duration: 474.71806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:51.160264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.685037Z","time spent":"475.164136ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:51.160395Z","caller":"traceutil/trace.go:171","msg":"trace[1780586219] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"466.151912ms","start":"2026-04-02T16:39:50.694219Z","end":"2026-04-02T16:39:51.160371Z","steps":["trace[1780586219] 'process raft request' (duration: 465.848612ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:51.160418Z","caller":"traceutil/trace.go:171","msg":"trace[1728574624] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"466.070889ms","start":"2026-04-02T16:39:50.694282Z","end":"2026-04-02T16:39:51.160353Z","steps":["trace[1728574624] 'process raft request' (duration: 465.830952ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:51.160426Z","caller":"traceutil/trace.go:171","msg":"trace[1406746323] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"466.202094ms","start":"2026-04-02T16:39:50.694166Z","end":"2026-04-02T16:39:51.160368Z","steps":["trace[1406746323] 'process raft request' (duration: 465.839092ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:51.160571Z","caller":"traceutil/trace.go:171","msg":"trace[311271525] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"457.19019ms","start":"2026-04-02T16:39:50.703371Z","end":"2026-04-02T16:39:51.160561Z","steps":["trace[311271525] 'process raft request' (duration: 456.85978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:51.160611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.703361Z","time spent":"457.230851ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:51.160623Z","caller":"traceutil/trace.go:171","msg":"trace[191078422] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:503; }","duration":"312.122532ms","start":"2026-04-02T16:39:50.848461Z","end":"2026-04-02T16:39:51.160583Z","steps":["trace[191078422] 'read index received' (duration: 302.207887ms)","trace[191078422] 'applied index is now lower than readState.Index' (duration: 9.913435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:51.160714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.694275Z","time spent":"466.234514ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:39:51.160735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.694149Z","time spent":"466.42105ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:51.160731Z","caller":"traceutil/trace.go:171","msg":"trace[462959011] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"434.049813ms","start":"2026-04-02T16:39:50.726671Z","end":"2026-04-02T16:39:51.16072Z","steps":["trace[462959011] 'process raft request' (duration: 433.64536ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:51.160828Z","caller":"traceutil/trace.go:171","msg":"trace[1399502820] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"468.95216ms","start":"2026-04-02T16:39:50.691865Z","end":"2026-04-02T16:39:51.160817Z","steps":["trace[1399502820] 'process raft request' (duration: 468.100573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:51.160868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.726655Z","time spent":"434.139895ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:51.160883Z","caller":"traceutil/trace.go:171","msg":"trace[794367792] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"466.436031ms","start":"2026-04-02T16:39:50.694421Z","end":"2026-04-02T16:39:51.160857Z","steps":["trace[794367792] 'process raft request' (duration: 465.736409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:51.160932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.69184Z","time spent":"469.033713ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:39:51.160947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.694416Z","time spent":"466.496913ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:51.161011Z","caller":"traceutil/trace.go:171","msg":"trace[257800059] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"332.398059ms","start":"2026-04-02T16:39:50.828584Z","end":"2026-04-02T16:39:51.160982Z","steps":["trace[257800059] 'process raft request' (duration: 331.916514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:51.160501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.694216Z","time spent":"466.228684ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:39:51.161073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.604467ms","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-04-02T16:39:51.161086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.828555Z","time spent":"332.483901ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:51.161049Z","caller":"traceutil/trace.go:171","msg":"trace[192652001] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"429.17346ms","start":"2026-04-02T16:39:50.73186Z","end":"2026-04-02T16:39:51.161033Z","steps":["trace[192652001] 'process raft request' (duration: 428.525549ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:51.161114Z","caller":"traceutil/trace.go:171","msg":"trace[1926064280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"312.675739ms","start":"2026-04-02T16:39:50.848428Z","end":"2026-04-02T16:39:51.161104Z","steps":["trace[1926064280] 'agreement among raft nodes before linearized reading' (duration: 312.573796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:51.161152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.848414Z","time spent":"312.728891ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T16:39:51.161221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.731852Z","time spent":"429.340385ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:51.16032Z","caller":"traceutil/trace.go:171","msg":"trace[1016826638] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"475.245448ms","start":"2026-04-02T16:39:50.685056Z","end":"2026-04-02T16:39:51.160302Z","steps":["trace[1016826638] 'process raft request' (duration: 474.865926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:51.161544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:50.685032Z","time spent":"476.457386ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:39:51.176556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.788163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:39:51.176627Z","caller":"traceutil/trace.go:171","msg":"trace[240482152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:502; }","duration":"128.897796ms","start":"2026-04-02T16:39:51.04772Z","end":"2026-04-02T16:39:51.176618Z","steps":["trace[240482152] 'agreement among raft nodes before linearized reading' (duration: 128.787413ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:51.426116Z","caller":"traceutil/trace.go:171","msg":"trace[96539936] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"168.305606ms","start":"2026-04-02T16:39:51.257775Z","end":"2026-04-02T16:39:51.42608Z","steps":["trace[96539936] 'process raft request' (duration: 162.397678ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:51.426374Z","caller":"traceutil/trace.go:171","msg":"trace[1050100800] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"141.455575ms","start":"2026-04-02T16:39:51.284876Z","end":"2026-04-02T16:39:51.426332Z","steps":["trace[1050100800] 'process raft request' (duration: 141.142524ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:51.426676Z","caller":"traceutil/trace.go:171","msg":"trace[2066351003] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"126.836012ms","start":"2026-04-02T16:39:51.299823Z","end":"2026-04-02T16:39:51.426659Z","steps":["trace[2066351003] 'process raft request' (duration: 126.598014ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:51.426857Z","caller":"traceutil/trace.go:171","msg":"trace[2002537269] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"140.834525ms","start":"2026-04-02T16:39:51.286007Z","end":"2026-04-02T16:39:51.426841Z","steps":["trace[2002537269] 'process raft request' (duration: 140.266097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:53.078996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.024792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570096"} {"level":"info","ts":"2026-04-02T16:39:53.079077Z","caller":"traceutil/trace.go:171","msg":"trace[59248846] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:561; }","duration":"753.113844ms","start":"2026-04-02T16:39:52.325944Z","end":"2026-04-02T16:39:53.079058Z","steps":["trace[59248846] 'range keys from in-memory index tree' (duration: 752.70234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:53.079116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:52.325898Z","time spent":"753.207847ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":570118,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-02T16:39:53.079165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.218686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"warn","ts":"2026-04-02T16:39:53.079201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:52.440716Z","time spent":"638.47707ms","remote":"127.0.0.1:50278","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-02T16:39:53.079239Z","caller":"traceutil/trace.go:171","msg":"trace[1470318348] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:561; }","duration":"747.326759ms","start":"2026-04-02T16:39:52.331884Z","end":"2026-04-02T16:39:53.079211Z","steps":["trace[1470318348] 'range keys from in-memory index tree' (duration: 747.073701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:53.07928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:52.331865Z","time spent":"747.406811ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7730,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-02T16:39:53.087563Z","caller":"traceutil/trace.go:171","msg":"trace[1565347154] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"452.745905ms","start":"2026-04-02T16:39:52.634799Z","end":"2026-04-02T16:39:53.087545Z","steps":["trace[1565347154] 'read index received' (duration: 452.246468ms)","trace[1565347154] 'applied index is now lower than readState.Index' (duration: 498.587µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:53.087627Z","caller":"traceutil/trace.go:171","msg":"trace[652733743] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"720.716241ms","start":"2026-04-02T16:39:52.366894Z","end":"2026-04-02T16:39:53.08761Z","steps":["trace[652733743] 'process raft request' (duration: 720.327198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:53.08768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.867359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:39:53.08771Z","caller":"traceutil/trace.go:171","msg":"trace[197740255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"452.927781ms","start":"2026-04-02T16:39:52.634774Z","end":"2026-04-02T16:39:53.087702Z","steps":["trace[197740255] 'agreement among raft nodes before linearized reading' (duration: 452.861009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:53.087722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:52.366874Z","time spent":"720.791884ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:39:53.087737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:52.634759Z","time spent":"452.971942ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T16:39:57.285348Z","caller":"traceutil/trace.go:171","msg":"trace[1823550079] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"118.626193ms","start":"2026-04-02T16:39:57.166674Z","end":"2026-04-02T16:39:57.2853Z","steps":["trace[1823550079] 'process raft request' (duration: 117.807746ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:57.706537Z","caller":"traceutil/trace.go:171","msg":"trace[1300364618] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"414.898043ms","start":"2026-04-02T16:39:57.2916Z","end":"2026-04-02T16:39:57.706498Z","steps":["trace[1300364618] 'process raft request' (duration: 366.772673ms)","trace[1300364618] 'compare' (duration: 47.935904ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:57.706585Z","caller":"traceutil/trace.go:171","msg":"trace[1240654515] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"414.8128ms","start":"2026-04-02T16:39:57.291744Z","end":"2026-04-02T16:39:57.706557Z","steps":["trace[1240654515] 'process raft request' (duration: 414.699336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:57.706731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:57.291575Z","time spent":"415.071469ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:39:57.706774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:57.291732Z","time spent":"414.951645ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:39:57.706855Z","caller":"traceutil/trace.go:171","msg":"trace[2136316848] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"350.752921ms","start":"2026-04-02T16:39:57.356077Z","end":"2026-04-02T16:39:57.706829Z","steps":["trace[2136316848] 'read index received' (duration: 302.346001ms)","trace[2136316848] 'applied index is now lower than readState.Index' (duration: 48.40576ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:57.706881Z","caller":"traceutil/trace.go:171","msg":"trace[120459674] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"346.062215ms","start":"2026-04-02T16:39:57.360808Z","end":"2026-04-02T16:39:57.70687Z","steps":["trace[120459674] 'process raft request' (duration: 345.684202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:57.706955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:57.360789Z","time spent":"346.140128ms","remote":"127.0.0.1:52698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:39:57.707108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.846182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5sm59\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-02T16:39:57.707154Z","caller":"traceutil/trace.go:171","msg":"trace[2145725279] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5sm59; range_end:; response_count:1; response_revision:596; }","duration":"337.933265ms","start":"2026-04-02T16:39:57.369206Z","end":"2026-04-02T16:39:57.707139Z","steps":["trace[2145725279] 'agreement among raft nodes before linearized reading' (duration: 337.808471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:57.707188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:57.369188Z","time spent":"337.991557ms","remote":"127.0.0.1:52524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-5sm59\" "} {"level":"warn","ts":"2026-04-02T16:39:57.708195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.115657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:39:57.708252Z","caller":"traceutil/trace.go:171","msg":"trace[2044639686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"352.205199ms","start":"2026-04-02T16:39:57.356031Z","end":"2026-04-02T16:39:57.708236Z","steps":["trace[2044639686] 'agreement among raft nodes before linearized reading' (duration: 350.934948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:57.708296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:57.356012Z","time spent":"352.276801ms","remote":"127.0.0.1:52358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:39:59.331205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.453756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:39:59.331421Z","caller":"traceutil/trace.go:171","msg":"trace[1009343163] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.609031214s","start":"2026-04-02T16:39:57.722353Z","end":"2026-04-02T16:39:59.331384Z","steps":["trace[1009343163] 'process raft request' (duration: 610.236067ms)","trace[1009343163] 'compare' (duration: 998.324871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:59.331645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:57.722342Z","time spent":"1.609210451s","remote":"127.0.0.1:52524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:39:59.332409Z","caller":"traceutil/trace.go:171","msg":"trace[1911905714] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.248830181s","start":"2026-04-02T16:39:58.083553Z","end":"2026-04-02T16:39:59.332383Z","steps":["trace[1911905714] 'process raft request' (duration: 1.248109966s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:59.332962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:58.083521Z","time spent":"1.248954595s","remote":"127.0.0.1:52450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:39:59.332521Z","caller":"traceutil/trace.go:171","msg":"trace[164708280] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"1.058715034s","start":"2026-04-02T16:39:58.273774Z","end":"2026-04-02T16:39:59.332489Z","steps":["trace[164708280] 'read index received' (duration: 58.956174ms)","trace[164708280] 'applied index is now lower than readState.Index' (duration: 999.75541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:59.361125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005929773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:39:59.361209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087435373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T16:39:59.361235Z","caller":"traceutil/trace.go:171","msg":"trace[1143242172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"1.006072388s","start":"2026-04-02T16:39:58.355147Z","end":"2026-04-02T16:39:59.36122Z","steps":["trace[1143242172] 'agreement among raft nodes before linearized reading' (duration: 1.005919333s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:59.361308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.921634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:39:59.361333Z","caller":"traceutil/trace.go:171","msg":"trace[1966332852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"226.986126ms","start":"2026-04-02T16:39:59.134341Z","end":"2026-04-02T16:39:59.361327Z","steps":["trace[1966332852] 'agreement among raft nodes before linearized reading' (duration: 226.940074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:59.361312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:58.355137Z","time spent":"1.006168261s","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:39:59.361257Z","caller":"traceutil/trace.go:171","msg":"trace[391857902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"1.087520936s","start":"2026-04-02T16:39:58.273722Z","end":"2026-04-02T16:39:59.361243Z","steps":["trace[391857902] 'agreement among raft nodes before linearized reading' (duration: 1.059985047s)","trace[391857902] 'range keys from in-memory index tree' (duration: 27.288801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:59.361641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:58.273701Z","time spent":"1.08793172s","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T16:40:00.779386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.829476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:40:00.779432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.097835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:00.779455Z","caller":"traceutil/trace.go:171","msg":"trace[688994419] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:620; }","duration":"166.94795ms","start":"2026-04-02T16:40:00.612488Z","end":"2026-04-02T16:40:00.779436Z","steps":["trace[688994419] 'range keys from in-memory index tree' (duration: 166.746803ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:00.779458Z","caller":"traceutil/trace.go:171","msg":"trace[26422894] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:620; }","duration":"142.135677ms","start":"2026-04-02T16:40:00.637312Z","end":"2026-04-02T16:40:00.779448Z","steps":["trace[26422894] 'range keys from in-memory index tree' (duration: 142.047834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:00.779391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.433708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:40:00.779613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.625628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:00.779623Z","caller":"traceutil/trace.go:171","msg":"trace[987005249] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:620; }","duration":"142.706707ms","start":"2026-04-02T16:40:00.636906Z","end":"2026-04-02T16:40:00.779613Z","steps":["trace[987005249] 'range keys from in-memory index tree' (duration: 142.359106ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:00.779661Z","caller":"traceutil/trace.go:171","msg":"trace[171051422] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:620; }","duration":"166.669871ms","start":"2026-04-02T16:40:00.612979Z","end":"2026-04-02T16:40:00.779648Z","steps":["trace[171051422] 'range keys from in-memory index tree' (duration: 166.580767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:00.779247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.379155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:00.779821Z","caller":"traceutil/trace.go:171","msg":"trace[253053996] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:620; }","duration":"142.987896ms","start":"2026-04-02T16:40:00.636826Z","end":"2026-04-02T16:40:00.779814Z","steps":["trace[253053996] 'count revisions from in-memory index tree' (duration: 142.322974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:00.779872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.26365ms","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-04-02T16:40:00.780006Z","caller":"traceutil/trace.go:171","msg":"trace[1477785843] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:620; }","duration":"167.393066ms","start":"2026-04-02T16:40:00.612552Z","end":"2026-04-02T16:40:00.779945Z","steps":["trace[1477785843] 'count revisions from in-memory index tree' (duration: 167.191218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:14.056739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.821893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:40:14.056823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.112249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-j8vmg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-02T16:40:14.056872Z","caller":"traceutil/trace.go:171","msg":"trace[1278385460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"698.041112ms","start":"2026-04-02T16:40:13.358806Z","end":"2026-04-02T16:40:14.056847Z","steps":["trace[1278385460] 'range keys from in-memory index tree' (duration: 696.500094ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:14.056924Z","caller":"traceutil/trace.go:171","msg":"trace[1672584311] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-j8vmg; range_end:; response_count:1; response_revision:757; }","duration":"596.253554ms","start":"2026-04-02T16:40:13.460649Z","end":"2026-04-02T16:40:14.056902Z","steps":["trace[1672584311] 'range keys from in-memory index tree' (duration: 595.935632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:14.056947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:13.35878Z","time spent":"698.154156ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:40:14.05697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:13.460633Z","time spent":"596.326477ms","remote":"127.0.0.1:52524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-j8vmg\" "} {"level":"warn","ts":"2026-04-02T16:40:14.057021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.71715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:40:14.057072Z","caller":"traceutil/trace.go:171","msg":"trace[722961874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"630.810304ms","start":"2026-04-02T16:40:13.42625Z","end":"2026-04-02T16:40:14.05706Z","steps":["trace[722961874] 'range keys from in-memory index tree' (duration: 630.585925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:14.057101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:13.426227Z","time spent":"630.862075ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T16:40:14.057176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.736464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T16:40:14.057235Z","caller":"traceutil/trace.go:171","msg":"trace[355102430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:757; }","duration":"630.283964ms","start":"2026-04-02T16:40:13.426933Z","end":"2026-04-02T16:40:14.057217Z","steps":["trace[355102430] 'range keys from in-memory index tree' (duration: 628.3ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:14.057294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:13.42691Z","time spent":"630.369758ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T16:40:14.061158Z","caller":"traceutil/trace.go:171","msg":"trace[1637387471] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"230.605239ms","start":"2026-04-02T16:40:13.830507Z","end":"2026-04-02T16:40:14.061112Z","steps":["trace[1637387471] 'read index received' (duration: 230.430002ms)","trace[1637387471] 'applied index is now lower than readState.Index' (duration: 174.217µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:14.061505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.982843ms","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-04-02T16:40:14.061525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.600849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T16:40:14.06157Z","caller":"traceutil/trace.go:171","msg":"trace[371744187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"231.093717ms","start":"2026-04-02T16:40:13.83046Z","end":"2026-04-02T16:40:14.061554Z","steps":["trace[371744187] 'agreement among raft nodes before linearized reading' (duration: 230.877408ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:14.061597Z","caller":"traceutil/trace.go:171","msg":"trace[852640869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"230.718284ms","start":"2026-04-02T16:40:13.830865Z","end":"2026-04-02T16:40:14.061583Z","steps":["trace[852640869] 'agreement among raft nodes before linearized reading' (duration: 230.536357ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:14.061695Z","caller":"traceutil/trace.go:171","msg":"trace[829752025] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"270.593578ms","start":"2026-04-02T16:40:13.79109Z","end":"2026-04-02T16:40:14.061684Z","steps":["trace[829752025] 'process raft request' (duration: 269.905002ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:20.67865Z","caller":"traceutil/trace.go:171","msg":"trace[2133754513] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"138.061574ms","start":"2026-04-02T16:40:20.540562Z","end":"2026-04-02T16:40:20.678624Z","steps":["trace[2133754513] 'process raft request' (duration: 137.894388ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:20.805813Z","caller":"traceutil/trace.go:171","msg":"trace[636962626] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"120.84433ms","start":"2026-04-02T16:40:20.684943Z","end":"2026-04-02T16:40:20.805787Z","steps":["trace[636962626] 'process raft request' (duration: 42.92921ms)","trace[636962626] 'compare' (duration: 77.801636ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:20.806621Z","caller":"traceutil/trace.go:171","msg":"trace[206252224] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"120.949014ms","start":"2026-04-02T16:40:20.685657Z","end":"2026-04-02T16:40:20.806606Z","steps":["trace[206252224] 'process raft request' (duration: 120.707215ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:20.806605Z","caller":"traceutil/trace.go:171","msg":"trace[123916537] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"116.946371ms","start":"2026-04-02T16:40:20.689638Z","end":"2026-04-02T16:40:20.806585Z","steps":["trace[123916537] 'process raft request' (duration: 116.890059ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:20.995663Z","caller":"traceutil/trace.go:171","msg":"trace[2128763216] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"128.294744ms","start":"2026-04-02T16:40:20.867342Z","end":"2026-04-02T16:40:20.995636Z","steps":["trace[2128763216] 'process raft request' (duration: 127.738533ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:21.146452Z","caller":"traceutil/trace.go:171","msg":"trace[194067226] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"146.080764ms","start":"2026-04-02T16:40:21.000352Z","end":"2026-04-02T16:40:21.146433Z","steps":["trace[194067226] 'read index received' (duration: 118.600356ms)","trace[194067226] 'applied index is now lower than readState.Index' (duration: 27.479298ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:21.146581Z","caller":"traceutil/trace.go:171","msg":"trace[1929638223] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"146.279821ms","start":"2026-04-02T16:40:21.000288Z","end":"2026-04-02T16:40:21.146568Z","steps":["trace[1929638223] 'process raft request' (duration: 118.683628ms)","trace[1929638223] 'compare' (duration: 27.349384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:21.146842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.463819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-q5p7c\" ","response":"range_response_count:1 size:4777"} {"level":"info","ts":"2026-04-02T16:40:21.146926Z","caller":"traceutil/trace.go:171","msg":"trace[1452970721] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-q5p7c; range_end:; response_count:1; response_revision:836; }","duration":"146.582823ms","start":"2026-04-02T16:40:21.000324Z","end":"2026-04-02T16:40:21.146907Z","steps":["trace[1452970721] 'agreement among raft nodes before linearized reading' (duration: 146.367585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:21.147186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.76407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-mq9kt\" ","response":"range_response_count:1 size:4777"} {"level":"info","ts":"2026-04-02T16:40:21.147315Z","caller":"traceutil/trace.go:171","msg":"trace[1606122932] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-mq9kt; range_end:; response_count:1; response_revision:836; }","duration":"146.914326ms","start":"2026-04-02T16:40:21.000387Z","end":"2026-04-02T16:40:21.147301Z","steps":["trace[1606122932] 'agreement among raft nodes before linearized reading' (duration: 146.357365ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:27.323996Z","caller":"traceutil/trace.go:171","msg":"trace[1515106101] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"151.382982ms","start":"2026-04-02T16:40:27.172581Z","end":"2026-04-02T16:40:27.323964Z","steps":["trace[1515106101] 'process raft request' (duration: 151.260997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.345986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.165451ms","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-04-02T16:40:28.346065Z","caller":"traceutil/trace.go:171","msg":"trace[1417158429] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:915; }","duration":"576.291736ms","start":"2026-04-02T16:40:27.769757Z","end":"2026-04-02T16:40:28.346048Z","steps":["trace[1417158429] 'count revisions from in-memory index tree' (duration: 576.112198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.346091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.593722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lwz2s\" ","response":"range_response_count:1 size:5674"} {"level":"warn","ts":"2026-04-02T16:40:28.346106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:27.769744Z","time spent":"576.351018ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-02T16:40:28.346168Z","caller":"traceutil/trace.go:171","msg":"trace[361930870] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lwz2s; range_end:; response_count:1; response_revision:915; }","duration":"627.727367ms","start":"2026-04-02T16:40:27.718405Z","end":"2026-04-02T16:40:28.346133Z","steps":["trace[361930870] 'range keys from in-memory index tree' (duration: 627.463137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.346209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:27.718393Z","time spent":"627.801781ms","remote":"127.0.0.1:52524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5696,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lwz2s\" "} {"level":"info","ts":"2026-04-02T16:40:28.411926Z","caller":"traceutil/trace.go:171","msg":"trace[85340633] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"247.767179ms","start":"2026-04-02T16:40:28.164128Z","end":"2026-04-02T16:40:28.411895Z","steps":["trace[85340633] 'read index received' (duration: 247.633024ms)","trace[85340633] 'applied index is now lower than readState.Index' (duration: 132.765µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:28.412071Z","caller":"traceutil/trace.go:171","msg":"trace[411548498] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"414.756036ms","start":"2026-04-02T16:40:27.997286Z","end":"2026-04-02T16:40:28.412042Z","steps":["trace[411548498] 'process raft request' (duration: 414.462985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.412237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:27.997255Z","time spent":"414.885171ms","remote":"127.0.0.1:52522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:40:28.41225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.117073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T16:40:28.412304Z","caller":"traceutil/trace.go:171","msg":"trace[1851151298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:916; }","duration":"248.208477ms","start":"2026-04-02T16:40:28.164078Z","end":"2026-04-02T16:40:28.412287Z","steps":["trace[1851151298] 'agreement among raft nodes before linearized reading' (duration: 247.952327ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:28.797262Z","caller":"traceutil/trace.go:171","msg":"trace[1528542454] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"440.53307ms","start":"2026-04-02T16:40:28.356703Z","end":"2026-04-02T16:40:28.797236Z","steps":["trace[1528542454] 'process raft request' (duration: 435.509883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.797361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.24585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T16:40:28.797387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:28.356684Z","time spent":"440.637515ms","remote":"127.0.0.1:52524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5469,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:28.797411Z","caller":"traceutil/trace.go:171","msg":"trace[1240909094] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"432.532087ms","start":"2026-04-02T16:40:28.364837Z","end":"2026-04-02T16:40:28.797369Z","steps":["trace[1240909094] 'process raft request' (duration: 432.342699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.797443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.562761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:40:28.797513Z","caller":"traceutil/trace.go:171","msg":"trace[1908796112] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:944; }","duration":"385.227748ms","start":"2026-04-02T16:40:28.412008Z","end":"2026-04-02T16:40:28.797236Z","steps":["trace[1908796112] 'read index received' (duration: 380.16585ms)","trace[1908796112] 'applied index is now lower than readState.Index' (duration: 5.060598ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:28.797525Z","caller":"traceutil/trace.go:171","msg":"trace[1154035292] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:918; }","duration":"551.698436ms","start":"2026-04-02T16:40:28.245807Z","end":"2026-04-02T16:40:28.797505Z","steps":["trace[1154035292] 'agreement among raft nodes before linearized reading' (duration: 551.486908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.79755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.159797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:40:28.797403Z","caller":"traceutil/trace.go:171","msg":"trace[381907336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"631.330543ms","start":"2026-04-02T16:40:28.16606Z","end":"2026-04-02T16:40:28.79739Z","steps":["trace[381907336] 'agreement among raft nodes before linearized reading' (duration: 631.180678ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:28.797579Z","caller":"traceutil/trace.go:171","msg":"trace[988871992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"630.199839ms","start":"2026-04-02T16:40:28.167372Z","end":"2026-04-02T16:40:28.797571Z","steps":["trace[988871992] 'agreement among raft nodes before linearized reading' (duration: 630.130856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.797585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:28.364824Z","time spent":"432.670073ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:40:28.797606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:28.167365Z","time spent":"630.23261ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T16:40:28.797589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:28.245795Z","time spent":"551.781469ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:40:28.797603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:28.166044Z","time spent":"631.548672ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T16:40:28.797486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.720771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:40:28.797745Z","caller":"traceutil/trace.go:171","msg":"trace[1295282492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"627.988762ms","start":"2026-04-02T16:40:28.169745Z","end":"2026-04-02T16:40:28.797734Z","steps":["trace[1295282492] 'agreement among raft nodes before linearized reading' (duration: 627.67591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.797777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:28.169739Z","time spent":"628.028363ms","remote":"127.0.0.1:52594","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":"info","ts":"2026-04-02T16:40:28.863881Z","caller":"traceutil/trace.go:171","msg":"trace[193845293] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"447.505844ms","start":"2026-04-02T16:40:28.41635Z","end":"2026-04-02T16:40:28.863856Z","steps":["trace[193845293] 'process raft request' (duration: 447.341578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.863923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.407269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:28.863994Z","caller":"traceutil/trace.go:171","msg":"trace[191676276] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:919; }","duration":"421.545695ms","start":"2026-04-02T16:40:28.442431Z","end":"2026-04-02T16:40:28.863977Z","steps":["trace[191676276] 'agreement among raft nodes before linearized reading' (duration: 421.399979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:28.864038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:28.442411Z","time spent":"421.616947ms","remote":"127.0.0.1:52818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:40:28.864099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:28.416328Z","time spent":"447.641168ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:29.057433Z","caller":"traceutil/trace.go:171","msg":"trace[969282398] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"254.970012ms","start":"2026-04-02T16:40:28.802438Z","end":"2026-04-02T16:40:29.057408Z","steps":["trace[969282398] 'process raft request' (duration: 254.70679ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:29.058027Z","caller":"traceutil/trace.go:171","msg":"trace[34748221] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"255.398768ms","start":"2026-04-02T16:40:28.8026Z","end":"2026-04-02T16:40:29.057999Z","steps":["trace[34748221] 'process raft request' (duration: 254.747511ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:29.058137Z","caller":"traceutil/trace.go:171","msg":"trace[1839242771] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"253.448221ms","start":"2026-04-02T16:40:28.804672Z","end":"2026-04-02T16:40:29.058121Z","steps":["trace[1839242771] 'process raft request' (duration: 253.389499ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:29.058358Z","caller":"traceutil/trace.go:171","msg":"trace[1456499531] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"254.321025ms","start":"2026-04-02T16:40:28.804025Z","end":"2026-04-02T16:40:29.058346Z","steps":["trace[1456499531] 'process raft request' (duration: 253.94044ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:29.058379Z","caller":"traceutil/trace.go:171","msg":"trace[2042782491] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"254.094387ms","start":"2026-04-02T16:40:28.804268Z","end":"2026-04-02T16:40:29.058363Z","steps":["trace[2042782491] 'process raft request' (duration: 253.742613ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:29.058463Z","caller":"traceutil/trace.go:171","msg":"trace[1087749963] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"254.596786ms","start":"2026-04-02T16:40:28.803851Z","end":"2026-04-02T16:40:29.058448Z","steps":["trace[1087749963] 'process raft request' (duration: 253.963821ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:29.200855Z","caller":"traceutil/trace.go:171","msg":"trace[1383506977] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"134.4443ms","start":"2026-04-02T16:40:29.066388Z","end":"2026-04-02T16:40:29.200832Z","steps":["trace[1383506977] 'process raft request' (duration: 127.946793ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:44.675546Z","caller":"traceutil/trace.go:171","msg":"trace[293357403] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"109.767575ms","start":"2026-04-02T16:40:44.565755Z","end":"2026-04-02T16:40:44.675522Z","steps":["trace[293357403] 'process raft request' (duration: 109.532814ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:41:49.237962Z","caller":"traceutil/trace.go:171","msg":"trace[1871593686] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"104.971877ms","start":"2026-04-02T16:41:49.132966Z","end":"2026-04-02T16:41:49.237938Z","steps":["trace[1871593686] 'process raft request' (duration: 104.861632ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:41:55.727618Z","caller":"traceutil/trace.go:171","msg":"trace[1679971044] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"162.739913ms","start":"2026-04-02T16:41:55.56485Z","end":"2026-04-02T16:41:55.72759Z","steps":["trace[1679971044] 'process raft request' (duration: 162.43988ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:03.88552Z","caller":"traceutil/trace.go:171","msg":"trace[1402530235] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"121.249692ms","start":"2026-04-02T16:42:03.764244Z","end":"2026-04-02T16:42:03.885494Z","steps":["trace[1402530235] 'process raft request' (duration: 120.800641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:22.065645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.527298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"warn","ts":"2026-04-02T16:42:22.065736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.321234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-04-02T16:42:22.065755Z","caller":"traceutil/trace.go:171","msg":"trace[134056788] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:1352; }","duration":"310.668314ms","start":"2026-04-02T16:42:21.755054Z","end":"2026-04-02T16:42:22.065722Z","steps":["trace[134056788] 'range keys from in-memory index tree' (duration: 310.442264ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:22.065802Z","caller":"traceutil/trace.go:171","msg":"trace[2016530406] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:1352; }","duration":"333.429879ms","start":"2026-04-02T16:42:21.732356Z","end":"2026-04-02T16:42:22.065786Z","steps":["trace[2016530406] 'range keys from in-memory index tree' (duration: 333.196579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:22.065815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:21.755037Z","time spent":"310.759599ms","remote":"127.0.0.1:52692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":734,"request content":"key:\"/registry/clusterroles/system:node-proxier\" "} {"level":"warn","ts":"2026-04-02T16:42:22.065833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:21.732347Z","time spent":"333.480512ms","remote":"127.0.0.1:52692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":677,"request content":"key:\"/registry/clusterroles/system:node-problem-detector\" "} {"level":"info","ts":"2026-04-02T16:42:37.868257Z","caller":"traceutil/trace.go:171","msg":"trace[930429852] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1461; }","duration":"104.470444ms","start":"2026-04-02T16:42:37.763766Z","end":"2026-04-02T16:42:37.868236Z","steps":["trace[930429852] 'read index received' (duration: 104.298125ms)","trace[930429852] 'applied index is now lower than readState.Index' (duration: 171.399µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:37.86838Z","caller":"traceutil/trace.go:171","msg":"trace[1689849811] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"118.273302ms","start":"2026-04-02T16:42:37.750084Z","end":"2026-04-02T16:42:37.868357Z","steps":["trace[1689849811] 'process raft request' (duration: 118.008433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:37.868473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.695773ms","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-04-02T16:42:37.868516Z","caller":"traceutil/trace.go:171","msg":"trace[1548743416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1405; }","duration":"104.767572ms","start":"2026-04-02T16:42:37.763736Z","end":"2026-04-02T16:42:37.868504Z","steps":["trace[1548743416] 'agreement among raft nodes before linearized reading' (duration: 104.616733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:38.10689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.386779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:42:38.107031Z","caller":"traceutil/trace.go:171","msg":"trace[372686438] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"234.304774ms","start":"2026-04-02T16:42:37.872708Z","end":"2026-04-02T16:42:38.107013Z","steps":["trace[372686438] 'process raft request' (duration: 120.677557ms)","trace[372686438] 'compare' (duration: 113.295549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:42:38.107101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.995208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:42:38.107159Z","caller":"traceutil/trace.go:171","msg":"trace[1422269128] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1406; }","duration":"217.053928ms","start":"2026-04-02T16:42:37.890089Z","end":"2026-04-02T16:42:38.107143Z","steps":["trace[1422269128] 'agreement among raft nodes before linearized reading' (duration: 216.966768ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:38.107023Z","caller":"traceutil/trace.go:171","msg":"trace[1520563990] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"216.902008ms","start":"2026-04-02T16:42:37.890096Z","end":"2026-04-02T16:42:38.106998Z","steps":["trace[1520563990] 'read index received' (duration: 103.318091ms)","trace[1520563990] 'applied index is now lower than readState.Index' (duration: 113.582257ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:38.107417Z","caller":"traceutil/trace.go:171","msg":"trace[1148833251] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1406; }","duration":"100.013268ms","start":"2026-04-02T16:42:38.007397Z","end":"2026-04-02T16:42:38.10741Z","steps":["trace[1148833251] 'agreement among raft nodes before linearized reading' (duration: 100.000578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:38.107396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.429631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-bd2fl\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-04-02T16:42:38.107477Z","caller":"traceutil/trace.go:171","msg":"trace[2127767563] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-bd2fl; range_end:; response_count:1; response_revision:1406; }","duration":"156.52822ms","start":"2026-04-02T16:42:37.950916Z","end":"2026-04-02T16:42:38.107445Z","steps":["trace[2127767563] 'agreement among raft nodes before linearized reading' (duration: 156.347841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:38.107527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.419338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-02T16:42:38.107588Z","caller":"traceutil/trace.go:171","msg":"trace[1755152606] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:1406; }","duration":"155.510368ms","start":"2026-04-02T16:42:37.952058Z","end":"2026-04-02T16:42:38.107569Z","steps":["trace[1755152606] 'agreement among raft nodes before linearized reading' (duration: 155.384158ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:39.727456Z","caller":"traceutil/trace.go:171","msg":"trace[208254509] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"138.341956ms","start":"2026-04-02T16:42:39.589084Z","end":"2026-04-02T16:42:39.727426Z","steps":["trace[208254509] 'process raft request' (duration: 56.990832ms)","trace[208254509] 'compare' (duration: 81.206915ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:40.299593Z","caller":"traceutil/trace.go:171","msg":"trace[1661227746] linearizableReadLoop","detail":"{readStateIndex:1469; appliedIndex:1468; }","duration":"331.372323ms","start":"2026-04-02T16:42:39.968192Z","end":"2026-04-02T16:42:40.299564Z","steps":["trace[1661227746] 'read index received' (duration: 331.229463ms)","trace[1661227746] 'applied index is now lower than readState.Index' (duration: 141.57µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:40.299656Z","caller":"traceutil/trace.go:171","msg":"trace[766311382] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"421.964809ms","start":"2026-04-02T16:42:39.877655Z","end":"2026-04-02T16:42:40.29962Z","steps":["trace[766311382] 'process raft request' (duration: 421.725251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:40.299841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.006589ms","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-04-02T16:42:40.299946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.740241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:6 size:2185"} {"level":"info","ts":"2026-04-02T16:42:40.30003Z","caller":"traceutil/trace.go:171","msg":"trace[723840688] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:6; response_revision:1411; }","duration":"331.82987ms","start":"2026-04-02T16:42:39.968179Z","end":"2026-04-02T16:42:40.300009Z","steps":["trace[723840688] 'agreement among raft nodes before linearized reading' (duration: 331.530892ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:40.299973Z","caller":"traceutil/trace.go:171","msg":"trace[986296259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1411; }","duration":"189.191979ms","start":"2026-04-02T16:42:40.110762Z","end":"2026-04-02T16:42:40.299954Z","steps":["trace[986296259] 'agreement among raft nodes before linearized reading' (duration: 188.95263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:40.300089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:39.968139Z","time spent":"331.93475ms","remote":"127.0.0.1:52478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":6,"response size":2207,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"warn","ts":"2026-04-02T16:42:40.299874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:39.877637Z","time spent":"422.140597ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:42:40.299905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.288377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:42:40.300331Z","caller":"traceutil/trace.go:171","msg":"trace[1999373464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1411; }","duration":"105.743545ms","start":"2026-04-02T16:42:40.194562Z","end":"2026-04-02T16:42:40.300305Z","steps":["trace[1999373464] 'agreement among raft nodes before linearized reading' (duration: 105.242677ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:40.496203Z","caller":"traceutil/trace.go:171","msg":"trace[2052335757] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1469; }","duration":"138.722254ms","start":"2026-04-02T16:42:40.35746Z","end":"2026-04-02T16:42:40.496182Z","steps":["trace[2052335757] 'read index received' (duration: 68.323707ms)","trace[2052335757] 'applied index is now lower than readState.Index' (duration: 70.397357ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:40.49633Z","caller":"traceutil/trace.go:171","msg":"trace[946387012] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"190.808361ms","start":"2026-04-02T16:42:40.305511Z","end":"2026-04-02T16:42:40.496319Z","steps":["trace[946387012] 'process raft request' (duration: 190.636742ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:40.496322Z","caller":"traceutil/trace.go:171","msg":"trace[529934572] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"191.727076ms","start":"2026-04-02T16:42:40.304575Z","end":"2026-04-02T16:42:40.496302Z","steps":["trace[529934572] 'process raft request' (duration: 191.524048ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:40.49652Z","caller":"traceutil/trace.go:171","msg":"trace[373950018] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"192.093204ms","start":"2026-04-02T16:42:40.304413Z","end":"2026-04-02T16:42:40.496507Z","steps":["trace[373950018] 'process raft request' (duration: 121.326479ms)","trace[373950018] 'compare' (duration: 70.217458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:42:40.496637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.162002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:42:40.496672Z","caller":"traceutil/trace.go:171","msg":"trace[1315023725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1414; }","duration":"139.249722ms","start":"2026-04-02T16:42:40.357413Z","end":"2026-04-02T16:42:40.496663Z","steps":["trace[1315023725] 'agreement among raft nodes before linearized reading' (duration: 139.149942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:40.496708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.651762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:42:40.496895Z","caller":"traceutil/trace.go:171","msg":"trace[1818223223] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:1414; }","duration":"114.880471ms","start":"2026-04-02T16:42:40.381949Z","end":"2026-04-02T16:42:40.496829Z","steps":["trace[1818223223] 'agreement among raft nodes before linearized reading' (duration: 114.582522ms)"],"step_count":1}