{"level":"warn","ts":"2026-04-27T01:24:31.553504Z","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-27T01:24:31.553582Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.121: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.19.213.121:2380","--initial-cluster=instance=https://199.19.213.121:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.121:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.121: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-27T01:24:31.553639Z","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-27T01:24:31.553651Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-04-27T01:24:31.553672Z","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-27T01:24:31.554609Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"]} {"level":"info","ts":"2026-04-27T01:24:31.554687Z","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.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.121: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-27T01:24:31.559422Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.522191ms"} {"level":"info","ts":"2026-04-27T01:24:31.568354Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"31541ae44470b70","cluster-id":"8ae2d89c48d1447d"} {"level":"info","ts":"2026-04-27T01:24:31.56843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T01:24:31.568454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 0"} {"level":"info","ts":"2026-04-27T01:24:31.56847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 31541ae44470b70 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T01:24:31.56848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 1"} {"level":"info","ts":"2026-04-27T01:24:31.568522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"warn","ts":"2026-04-27T01:24:31.578741Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T01:24:31.580101Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T01:24:31.581467Z","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-27T01:24:31.585175Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"31541ae44470b70","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T01:24:31.58531Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"31541ae44470b70","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T01:24:31.585551Z","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-27T01:24:31.585661Z","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-27T01:24:31.585748Z","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-27T01:24:31.587551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"info","ts":"2026-04-27T01:24:31.587758Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","added-peer-id":"31541ae44470b70","added-peer-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-04-27T01:24:31.589025Z","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-27T01:24:31.589618Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-04-27T01:24:31.589655Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-04-27T01:24:31.589801Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"31541ae44470b70","initial-advertise-peer-urls":["https://199.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T01:24:31.589834Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T01:24:31.969494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T01:24:31.969574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T01:24:31.969599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgPreVoteResp from 31541ae44470b70 at term 1"} {"level":"info","ts":"2026-04-27T01:24:31.969618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became candidate at term 2"} {"level":"info","ts":"2026-04-27T01:24:31.969629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgVoteResp from 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-04-27T01:24:31.969651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became leader at term 2"} {"level":"info","ts":"2026-04-27T01:24:31.969664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 31541ae44470b70 elected leader 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-04-27T01:24:32.048474Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T01:24:32.048625Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T01:24:32.048752Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"31541ae44470b70","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.121:2379]}","request-path":"/0/members/31541ae44470b70/attributes","cluster-id":"8ae2d89c48d1447d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T01:24:32.048836Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T01:24:32.049043Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T01:24:32.049075Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T01:24:32.052133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T01:24:32.054147Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.121:2379"} {"level":"info","ts":"2026-04-27T01:24:32.05738Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T01:24:32.057455Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T01:24:32.057507Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-27T01:24:33.732552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.195112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:33.732557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.708739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.121\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:33.732645Z","caller":"traceutil/trace.go:171","msg":"trace[1843495378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:3; }","duration":"239.358186ms","start":"2026-04-27T01:24:33.493274Z","end":"2026-04-27T01:24:33.732632Z","steps":["trace[1843495378] 'range keys from in-memory index tree' (duration: 239.128431ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:33.732664Z","caller":"traceutil/trace.go:171","msg":"trace[1744664603] range","detail":"{range_begin:/registry/masterleases/199.19.213.121; range_end:; response_count:0; response_revision:3; }","duration":"228.835911ms","start":"2026-04-27T01:24:33.50381Z","end":"2026-04-27T01:24:33.732646Z","steps":["trace[1744664603] 'range keys from in-memory index tree' (duration: 228.671288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:33.732668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.779623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:33.732751Z","caller":"traceutil/trace.go:171","msg":"trace[1090273522] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"227.914165ms","start":"2026-04-27T01:24:33.504819Z","end":"2026-04-27T01:24:33.732733Z","steps":["trace[1090273522] 'range keys from in-memory index tree' (duration: 227.711182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:33.7329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.63411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:33.733089Z","caller":"traceutil/trace.go:171","msg":"trace[808102631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:3; }","duration":"239.767833ms","start":"2026-04-27T01:24:33.493211Z","end":"2026-04-27T01:24:33.732979Z","steps":["trace[808102631] 'range keys from in-memory index tree' (duration: 239.382246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:33.733245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.585022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:33.73331Z","caller":"traceutil/trace.go:171","msg":"trace[1266360802] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"251.286274ms","start":"2026-04-27T01:24:33.48201Z","end":"2026-04-27T01:24:33.733296Z","steps":["trace[1266360802] 'range keys from in-memory index tree' (duration: 250.465221ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:33.733314Z","caller":"traceutil/trace.go:171","msg":"trace[520438089] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:6; }","duration":"226.727474ms","start":"2026-04-27T01:24:33.506567Z","end":"2026-04-27T01:24:33.733294Z","steps":["trace[520438089] 'read index received' (duration: 175.483486ms)","trace[520438089] 'applied index is now lower than readState.Index' (duration: 51.243148ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:33.733448Z","caller":"traceutil/trace.go:171","msg":"trace[167209900] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"226.903588ms","start":"2026-04-27T01:24:33.506513Z","end":"2026-04-27T01:24:33.733417Z","steps":["trace[167209900] 'process raft request' (duration: 175.508887ms)","trace[167209900] 'compare' (duration: 50.441154ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:33.733582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.983239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:33.733703Z","caller":"traceutil/trace.go:171","msg":"trace[519406155] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:4; }","duration":"227.100391ms","start":"2026-04-27T01:24:33.506562Z","end":"2026-04-27T01:24:33.733663Z","steps":["trace[519406155] 'agreement among raft nodes before linearized reading' (duration: 226.853187ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:33.828925Z","caller":"traceutil/trace.go:171","msg":"trace[448710440] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"322.129786ms","start":"2026-04-27T01:24:33.506767Z","end":"2026-04-27T01:24:33.828896Z","steps":["trace[448710440] 'process raft request' (duration: 322.002364ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:33.82898Z","caller":"traceutil/trace.go:171","msg":"trace[1973743738] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"322.263078ms","start":"2026-04-27T01:24:33.50669Z","end":"2026-04-27T01:24:33.828953Z","steps":["trace[1973743738] 'process raft request' (duration: 321.980793ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:33.828988Z","caller":"traceutil/trace.go:171","msg":"trace[420213828] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"322.214307ms","start":"2026-04-27T01:24:33.506752Z","end":"2026-04-27T01:24:33.828966Z","steps":["trace[420213828] 'process raft request' (duration: 322.081625ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:33.828999Z","caller":"traceutil/trace.go:171","msg":"trace[431440015] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"304.149622ms","start":"2026-04-27T01:24:33.52483Z","end":"2026-04-27T01:24:33.82898Z","steps":["trace[431440015] 'process raft request' (duration: 304.109211ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:33.829086Z","caller":"traceutil/trace.go:171","msg":"trace[1057323351] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"313.660977ms","start":"2026-04-27T01:24:33.515417Z","end":"2026-04-27T01:24:33.829078Z","steps":["trace[1057323351] 'process raft request' (duration: 313.488914ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:33.829122Z","caller":"traceutil/trace.go:171","msg":"trace[507078875] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"322.35462ms","start":"2026-04-27T01:24:33.50676Z","end":"2026-04-27T01:24:33.829115Z","steps":["trace[507078875] 'process raft request' (duration: 322.046465ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:33.829192Z","caller":"traceutil/trace.go:171","msg":"trace[1468936287] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"321.740739ms","start":"2026-04-27T01:24:33.507445Z","end":"2026-04-27T01:24:33.829186Z","steps":["trace[1468936287] 'process raft request' (duration: 321.423334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:33.830842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.506758Z","time spent":"322.237018ms","remote":"127.0.0.1:34540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:33.831511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.594843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:33.831568Z","caller":"traceutil/trace.go:171","msg":"trace[1203076287] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"266.676445ms","start":"2026-04-27T01:24:33.564865Z","end":"2026-04-27T01:24:33.831541Z","steps":["trace[1203076287] 'agreement among raft nodes before linearized reading' (duration: 264.350714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:33.83185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.506673Z","time spent":"322.366341ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:33.832102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.404498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:33.832171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.506715Z","time spent":"322.33171ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:33.832171Z","caller":"traceutil/trace.go:171","msg":"trace[653685716] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"275.512539ms","start":"2026-04-27T01:24:33.556641Z","end":"2026-04-27T01:24:33.832153Z","steps":["trace[653685716] 'agreement among raft nodes before linearized reading' (duration: 272.575178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:33.832335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.524817Z","time spent":"304.244033ms","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:33.832467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.515408Z","time spent":"313.703119ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:33.832553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.506744Z","time spent":"322.39244ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:33.832626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.507435Z","time spent":"321.76983ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:34.444167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.950698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:34.444235Z","caller":"traceutil/trace.go:171","msg":"trace[465413087] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"864.026449ms","start":"2026-04-27T01:24:33.580191Z","end":"2026-04-27T01:24:34.444217Z","steps":["trace[465413087] 'agreement among raft nodes before linearized reading' (duration: 249.040925ms)","trace[465413087] 'range keys from in-memory index tree' (duration: 614.851582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:34.444265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.580156Z","time spent":"864.102361ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T01:24:34.444658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.017875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-27T01:24:34.444953Z","caller":"traceutil/trace.go:171","msg":"trace[1895783318] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"709.041422ms","start":"2026-04-27T01:24:33.735886Z","end":"2026-04-27T01:24:34.444928Z","steps":["trace[1895783318] 'process raft request' (duration: 170.370626ms)","trace[1895783318] 'compare' (duration: 537.901783ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:34.445059Z","caller":"traceutil/trace.go:171","msg":"trace[69866855] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"708.988871ms","start":"2026-04-27T01:24:33.736054Z","end":"2026-04-27T01:24:34.445043Z","steps":["trace[69866855] 'process raft request' (duration: 708.729047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:34.445145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.736043Z","time spent":"709.065812ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:34.445082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.735861Z","time spent":"709.166595ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:34.445308Z","caller":"traceutil/trace.go:171","msg":"trace[449529101] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:14; }","duration":"616.094164ms","start":"2026-04-27T01:24:33.829198Z","end":"2026-04-27T01:24:34.445292Z","steps":["trace[449529101] 'read index received' (duration: 77.068161ms)","trace[449529101] 'applied index is now lower than readState.Index' (duration: 539.025123ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:34.445453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.027083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-27T01:24:34.445476Z","caller":"traceutil/trace.go:171","msg":"trace[1188728588] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"708.415472ms","start":"2026-04-27T01:24:33.737047Z","end":"2026-04-27T01:24:34.445463Z","steps":["trace[1188728588] 'process raft request' (duration: 707.876472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:34.445523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.450063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:34.44555Z","caller":"traceutil/trace.go:171","msg":"trace[1046657989] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"655.497464ms","start":"2026-04-27T01:24:33.790047Z","end":"2026-04-27T01:24:34.445544Z","steps":["trace[1046657989] 'agreement among raft nodes before linearized reading' (duration: 655.461173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:34.445606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.790034Z","time spent":"655.564845ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-27T01:24:34.445668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.326952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:34.445721Z","caller":"traceutil/trace.go:171","msg":"trace[1436885207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13; }","duration":"711.405584ms","start":"2026-04-27T01:24:33.734303Z","end":"2026-04-27T01:24:34.445709Z","steps":["trace[1436885207] 'agreement among raft nodes before linearized reading' (duration: 711.326852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:34.445758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.734291Z","time spent":"711.460645ms","remote":"127.0.0.1:58594","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-27T01:24:34.44574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.737025Z","time spent":"708.517933ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:34.445498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.180146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-27T01:24:34.446067Z","caller":"traceutil/trace.go:171","msg":"trace[1586936504] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"656.677204ms","start":"2026-04-27T01:24:33.789216Z","end":"2026-04-27T01:24:34.445893Z","steps":["trace[1586936504] 'agreement among raft nodes before linearized reading' (duration: 656.233876ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:34.445493Z","caller":"traceutil/trace.go:171","msg":"trace[1063819730] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"656.096864ms","start":"2026-04-27T01:24:33.789385Z","end":"2026-04-27T01:24:34.445482Z","steps":["trace[1063819730] 'agreement among raft nodes before linearized reading' (duration: 655.982022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:34.446211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.789191Z","time spent":"657.00115ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T01:24:34.446195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.789367Z","time spent":"656.779656ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T01:24:34.945937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332233618411921,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T01:24:34.964181Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.053502529s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T01:24:36.549485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000024323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T01:24:36.54959Z","caller":"traceutil/trace.go:171","msg":"trace[94696246] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000182275s","start":"2026-04-27T01:24:34.54939Z","end":"2026-04-27T01:24:36.549573Z","steps":["trace[94696246] 'agreement among raft nodes before linearized reading' (duration: 2.000054603s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:36.549639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.549367Z","time spent":"2.000262347s","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T01:24:36.566855Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.602265081s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T01:24:37.831521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000026432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T01:24:37.831605Z","caller":"traceutil/trace.go:171","msg":"trace[917185590] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000162355s","start":"2026-04-27T01:24:35.831422Z","end":"2026-04-27T01:24:37.831584Z","steps":["trace[917185590] 'agreement among raft nodes before linearized reading' (duration: 2.000057643s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:37.831652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:35.831396Z","time spent":"2.000245055s","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T01:24:38.550843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000381564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T01:24:38.550954Z","caller":"traceutil/trace.go:171","msg":"trace[623211294] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000542217s","start":"2026-04-27T01:24:36.550394Z","end":"2026-04-27T01:24:38.550937Z","steps":["trace[623211294] 'agreement among raft nodes before linearized reading' (duration: 2.000409765s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:38.551003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:36.550376Z","time spent":"2.000617788s","remote":"127.0.0.1:58594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T01:24:39.265031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.300394054s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-27T01:24:39.265545Z","caller":"traceutil/trace.go:171","msg":"trace[2000498166] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"5.428974199s","start":"2026-04-27T01:24:33.836553Z","end":"2026-04-27T01:24:39.265527Z","steps":["trace[2000498166] 'process raft request' (duration: 5.428582983s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.265604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.8365Z","time spent":"5.429081521s","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:39.265608Z","caller":"traceutil/trace.go:171","msg":"trace[681583682] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"5.429296074s","start":"2026-04-27T01:24:33.836282Z","end":"2026-04-27T01:24:39.265578Z","steps":["trace[681583682] 'process raft request' (duration: 1.12828428s)","trace[681583682] 'compare' (duration: 4.300067248s)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:39.26564Z","caller":"traceutil/trace.go:171","msg":"trace[1682445065] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"5.429134072s","start":"2026-04-27T01:24:33.836488Z","end":"2026-04-27T01:24:39.265622Z","steps":["trace[1682445065] 'process raft request' (duration: 5.428704814s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.2657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.836264Z","time spent":"5.429387916s","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:39.26572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.836475Z","time spent":"5.429205532s","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:39.266678Z","caller":"traceutil/trace.go:171","msg":"trace[1574629785] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"5.429760742s","start":"2026-04-27T01:24:33.836901Z","end":"2026-04-27T01:24:39.266662Z","steps":["trace[1574629785] 'process raft request' (duration: 5.428334868s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.266753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.83685Z","time spent":"5.429868543s","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:39.267343Z","caller":"traceutil/trace.go:171","msg":"trace[585547104] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"5.429516186s","start":"2026-04-27T01:24:33.837805Z","end":"2026-04-27T01:24:39.267321Z","steps":["trace[585547104] 'process raft request' (duration: 5.428809695s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.267424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.837786Z","time spent":"5.429601698s","remote":"127.0.0.1:34540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:39.267878Z","caller":"traceutil/trace.go:171","msg":"trace[161177545] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"4.817868671s","start":"2026-04-27T01:24:34.449995Z","end":"2026-04-27T01:24:39.267863Z","steps":["trace[161177545] 'process raft request' (duration: 4.817261431s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.267956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.449975Z","time spent":"4.817945142s","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:39.268578Z","caller":"traceutil/trace.go:171","msg":"trace[615497320] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:17; }","duration":"4.823208803s","start":"2026-04-27T01:24:34.445352Z","end":"2026-04-27T01:24:39.268561Z","steps":["trace[615497320] 'read index received' (duration: 519.162059ms)","trace[615497320] 'applied index is now lower than readState.Index' (duration: 4.304045664s)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:39.268605Z","caller":"traceutil/trace.go:171","msg":"trace[360598829] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"4.818493552s","start":"2026-04-27T01:24:34.450078Z","end":"2026-04-27T01:24:39.268572Z","steps":["trace[360598829] 'process raft request' (duration: 4.817728039s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.268855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.450068Z","time spent":"4.818678575s","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:39.268934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.380404784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.268996Z","caller":"traceutil/trace.go:171","msg":"trace[473849474] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:21; }","duration":"5.380491395s","start":"2026-04-27T01:24:33.888493Z","end":"2026-04-27T01:24:39.268984Z","steps":["trace[473849474] 'agreement among raft nodes before linearized reading' (duration: 5.380349343s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.269028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.888482Z","time spent":"5.380537555s","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-27T01:24:39.269167Z","caller":"traceutil/trace.go:171","msg":"trace[527938105] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"4.818231587s","start":"2026-04-27T01:24:34.450924Z","end":"2026-04-27T01:24:39.269155Z","steps":["trace[527938105] 'process raft request' (duration: 4.817585796s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.269224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.450911Z","time spent":"4.818286587s","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:39.269305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.864623621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:39.269325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.376381162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-27T01:24:39.269316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.112254993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-27T01:24:39.269352Z","caller":"traceutil/trace.go:171","msg":"trace[1140333861] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"5.376408363s","start":"2026-04-27T01:24:33.892935Z","end":"2026-04-27T01:24:39.269343Z","steps":["trace[1140333861] 'agreement among raft nodes before linearized reading' (duration: 5.376349142s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.269343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.82128006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-27T01:24:39.269369Z","caller":"traceutil/trace.go:171","msg":"trace[1670303168] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:21; }","duration":"4.864797833s","start":"2026-04-27T01:24:34.404559Z","end":"2026-04-27T01:24:39.269357Z","steps":["trace[1670303168] 'agreement among raft nodes before linearized reading' (duration: 4.8646373s)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.26939Z","caller":"traceutil/trace.go:171","msg":"trace[952332824] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"4.82135603s","start":"2026-04-27T01:24:34.448021Z","end":"2026-04-27T01:24:39.269377Z","steps":["trace[952332824] 'agreement among raft nodes before linearized reading' (duration: 4.821198668s)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.269383Z","caller":"traceutil/trace.go:171","msg":"trace[543871943] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"5.112336194s","start":"2026-04-27T01:24:34.157026Z","end":"2026-04-27T01:24:39.269363Z","steps":["trace[543871943] 'agreement among raft nodes before linearized reading' (duration: 5.112157121s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.269416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.404539Z","time spent":"4.864866605s","remote":"127.0.0.1:58928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-27T01:24:39.269436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.448005Z","time spent":"4.821422802s","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T01:24:39.269449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.156968Z","time spent":"5.112467916s","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T01:24:39.269378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.892924Z","time spent":"5.376446623s","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T01:24:39.269601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.61938246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.269631Z","caller":"traceutil/trace.go:171","msg":"trace[1617306451] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"4.61943287s","start":"2026-04-27T01:24:34.650188Z","end":"2026-04-27T01:24:39.26962Z","steps":["trace[1617306451] 'agreement among raft nodes before linearized reading' (duration: 4.61938406s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.269654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.268751668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:39.269738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.163455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:39.269748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.865303782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.269781Z","caller":"traceutil/trace.go:171","msg":"trace[888682348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"620.227486ms","start":"2026-04-27T01:24:38.649538Z","end":"2026-04-27T01:24:39.269765Z","steps":["trace[888682348] 'agreement among raft nodes before linearized reading' (duration: 620.176045ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.26979Z","caller":"traceutil/trace.go:171","msg":"trace[1908688410] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:21; }","duration":"3.268904021s","start":"2026-04-27T01:24:36.000812Z","end":"2026-04-27T01:24:39.269716Z","steps":["trace[1908688410] 'agreement among raft nodes before linearized reading' (duration: 3.268758619s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.269807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.819426457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.269796Z","caller":"traceutil/trace.go:171","msg":"trace[784205201] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:21; }","duration":"4.865397663s","start":"2026-04-27T01:24:34.404384Z","end":"2026-04-27T01:24:39.269782Z","steps":["trace[784205201] 'agreement among raft nodes before linearized reading' (duration: 4.865286891s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.269813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:38.649519Z","time spent":"620.286086ms","remote":"127.0.0.1:58594","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-27T01:24:39.269839Z","caller":"traceutil/trace.go:171","msg":"trace[1375744455] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"4.819477018s","start":"2026-04-27T01:24:34.450354Z","end":"2026-04-27T01:24:39.269831Z","steps":["trace[1375744455] 'agreement among raft nodes before linearized reading' (duration: 4.819423317s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.269842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:36.000791Z","time spent":"3.269040683s","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-27T01:24:39.269853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.404361Z","time spent":"4.865480355s","remote":"127.0.0.1:58948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-27T01:24:39.269868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.450344Z","time spent":"4.819517418s","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-04-27T01:24:39.269999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.431704001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-ml5mm\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-04-27T01:24:39.270086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.425361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:39.269677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.650172Z","time spent":"4.619488321s","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-04-27T01:24:39.270112Z","caller":"traceutil/trace.go:171","msg":"trace[279870220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"938.484752ms","start":"2026-04-27T01:24:38.331619Z","end":"2026-04-27T01:24:39.270103Z","steps":["trace[279870220] 'agreement among raft nodes before linearized reading' (duration: 938.438922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.270105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.819852334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"warn","ts":"2026-04-27T01:24:39.270135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:38.331594Z","time spent":"938.536823ms","remote":"127.0.0.1:58592","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-27T01:24:39.270145Z","caller":"traceutil/trace.go:171","msg":"trace[1264629297] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21; }","duration":"4.819919405s","start":"2026-04-27T01:24:34.450216Z","end":"2026-04-27T01:24:39.270136Z","steps":["trace[1264629297] 'agreement among raft nodes before linearized reading' (duration: 4.819847124s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.270186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:34.450209Z","time spent":"4.819958045s","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-27T01:24:39.270349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.430323327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-04-27T01:24:39.270427Z","caller":"traceutil/trace.go:171","msg":"trace[1882855954] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-ml5mm; range_end:; response_count:1; response_revision:21; }","duration":"5.431759913s","start":"2026-04-27T01:24:33.838266Z","end":"2026-04-27T01:24:39.270026Z","steps":["trace[1882855954] 'agreement among raft nodes before linearized reading' (duration: 5.431420107s)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.270482Z","caller":"traceutil/trace.go:171","msg":"trace[2018518633] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21; }","duration":"5.430356478s","start":"2026-04-27T01:24:33.840006Z","end":"2026-04-27T01:24:39.270363Z","steps":["trace[2018518633] 'agreement among raft nodes before linearized reading' (duration: 5.430298577s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.270474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.838259Z","time spent":"5.43220235s","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-ml5mm\" "} {"level":"warn","ts":"2026-04-27T01:24:39.270808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:33.839969Z","time spent":"5.430536311s","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-27T01:24:39.497363Z","caller":"traceutil/trace.go:171","msg":"trace[1260359102] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"227.282747ms","start":"2026-04-27T01:24:39.270051Z","end":"2026-04-27T01:24:39.497334Z","steps":["trace[1260359102] 'process raft request' (duration: 227.173786ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.497561Z","caller":"traceutil/trace.go:171","msg":"trace[1209060997] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"226.807899ms","start":"2026-04-27T01:24:39.27074Z","end":"2026-04-27T01:24:39.497548Z","steps":["trace[1209060997] 'process raft request' (duration: 226.521164ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.497561Z","caller":"traceutil/trace.go:171","msg":"trace[57827026] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"227.730355ms","start":"2026-04-27T01:24:39.269668Z","end":"2026-04-27T01:24:39.497398Z","steps":["trace[57827026] 'process raft request' (duration: 147.387694ms)","trace[57827026] 'compare' (duration: 80.031895ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:39.497812Z","caller":"traceutil/trace.go:171","msg":"trace[1713642592] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"225.253264ms","start":"2026-04-27T01:24:39.272535Z","end":"2026-04-27T01:24:39.497788Z","steps":["trace[1713642592] 'process raft request' (duration: 224.768186ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.498111Z","caller":"traceutil/trace.go:171","msg":"trace[513505752] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"147.124149ms","start":"2026-04-27T01:24:39.350976Z","end":"2026-04-27T01:24:39.4981Z","steps":["trace[513505752] 'process raft request' (duration: 147.093279ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.49819Z","caller":"traceutil/trace.go:171","msg":"trace[1653797980] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"221.080725ms","start":"2026-04-27T01:24:39.277103Z","end":"2026-04-27T01:24:39.498184Z","steps":["trace[1653797980] 'process raft request' (duration: 220.887862ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.498231Z","caller":"traceutil/trace.go:171","msg":"trace[1518284980] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"221.073115ms","start":"2026-04-27T01:24:39.277125Z","end":"2026-04-27T01:24:39.498198Z","steps":["trace[1518284980] 'process raft request' (duration: 220.909292ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.498257Z","caller":"traceutil/trace.go:171","msg":"trace[17668890] linearizableReadLoop","detail":"{readStateIndex:31; appliedIndex:25; }","duration":"223.580206ms","start":"2026-04-27T01:24:39.274664Z","end":"2026-04-27T01:24:39.498244Z","steps":["trace[17668890] 'read index received' (duration: 72.815658ms)","trace[17668890] 'applied index is now lower than readState.Index' (duration: 150.763408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:39.498408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.728449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-27T01:24:39.498444Z","caller":"traceutil/trace.go:171","msg":"trace[1355889960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:34; }","duration":"223.80396ms","start":"2026-04-27T01:24:39.274631Z","end":"2026-04-27T01:24:39.498435Z","steps":["trace[1355889960] 'agreement among raft nodes before linearized reading' (duration: 223.679518ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.49842Z","caller":"traceutil/trace.go:171","msg":"trace[2079544844] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"221.667005ms","start":"2026-04-27T01:24:39.276734Z","end":"2026-04-27T01:24:39.498401Z","steps":["trace[2079544844] 'process raft request' (duration: 221.153387ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.498541Z","caller":"traceutil/trace.go:171","msg":"trace[1900292075] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"222.403877ms","start":"2026-04-27T01:24:39.276126Z","end":"2026-04-27T01:24:39.49853Z","steps":["trace[1900292075] 'process raft request' (duration: 221.701746ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.498586Z","caller":"traceutil/trace.go:171","msg":"trace[118829006] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"221.624454ms","start":"2026-04-27T01:24:39.276949Z","end":"2026-04-27T01:24:39.498574Z","steps":["trace[118829006] 'process raft request' (duration: 220.960113ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.49865Z","caller":"traceutil/trace.go:171","msg":"trace[717207458] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"225.135322ms","start":"2026-04-27T01:24:39.273458Z","end":"2026-04-27T01:24:39.498593Z","steps":["trace[717207458] 'process raft request' (duration: 224.054304ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.498794Z","caller":"traceutil/trace.go:171","msg":"trace[743753601] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"222.428377ms","start":"2026-04-27T01:24:39.276355Z","end":"2026-04-27T01:24:39.498784Z","steps":["trace[743753601] 'process raft request' (duration: 221.500312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.498823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.012037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.498861Z","caller":"traceutil/trace.go:171","msg":"trace[610797303] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:34; }","duration":"223.056587ms","start":"2026-04-27T01:24:39.275784Z","end":"2026-04-27T01:24:39.498841Z","steps":["trace[610797303] 'agreement among raft nodes before linearized reading' (duration: 223.018817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.498898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.392615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:39.498973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.084408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-27T01:24:39.498993Z","caller":"traceutil/trace.go:171","msg":"trace[1360339633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:34; }","duration":"223.119319ms","start":"2026-04-27T01:24:39.275868Z","end":"2026-04-27T01:24:39.498987Z","steps":["trace[1360339633] 'agreement among raft nodes before linearized reading' (duration: 223.078018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.499027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.566545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.499005Z","caller":"traceutil/trace.go:171","msg":"trace[998445297] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:34; }","duration":"172.490276ms","start":"2026-04-27T01:24:39.326486Z","end":"2026-04-27T01:24:39.498976Z","steps":["trace[998445297] 'agreement among raft nodes before linearized reading' (duration: 172.321104ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.49907Z","caller":"traceutil/trace.go:171","msg":"trace[851048930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34; }","duration":"167.647897ms","start":"2026-04-27T01:24:39.331411Z","end":"2026-04-27T01:24:39.499059Z","steps":["trace[851048930] 'agreement among raft nodes before linearized reading' (duration: 167.568806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.498967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.014177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.499241Z","caller":"traceutil/trace.go:171","msg":"trace[2107564083] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:34; }","duration":"223.329042ms","start":"2026-04-27T01:24:39.275897Z","end":"2026-04-27T01:24:39.499226Z","steps":["trace[2107564083] 'agreement among raft nodes before linearized reading' (duration: 222.908915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.780188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.996607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-27T01:24:39.780505Z","caller":"traceutil/trace.go:171","msg":"trace[1630880277] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"262.12501ms","start":"2026-04-27T01:24:39.518367Z","end":"2026-04-27T01:24:39.780492Z","steps":["trace[1630880277] 'process raft request' (duration: 262.10168ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.780587Z","caller":"traceutil/trace.go:171","msg":"trace[1199496659] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"276.570147ms","start":"2026-04-27T01:24:39.503982Z","end":"2026-04-27T01:24:39.780552Z","steps":["trace[1199496659] 'process raft request' (duration: 160.042311ms)","trace[1199496659] 'compare' (duration: 115.892156ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:39.780683Z","caller":"traceutil/trace.go:171","msg":"trace[988544161] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"275.715093ms","start":"2026-04-27T01:24:39.504952Z","end":"2026-04-27T01:24:39.780667Z","steps":["trace[988544161] 'process raft request' (duration: 275.465599ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.780742Z","caller":"traceutil/trace.go:171","msg":"trace[1582988496] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"276.690619ms","start":"2026-04-27T01:24:39.504036Z","end":"2026-04-27T01:24:39.780727Z","steps":["trace[1582988496] 'process raft request' (duration: 276.223151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.780792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.649642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.780836Z","caller":"traceutil/trace.go:171","msg":"trace[837441965] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:44; }","duration":"276.018308ms","start":"2026-04-27T01:24:39.504791Z","end":"2026-04-27T01:24:39.780809Z","steps":["trace[837441965] 'agreement among raft nodes before linearized reading' (duration: 275.973008ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.780848Z","caller":"traceutil/trace.go:171","msg":"trace[144269826] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"276.685889ms","start":"2026-04-27T01:24:39.504153Z","end":"2026-04-27T01:24:39.780839Z","steps":["trace[144269826] 'process raft request' (duration: 276.14172ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.78093Z","caller":"traceutil/trace.go:171","msg":"trace[1634989411] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"276.028618ms","start":"2026-04-27T01:24:39.504891Z","end":"2026-04-27T01:24:39.780919Z","steps":["trace[1634989411] 'process raft request' (duration: 275.490319ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.781203Z","caller":"traceutil/trace.go:171","msg":"trace[1198213262] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"276.963364ms","start":"2026-04-27T01:24:39.504232Z","end":"2026-04-27T01:24:39.781195Z","steps":["trace[1198213262] 'process raft request' (duration: 276.083479ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.780988Z","caller":"traceutil/trace.go:171","msg":"trace[486779527] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"276.039408ms","start":"2026-04-27T01:24:39.50494Z","end":"2026-04-27T01:24:39.780979Z","steps":["trace[486779527] 'process raft request' (duration: 275.461289ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.780726Z","caller":"traceutil/trace.go:171","msg":"trace[1195469506] linearizableReadLoop","detail":"{readStateIndex:47; appliedIndex:39; }","duration":"264.968797ms","start":"2026-04-27T01:24:39.515744Z","end":"2026-04-27T01:24:39.780712Z","steps":["trace[1195469506] 'read index received' (duration: 148.309509ms)","trace[1195469506] 'applied index is now lower than readState.Index' (duration: 116.657448ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:39.781355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.016884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.781407Z","caller":"traceutil/trace.go:171","msg":"trace[1128057116] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:44; }","duration":"263.096436ms","start":"2026-04-27T01:24:39.518294Z","end":"2026-04-27T01:24:39.781391Z","steps":["trace[1128057116] 'agreement among raft nodes before linearized reading' (duration: 263.018185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:39.781549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.653501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:39.781582Z","caller":"traceutil/trace.go:171","msg":"trace[494282189] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:44; }","duration":"225.710201ms","start":"2026-04-27T01:24:39.555864Z","end":"2026-04-27T01:24:39.781574Z","steps":["trace[494282189] 'agreement among raft nodes before linearized reading' (duration: 225.66207ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:39.781639Z","caller":"traceutil/trace.go:171","msg":"trace[702349756] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"276.255712ms","start":"2026-04-27T01:24:39.505336Z","end":"2026-04-27T01:24:39.781592Z","steps":["trace[702349756] 'process raft request' (duration: 275.105163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:40.803566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.722731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-27T01:24:40.803718Z","caller":"traceutil/trace.go:171","msg":"trace[510469991] linearizableReadLoop","detail":"{readStateIndex:50; appliedIndex:49; }","duration":"947.311136ms","start":"2026-04-27T01:24:39.856389Z","end":"2026-04-27T01:24:40.803701Z","steps":["trace[510469991] 'read index received' (duration: 49.413373ms)","trace[510469991] 'applied index is now lower than readState.Index' (duration: 897.896323ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:40.803781Z","caller":"traceutil/trace.go:171","msg":"trace[1631878037] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"1.019639075s","start":"2026-04-27T01:24:39.78408Z","end":"2026-04-27T01:24:40.803719Z","steps":["trace[1631878037] 'process raft request' (duration: 121.697231ms)","trace[1631878037] 'compare' (duration: 897.584368ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:40.803943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.675088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:40.803982Z","caller":"traceutil/trace.go:171","msg":"trace[1681904379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:46; }","duration":"471.745129ms","start":"2026-04-27T01:24:40.332225Z","end":"2026-04-27T01:24:40.80397Z","steps":["trace[1681904379] 'agreement among raft nodes before linearized reading' (duration: 471.700799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:40.804081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:40.332203Z","time spent":"471.861231ms","remote":"127.0.0.1:58592","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-27T01:24:40.804093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.476462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:40.804167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.067638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-27T01:24:40.80421Z","caller":"traceutil/trace.go:171","msg":"trace[370329038] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:46; }","duration":"721.14647ms","start":"2026-04-27T01:24:40.08305Z","end":"2026-04-27T01:24:40.804197Z","steps":["trace[370329038] 'agreement among raft nodes before linearized reading' (duration: 721.063129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:40.804252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:40.083028Z","time spent":"721.215361ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3488,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-04-27T01:24:40.804259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:39.784064Z","time spent":"1.019907649s","remote":"127.0.0.1:34540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:40.804359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.858487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:40.804422Z","caller":"traceutil/trace.go:171","msg":"trace[1288457176] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:46; }","duration":"932.950479ms","start":"2026-04-27T01:24:39.871462Z","end":"2026-04-27T01:24:40.804413Z","steps":["trace[1288457176] 'agreement among raft nodes before linearized reading' (duration: 932.800577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:40.803895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.863961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:40.804463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:39.871447Z","time spent":"933.00895ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" "} {"level":"info","ts":"2026-04-27T01:24:40.804483Z","caller":"traceutil/trace.go:171","msg":"trace[1933128106] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:46; }","duration":"966.46205ms","start":"2026-04-27T01:24:39.838013Z","end":"2026-04-27T01:24:40.804475Z","steps":["trace[1933128106] 'agreement among raft nodes before linearized reading' (duration: 965.80172ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:40.804218Z","caller":"traceutil/trace.go:171","msg":"trace[140181966] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:46; }","duration":"932.741016ms","start":"2026-04-27T01:24:39.871449Z","end":"2026-04-27T01:24:40.80419Z","steps":["trace[140181966] 'agreement among raft nodes before linearized reading' (duration: 932.37997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:40.804522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:39.837956Z","time spent":"966.558992ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-27T01:24:40.804601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:39.87144Z","time spent":"933.125172ms","remote":"127.0.0.1:58928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-04-27T01:24:41.137725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.03509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-27T01:24:41.138075Z","caller":"traceutil/trace.go:171","msg":"trace[659869952] linearizableReadLoop","detail":"{readStateIndex:52; appliedIndex:51; }","duration":"329.152749ms","start":"2026-04-27T01:24:40.8089Z","end":"2026-04-27T01:24:41.138053Z","steps":["trace[659869952] 'read index received' (duration: 25.475935ms)","trace[659869952] 'applied index is now lower than readState.Index' (duration: 303.675504ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:41.138111Z","caller":"traceutil/trace.go:171","msg":"trace[1949554848] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"317.357016ms","start":"2026-04-27T01:24:40.820732Z","end":"2026-04-27T01:24:41.138089Z","steps":["trace[1949554848] 'process raft request' (duration: 317.191014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:41.138241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:40.820703Z","time spent":"317.458577ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T01:24:41.138278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.366652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:41.138339Z","caller":"traceutil/trace.go:171","msg":"trace[1444049778] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:49; }","duration":"329.452154ms","start":"2026-04-27T01:24:40.808848Z","end":"2026-04-27T01:24:41.1383Z","steps":["trace[1444049778] 'agreement among raft nodes before linearized reading' (duration: 329.362602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:41.138384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:40.808837Z","time spent":"329.537415ms","remote":"127.0.0.1:58928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"info","ts":"2026-04-27T01:24:41.138452Z","caller":"traceutil/trace.go:171","msg":"trace[1527198589] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"330.423339ms","start":"2026-04-27T01:24:40.808003Z","end":"2026-04-27T01:24:41.138426Z","steps":["trace[1527198589] 'process raft request' (duration: 130.496054ms)","trace[1527198589] 'compare' (duration: 198.838217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:41.138554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:40.807986Z","time spent":"330.525261ms","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":647,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:41.138615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.967465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18aa12403794cab3\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:41.13872Z","caller":"traceutil/trace.go:171","msg":"trace[1046432159] range","detail":"{range_begin:/registry/events/default/instance.18aa12403794cab3; range_end:; response_count:0; response_revision:49; }","duration":"329.120208ms","start":"2026-04-27T01:24:40.809574Z","end":"2026-04-27T01:24:41.138694Z","steps":["trace[1046432159] 'agreement among raft nodes before linearized reading' (duration: 328.945165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:41.138775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:40.809551Z","time spent":"329.213349ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/events/default/instance.18aa12403794cab3\" "} {"level":"warn","ts":"2026-04-27T01:24:41.918226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.312531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-27T01:24:41.918434Z","caller":"traceutil/trace.go:171","msg":"trace[1599940902] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"735.055191ms","start":"2026-04-27T01:24:41.183343Z","end":"2026-04-27T01:24:41.918398Z","steps":["trace[1599940902] 'process raft request' (duration: 96.525526ms)","trace[1599940902] 'compare' (duration: 638.167779ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:41.918558Z","caller":"traceutil/trace.go:171","msg":"trace[1384655263] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"731.534884ms","start":"2026-04-27T01:24:41.186931Z","end":"2026-04-27T01:24:41.918465Z","steps":["trace[1384655263] 'process raft request' (duration: 731.487783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:41.918677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.183318Z","time spent":"735.221953ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:41.918729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.186917Z","time spent":"731.774438ms","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T01:24:41.918695Z","caller":"traceutil/trace.go:171","msg":"trace[1065730889] linearizableReadLoop","detail":"{readStateIndex:57; appliedIndex:56; }","duration":"735.076241ms","start":"2026-04-27T01:24:41.183582Z","end":"2026-04-27T01:24:41.918658Z","steps":["trace[1065730889] 'read index received' (duration: 96.378174ms)","trace[1065730889] 'applied index is now lower than readState.Index' (duration: 638.692697ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:41.918744Z","caller":"traceutil/trace.go:171","msg":"trace[1705162227] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"734.117506ms","start":"2026-04-27T01:24:41.1846Z","end":"2026-04-27T01:24:41.918718Z","steps":["trace[1705162227] 'process raft request' (duration: 733.72412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:41.918855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.259464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:41.91888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.184545Z","time spent":"734.253788ms","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":632,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:41.918912Z","caller":"traceutil/trace.go:171","msg":"trace[458163331] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:54; }","duration":"735.358486ms","start":"2026-04-27T01:24:41.183539Z","end":"2026-04-27T01:24:41.918898Z","steps":["trace[458163331] 'agreement among raft nodes before linearized reading' (duration: 735.262595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:41.918954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.183522Z","time spent":"735.419607ms","remote":"127.0.0.1:58928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/cluster-admin\" "} {"level":"info","ts":"2026-04-27T01:24:41.950049Z","caller":"traceutil/trace.go:171","msg":"trace[1042700025] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"520.594153ms","start":"2026-04-27T01:24:41.429436Z","end":"2026-04-27T01:24:41.95003Z","steps":["trace[1042700025] 'process raft request' (duration: 520.483231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:41.950201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.429406Z","time spent":"520.707055ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:41.950345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.037645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:41.950391Z","caller":"traceutil/trace.go:171","msg":"trace[2029064160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:55; }","duration":"587.126586ms","start":"2026-04-27T01:24:41.363254Z","end":"2026-04-27T01:24:41.95038Z","steps":["trace[2029064160] 'agreement among raft nodes before linearized reading' (duration: 586.985643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:41.950413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.36323Z","time spent":"587.178717ms","remote":"127.0.0.1:58592","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-27T01:24:41.950428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.473881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-27T01:24:41.950549Z","caller":"traceutil/trace.go:171","msg":"trace[346746260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:55; }","duration":"164.642424ms","start":"2026-04-27T01:24:41.785851Z","end":"2026-04-27T01:24:41.950494Z","steps":["trace[346746260] 'agreement among raft nodes before linearized reading' (duration: 164.308129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:41.950721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.806987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-27T01:24:41.950775Z","caller":"traceutil/trace.go:171","msg":"trace[727940726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:55; }","duration":"164.877118ms","start":"2026-04-27T01:24:41.785871Z","end":"2026-04-27T01:24:41.950749Z","steps":["trace[727940726] 'agreement among raft nodes before linearized reading' (duration: 164.181986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:42.382134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.703618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-27T01:24:42.382325Z","caller":"traceutil/trace.go:171","msg":"trace[1458038060] linearizableReadLoop","detail":"{readStateIndex:63; appliedIndex:60; }","duration":"432.279563ms","start":"2026-04-27T01:24:41.95003Z","end":"2026-04-27T01:24:42.38231Z","steps":["trace[1458038060] 'read index received' (duration: 80.357172ms)","trace[1458038060] 'applied index is now lower than readState.Index' (duration: 351.921531ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:42.382396Z","caller":"traceutil/trace.go:171","msg":"trace[1729059138] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"459.296158ms","start":"2026-04-27T01:24:41.923091Z","end":"2026-04-27T01:24:42.382387Z","steps":["trace[1729059138] 'process raft request' (duration: 459.120985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:42.382442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.923079Z","time spent":"459.338438ms","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":796,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:42.382438Z","caller":"traceutil/trace.go:171","msg":"trace[1363878416] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"458.483194ms","start":"2026-04-27T01:24:41.923935Z","end":"2026-04-27T01:24:42.382418Z","steps":["trace[1363878416] 'process raft request' (duration: 458.329392ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:42.382517Z","caller":"traceutil/trace.go:171","msg":"trace[956751140] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"459.943248ms","start":"2026-04-27T01:24:41.922522Z","end":"2026-04-27T01:24:42.382465Z","steps":["trace[956751140] 'process raft request' (duration: 107.853654ms)","trace[956751140] 'compare' (duration: 351.565736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:42.382664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.922507Z","time spent":"460.091161ms","remote":"127.0.0.1:58928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:42.382614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.923926Z","time spent":"458.571887ms","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1059,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T01:24:42.383037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.68517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18aa12403794fd67\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:42.383194Z","caller":"traceutil/trace.go:171","msg":"trace[1959673677] range","detail":"{range_begin:/registry/events/default/instance.18aa12403794fd67; range_end:; response_count:0; response_revision:58; }","duration":"460.921054ms","start":"2026-04-27T01:24:41.922259Z","end":"2026-04-27T01:24:42.38318Z","steps":["trace[1959673677] 'agreement among raft nodes before linearized reading' (duration: 460.691681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:42.38327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.922241Z","time spent":"460.983575ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/events/default/instance.18aa12403794fd67\" "} {"level":"info","ts":"2026-04-27T01:24:42.388434Z","caller":"traceutil/trace.go:171","msg":"trace[807459799] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"432.8192ms","start":"2026-04-27T01:24:41.955591Z","end":"2026-04-27T01:24:42.388411Z","steps":["trace[807459799] 'process raft request' (duration: 432.618727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:42.388575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.955578Z","time spent":"432.922851ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T01:24:42.388869Z","caller":"traceutil/trace.go:171","msg":"trace[840768777] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"433.151805ms","start":"2026-04-27T01:24:41.955694Z","end":"2026-04-27T01:24:42.388846Z","steps":["trace[840768777] 'process raft request' (duration: 432.629177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:42.388931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.333362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2917"} {"level":"info","ts":"2026-04-27T01:24:42.388973Z","caller":"traceutil/trace.go:171","msg":"trace[682775686] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:60; }","duration":"269.410553ms","start":"2026-04-27T01:24:42.119545Z","end":"2026-04-27T01:24:42.388956Z","steps":["trace[682775686] 'agreement among raft nodes before linearized reading' (duration: 269.336922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:42.388994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.492785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T01:24:42.389014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.955658Z","time spent":"433.276507ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T01:24:42.389031Z","caller":"traceutil/trace.go:171","msg":"trace[385196374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:60; }","duration":"437.553487ms","start":"2026-04-27T01:24:41.951466Z","end":"2026-04-27T01:24:42.389019Z","steps":["trace[385196374] 'agreement among raft nodes before linearized reading' (duration: 437.495816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:42.389074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:41.951432Z","time spent":"437.632557ms","remote":"127.0.0.1:58594","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-27T01:24:42.579567Z","caller":"traceutil/trace.go:171","msg":"trace[1626028978] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"193.650362ms","start":"2026-04-27T01:24:42.38588Z","end":"2026-04-27T01:24:42.57953Z","steps":["trace[1626028978] 'process raft request' (duration: 164.625859ms)","trace[1626028978] 'compare' (duration: 28.702318ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:42.580157Z","caller":"traceutil/trace.go:171","msg":"trace[384258440] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"194.263092ms","start":"2026-04-27T01:24:42.38588Z","end":"2026-04-27T01:24:42.580143Z","steps":["trace[384258440] 'process raft request' (duration: 193.599332ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:42.58041Z","caller":"traceutil/trace.go:171","msg":"trace[1527183441] linearizableReadLoop","detail":"{readStateIndex:68; appliedIndex:65; }","duration":"191.690821ms","start":"2026-04-27T01:24:42.388707Z","end":"2026-04-27T01:24:42.580397Z","steps":["trace[1527183441] 'read index received' (duration: 161.949556ms)","trace[1527183441] 'applied index is now lower than readState.Index' (duration: 29.740215ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:42.580514Z","caller":"traceutil/trace.go:171","msg":"trace[1505339946] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"193.229886ms","start":"2026-04-27T01:24:42.387253Z","end":"2026-04-27T01:24:42.580483Z","steps":["trace[1505339946] 'process raft request' (duration: 192.702207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:42.5806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.625168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:42.580667Z","caller":"traceutil/trace.go:171","msg":"trace[1901879658] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:0; response_revision:63; }","duration":"194.7243ms","start":"2026-04-27T01:24:42.385924Z","end":"2026-04-27T01:24:42.580648Z","steps":["trace[1901879658] 'agreement among raft nodes before linearized reading' (duration: 194.602408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:42.580828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.065083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T01:24:42.580896Z","caller":"traceutil/trace.go:171","msg":"trace[2045084841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:64; }","duration":"131.193145ms","start":"2026-04-27T01:24:42.449686Z","end":"2026-04-27T01:24:42.580879Z","steps":["trace[2045084841] 'agreement among raft nodes before linearized reading' (duration: 131.062633ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:42.581113Z","caller":"traceutil/trace.go:171","msg":"trace[1157040587] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"186.331895ms","start":"2026-04-27T01:24:42.394765Z","end":"2026-04-27T01:24:42.581097Z","steps":["trace[1157040587] 'process raft request' (duration: 185.866808ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:43.543082Z","caller":"traceutil/trace.go:171","msg":"trace[65766549] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"115.774522ms","start":"2026-04-27T01:24:43.427276Z","end":"2026-04-27T01:24:43.54305Z","steps":["trace[65766549] 'process raft request' (duration: 80.25024ms)","trace[65766549] 'compare' (duration: 35.370319ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:43.76968Z","caller":"traceutil/trace.go:171","msg":"trace[23247758] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"114.138616ms","start":"2026-04-27T01:24:43.655506Z","end":"2026-04-27T01:24:43.769644Z","steps":["trace[23247758] 'process raft request' (duration: 29.594159ms)","trace[23247758] 'compare' (duration: 84.347894ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:43.962914Z","caller":"traceutil/trace.go:171","msg":"trace[912034726] transaction","detail":"{read_only:false; response_revision:188; number_of_response:1; }","duration":"104.741578ms","start":"2026-04-27T01:24:43.858142Z","end":"2026-04-27T01:24:43.962883Z","steps":["trace[912034726] 'process raft request' (duration: 38.52762ms)","trace[912034726] 'compare' (duration: 66.072455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:48.097357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.288316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T01:24:48.097458Z","caller":"traceutil/trace.go:171","msg":"trace[382513571] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"183.474942ms","start":"2026-04-27T01:24:47.913963Z","end":"2026-04-27T01:24:48.097438Z","steps":["trace[382513571] 'read index received' (duration: 31.058015ms)","trace[382513571] 'applied index is now lower than readState.Index' (duration: 152.415677ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:48.097587Z","caller":"traceutil/trace.go:171","msg":"trace[1908585272] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"213.747255ms","start":"2026-04-27T01:24:47.883819Z","end":"2026-04-27T01:24:48.097567Z","steps":["trace[1908585272] 'process raft request' (duration: 61.173455ms)","trace[1908585272] 'compare' (duration: 151.93157ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:48.09764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.666575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4133"} {"level":"info","ts":"2026-04-27T01:24:48.097684Z","caller":"traceutil/trace.go:171","msg":"trace[853766078] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:320; }","duration":"183.742196ms","start":"2026-04-27T01:24:47.91393Z","end":"2026-04-27T01:24:48.097672Z","steps":["trace[853766078] 'agreement among raft nodes before linearized reading' (duration: 183.632534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:48.09796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.854584ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:24:48.097988Z","caller":"traceutil/trace.go:171","msg":"trace[1529961670] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:320; }","duration":"152.884314ms","start":"2026-04-27T01:24:47.945095Z","end":"2026-04-27T01:24:48.097979Z","steps":["trace[1529961670] 'agreement among raft nodes before linearized reading' (duration: 152.806703ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:50.438584Z","caller":"traceutil/trace.go:171","msg":"trace[130227631] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"171.967215ms","start":"2026-04-27T01:24:50.26652Z","end":"2026-04-27T01:24:50.438487Z","steps":["trace[130227631] 'read index received' (duration: 167.416488ms)","trace[130227631] 'applied index is now lower than readState.Index' (duration: 4.549417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:50.438631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.591567ms","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-27T01:24:50.438735Z","caller":"traceutil/trace.go:171","msg":"trace[1101875921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:342; }","duration":"325.723569ms","start":"2026-04-27T01:24:50.112973Z","end":"2026-04-27T01:24:50.438697Z","steps":["trace[1101875921] 'range keys from in-memory index tree' (duration: 325.057889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:50.438802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:50.112956Z","time spent":"325.83289ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T01:24:50.438801Z","caller":"traceutil/trace.go:171","msg":"trace[39739193] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"304.207987ms","start":"2026-04-27T01:24:50.134346Z","end":"2026-04-27T01:24:50.438553Z","steps":["trace[39739193] 'process raft request' (duration: 299.592349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:50.439024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:50.134309Z","time spent":"304.650195ms","remote":"127.0.0.1:58790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:50.439558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.016441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:24:50.439718Z","caller":"traceutil/trace.go:171","msg":"trace[1644100773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:343; }","duration":"173.155752ms","start":"2026-04-27T01:24:50.266472Z","end":"2026-04-27T01:24:50.439628Z","steps":["trace[1644100773] 'agreement among raft nodes before linearized reading' (duration: 172.183948ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:57.636969Z","caller":"traceutil/trace.go:171","msg":"trace[124427852] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"116.485343ms","start":"2026-04-27T01:24:57.520458Z","end":"2026-04-27T01:24:57.636944Z","steps":["trace[124427852] 'read index received' (duration: 750.871µs)","trace[124427852] 'applied index is now lower than readState.Index' (duration: 115.733482ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:57.637102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.621925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-27T01:24:57.637134Z","caller":"traceutil/trace.go:171","msg":"trace[528237383] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:427; }","duration":"116.720667ms","start":"2026-04-27T01:24:57.520404Z","end":"2026-04-27T01:24:57.637125Z","steps":["trace[528237383] 'agreement among raft nodes before linearized reading' (duration: 116.635626ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:57.637135Z","caller":"traceutil/trace.go:171","msg":"trace[976297133] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"117.402516ms","start":"2026-04-27T01:24:57.519694Z","end":"2026-04-27T01:24:57.637097Z","steps":["trace[976297133] 'process raft request' (duration: 94.218807ms)","trace[976297133] 'compare' (duration: 22.923496ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:57.63808Z","caller":"traceutil/trace.go:171","msg":"trace[197081985] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"116.944209ms","start":"2026-04-27T01:24:57.521119Z","end":"2026-04-27T01:24:57.638064Z","steps":["trace[197081985] 'process raft request' (duration: 116.863808ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:57.853418Z","caller":"traceutil/trace.go:171","msg":"trace[1439936869] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"207.919771ms","start":"2026-04-27T01:24:57.645483Z","end":"2026-04-27T01:24:57.853403Z","steps":["trace[1439936869] 'process raft request' (duration: 207.818489ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:57.853378Z","caller":"traceutil/trace.go:171","msg":"trace[1394187366] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"211.269028ms","start":"2026-04-27T01:24:57.642087Z","end":"2026-04-27T01:24:57.853356Z","steps":["trace[1394187366] 'process raft request' (duration: 173.132177ms)","trace[1394187366] 'compare' (duration: 37.885817ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:57.85359Z","caller":"traceutil/trace.go:171","msg":"trace[158996588] transaction","detail":"{read_only:false; number_of_response:1; response_revision:431; }","duration":"182.51272ms","start":"2026-04-27T01:24:57.671064Z","end":"2026-04-27T01:24:57.853576Z","steps":["trace[158996588] 'process raft request' (duration: 182.303637ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:58.095789Z","caller":"traceutil/trace.go:171","msg":"trace[1838821316] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"237.545142ms","start":"2026-04-27T01:24:57.858219Z","end":"2026-04-27T01:24:58.095764Z","steps":["trace[1838821316] 'process raft request' (duration: 164.739816ms)","trace[1838821316] 'compare' (duration: 72.671224ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:58.101109Z","caller":"traceutil/trace.go:171","msg":"trace[1396137692] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"242.830096ms","start":"2026-04-27T01:24:57.858253Z","end":"2026-04-27T01:24:58.101083Z","steps":["trace[1396137692] 'process raft request' (duration: 242.638683ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:58.101135Z","caller":"traceutil/trace.go:171","msg":"trace[179574824] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"238.86623ms","start":"2026-04-27T01:24:57.862249Z","end":"2026-04-27T01:24:58.101115Z","steps":["trace[179574824] 'process raft request' (duration: 238.82739ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:58.101135Z","caller":"traceutil/trace.go:171","msg":"trace[887249928] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"242.820946ms","start":"2026-04-27T01:24:57.858284Z","end":"2026-04-27T01:24:58.101105Z","steps":["trace[887249928] 'process raft request' (duration: 242.715875ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:58.101376Z","caller":"traceutil/trace.go:171","msg":"trace[706719682] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"239.367027ms","start":"2026-04-27T01:24:57.861782Z","end":"2026-04-27T01:24:58.101149Z","steps":["trace[706719682] 'process raft request' (duration: 239.258555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:58.454795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.608107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T01:24:58.454977Z","caller":"traceutil/trace.go:171","msg":"trace[653986628] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"343.857417ms","start":"2026-04-27T01:24:58.111088Z","end":"2026-04-27T01:24:58.454946Z","steps":["trace[653986628] 'process raft request' (duration: 343.779396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:58.455045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.549701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:24:58.455067Z","caller":"traceutil/trace.go:171","msg":"trace[1685357867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"184.607612ms","start":"2026-04-27T01:24:58.270455Z","end":"2026-04-27T01:24:58.455063Z","steps":["trace[1685357867] 'agreement among raft nodes before linearized reading' (duration: 184.561401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:58.455084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:58.111082Z","time spent":"343.952708ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T01:24:58.455Z","caller":"traceutil/trace.go:171","msg":"trace[643176404] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:453; }","duration":"184.48994ms","start":"2026-04-27T01:24:58.270496Z","end":"2026-04-27T01:24:58.454986Z","steps":["trace[643176404] 'read index received' (duration: 66.530987ms)","trace[643176404] 'applied index is now lower than readState.Index' (duration: 117.958243ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:58.455213Z","caller":"traceutil/trace.go:171","msg":"trace[2088010497] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"342.138672ms","start":"2026-04-27T01:24:58.113046Z","end":"2026-04-27T01:24:58.455185Z","steps":["trace[2088010497] 'process raft request' (duration: 341.906569ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:58.455257Z","caller":"traceutil/trace.go:171","msg":"trace[685989753] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"342.591149ms","start":"2026-04-27T01:24:58.112659Z","end":"2026-04-27T01:24:58.45525Z","steps":["trace[685989753] 'process raft request' (duration: 342.268765ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:58.455228Z","caller":"traceutil/trace.go:171","msg":"trace[1649549151] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"344.172251ms","start":"2026-04-27T01:24:58.111002Z","end":"2026-04-27T01:24:58.455174Z","steps":["trace[1649549151] 'process raft request' (duration: 226.141548ms)","trace[1649549151] 'compare' (duration: 117.497306ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:58.455321Z","caller":"traceutil/trace.go:171","msg":"trace[1370582784] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"342.776111ms","start":"2026-04-27T01:24:58.112542Z","end":"2026-04-27T01:24:58.455318Z","steps":["trace[1370582784] 'process raft request' (duration: 342.350375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:24:58.455348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:58.113022Z","time spent":"342.249415ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T01:24:58.455357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:58.112535Z","time spent":"342.805352ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T01:24:58.455488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:58.110983Z","time spent":"344.431515ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:24:58.455297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:24:58.11264Z","time spent":"342.6332ms","remote":"127.0.0.1:34542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T01:24:58.61944Z","caller":"traceutil/trace.go:171","msg":"trace[554106858] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:458; }","duration":"145.780856ms","start":"2026-04-27T01:24:58.473634Z","end":"2026-04-27T01:24:58.619415Z","steps":["trace[554106858] 'read index received' (duration: 45.115446ms)","trace[554106858] 'applied index is now lower than readState.Index' (duration: 100.66445ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:24:58.619472Z","caller":"traceutil/trace.go:171","msg":"trace[113056041] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"156.871652ms","start":"2026-04-27T01:24:58.462575Z","end":"2026-04-27T01:24:58.619446Z","steps":["trace[113056041] 'process raft request' (duration: 99.915999ms)","trace[113056041] 'compare' (duration: 56.820681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:24:58.619603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.947918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T01:24:58.619699Z","caller":"traceutil/trace.go:171","msg":"trace[1900126890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"146.040829ms","start":"2026-04-27T01:24:58.473616Z","end":"2026-04-27T01:24:58.619657Z","steps":["trace[1900126890] 'agreement among raft nodes before linearized reading' (duration: 145.915407ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:24:58.812788Z","caller":"traceutil/trace.go:171","msg":"trace[1553093846] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"176.246044ms","start":"2026-04-27T01:24:58.636502Z","end":"2026-04-27T01:24:58.812748Z","steps":["trace[1553093846] 'process raft request' (duration: 113.850895ms)","trace[1553093846] 'compare' (duration: 58.803379ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:07.340785Z","caller":"traceutil/trace.go:171","msg":"trace[5598779] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"197.881651ms","start":"2026-04-27T01:25:07.142878Z","end":"2026-04-27T01:25:07.34076Z","steps":["trace[5598779] 'process raft request' (duration: 197.746549ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:09.009035Z","caller":"traceutil/trace.go:171","msg":"trace[995413372] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"103.679362ms","start":"2026-04-27T01:25:08.905333Z","end":"2026-04-27T01:25:09.009012Z","steps":["trace[995413372] 'read index received' (duration: 103.54048ms)","trace[995413372] 'applied index is now lower than readState.Index' (duration: 137.222µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:09.009123Z","caller":"traceutil/trace.go:171","msg":"trace[1832361689] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"175.420088ms","start":"2026-04-27T01:25:08.833652Z","end":"2026-04-27T01:25:09.009072Z","steps":["trace[1832361689] 'process raft request' (duration: 175.220445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:09.009389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.043696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T01:25:09.00947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.062287ms","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-27T01:25:09.009564Z","caller":"traceutil/trace.go:171","msg":"trace[347333704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"104.209159ms","start":"2026-04-27T01:25:08.905331Z","end":"2026-04-27T01:25:09.009541Z","steps":["trace[347333704] 'agreement among raft nodes before linearized reading' (duration: 104.060457ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:09.009525Z","caller":"traceutil/trace.go:171","msg":"trace[1264071496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"104.232489ms","start":"2026-04-27T01:25:08.905277Z","end":"2026-04-27T01:25:09.009509Z","steps":["trace[1264071496] 'agreement among raft nodes before linearized reading' (duration: 104.041317ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:09.143908Z","caller":"traceutil/trace.go:171","msg":"trace[1770107542] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"130.108224ms","start":"2026-04-27T01:25:09.013762Z","end":"2026-04-27T01:25:09.14387Z","steps":["trace[1770107542] 'process raft request' (duration: 129.880021ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:09.168568Z","caller":"traceutil/trace.go:171","msg":"trace[1618998906] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:487; }","duration":"153.494304ms","start":"2026-04-27T01:25:09.015046Z","end":"2026-04-27T01:25:09.168541Z","steps":["trace[1618998906] 'read index received' (duration: 128.574934ms)","trace[1618998906] 'applied index is now lower than readState.Index' (duration: 24.91789ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:09.168633Z","caller":"traceutil/trace.go:171","msg":"trace[906164843] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"154.294385ms","start":"2026-04-27T01:25:09.014317Z","end":"2026-04-27T01:25:09.168612Z","steps":["trace[906164843] 'process raft request' (duration: 154.086913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:09.168707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.634186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:25:09.16881Z","caller":"traceutil/trace.go:171","msg":"trace[1408759972] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:471; }","duration":"153.776279ms","start":"2026-04-27T01:25:09.015012Z","end":"2026-04-27T01:25:09.168788Z","steps":["trace[1408759972] 'agreement among raft nodes before linearized reading' (duration: 153.627797ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:09.690815Z","caller":"traceutil/trace.go:171","msg":"trace[846588121] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"116.442355ms","start":"2026-04-27T01:25:09.574338Z","end":"2026-04-27T01:25:09.690781Z","steps":["trace[846588121] 'process raft request' (duration: 33.510605ms)","trace[846588121] 'compare' (duration: 82.667157ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:09.710722Z","caller":"traceutil/trace.go:171","msg":"trace[1734035020] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"135.530098ms","start":"2026-04-27T01:25:09.575136Z","end":"2026-04-27T01:25:09.710666Z","steps":["trace[1734035020] 'process raft request' (duration: 135.458557ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:09.710738Z","caller":"traceutil/trace.go:171","msg":"trace[1161437568] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"135.639109ms","start":"2026-04-27T01:25:09.575058Z","end":"2026-04-27T01:25:09.710697Z","steps":["trace[1161437568] 'process raft request' (duration: 135.414396ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:09.835928Z","caller":"traceutil/trace.go:171","msg":"trace[1243448073] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"119.192001ms","start":"2026-04-27T01:25:09.716704Z","end":"2026-04-27T01:25:09.835896Z","steps":["trace[1243448073] 'process raft request' (duration: 118.755045ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:09.835928Z","caller":"traceutil/trace.go:171","msg":"trace[1147159556] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"119.091639ms","start":"2026-04-27T01:25:09.71682Z","end":"2026-04-27T01:25:09.835911Z","steps":["trace[1147159556] 'process raft request' (duration: 119.031638ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:10.303894Z","caller":"traceutil/trace.go:171","msg":"trace[384209406] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"138.298036ms","start":"2026-04-27T01:25:10.165561Z","end":"2026-04-27T01:25:10.303859Z","steps":["trace[384209406] 'process raft request' (duration: 82.170324ms)","trace[384209406] 'compare' (duration: 55.678726ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:10.304183Z","caller":"traceutil/trace.go:171","msg":"trace[91924670] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"135.099344ms","start":"2026-04-27T01:25:10.169063Z","end":"2026-04-27T01:25:10.304162Z","steps":["trace[91924670] 'process raft request' (duration: 134.661108ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:10.304433Z","caller":"traceutil/trace.go:171","msg":"trace[40265668] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"133.195459ms","start":"2026-04-27T01:25:10.171208Z","end":"2026-04-27T01:25:10.304403Z","steps":["trace[40265668] 'process raft request' (duration: 132.640342ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:10.304501Z","caller":"traceutil/trace.go:171","msg":"trace[1492593863] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"133.27934ms","start":"2026-04-27T01:25:10.171208Z","end":"2026-04-27T01:25:10.304487Z","steps":["trace[1492593863] 'process raft request' (duration: 132.719293ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:10.304506Z","caller":"traceutil/trace.go:171","msg":"trace[1889455280] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:512; }","duration":"126.696783ms","start":"2026-04-27T01:25:10.177797Z","end":"2026-04-27T01:25:10.304494Z","steps":["trace[1889455280] 'read index received' (duration: 25.574308ms)","trace[1889455280] 'applied index is now lower than readState.Index' (duration: 101.121495ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:25:10.30464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.824555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-27T01:25:10.304671Z","caller":"traceutil/trace.go:171","msg":"trace[1590657308] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:498; }","duration":"126.900515ms","start":"2026-04-27T01:25:10.177762Z","end":"2026-04-27T01:25:10.304662Z","steps":["trace[1590657308] 'agreement among raft nodes before linearized reading' (duration: 126.771014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:10.618299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.450427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T01:25:10.618416Z","caller":"traceutil/trace.go:171","msg":"trace[669414494] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"279.691712ms","start":"2026-04-27T01:25:10.338708Z","end":"2026-04-27T01:25:10.6184Z","steps":["trace[669414494] 'read index received' (duration: 84.973891ms)","trace[669414494] 'applied index is now lower than readState.Index' (duration: 194.716791ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:10.61849Z","caller":"traceutil/trace.go:171","msg":"trace[312813471] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"295.064005ms","start":"2026-04-27T01:25:10.32339Z","end":"2026-04-27T01:25:10.618454Z","steps":["trace[312813471] 'process raft request' (duration: 100.381435ms)","trace[312813471] 'compare' (duration: 194.329245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:25:10.618674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.63171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-04-27T01:25:10.618713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.974715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4632"} {"level":"info","ts":"2026-04-27T01:25:10.618779Z","caller":"traceutil/trace.go:171","msg":"trace[788404914] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:503; }","duration":"160.795643ms","start":"2026-04-27T01:25:10.457963Z","end":"2026-04-27T01:25:10.618759Z","steps":["trace[788404914] 'agreement among raft nodes before linearized reading' (duration: 160.58717ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:10.618792Z","caller":"traceutil/trace.go:171","msg":"trace[365539837] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:503; }","duration":"280.116838ms","start":"2026-04-27T01:25:10.338656Z","end":"2026-04-27T01:25:10.618772Z","steps":["trace[365539837] 'agreement among raft nodes before linearized reading' (duration: 279.820974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:10.618858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.341021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-04-27T01:25:10.618907Z","caller":"traceutil/trace.go:171","msg":"trace[1161746767] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:503; }","duration":"208.429361ms","start":"2026-04-27T01:25:10.410464Z","end":"2026-04-27T01:25:10.618894Z","steps":["trace[1161746767] 'agreement among raft nodes before linearized reading' (duration: 208.120007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:10.61993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.543656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-27T01:25:10.620008Z","caller":"traceutil/trace.go:171","msg":"trace[993887388] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:503; }","duration":"262.679538ms","start":"2026-04-27T01:25:10.357309Z","end":"2026-04-27T01:25:10.619989Z","steps":["trace[993887388] 'agreement among raft nodes before linearized reading' (duration: 261.186568ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:10.788532Z","caller":"traceutil/trace.go:171","msg":"trace[2051678412] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"159.920052ms","start":"2026-04-27T01:25:10.628581Z","end":"2026-04-27T01:25:10.788501Z","steps":["trace[2051678412] 'process raft request' (duration: 155.618915ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:10.835489Z","caller":"traceutil/trace.go:171","msg":"trace[352233264] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"193.802838ms","start":"2026-04-27T01:25:10.641438Z","end":"2026-04-27T01:25:10.835241Z","steps":["trace[352233264] 'process raft request' (duration: 193.657936ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:11.16774Z","caller":"traceutil/trace.go:171","msg":"trace[666166254] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"151.566542ms","start":"2026-04-27T01:25:11.016152Z","end":"2026-04-27T01:25:11.167719Z","steps":["trace[666166254] 'process raft request' (duration: 151.063626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:11.673199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332233618412871,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T01:25:12.173336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332233618412871,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T01:25:12.195156Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023820802s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T01:25:12.195464Z","caller":"traceutil/trace.go:171","msg":"trace[2116080677] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"1.023297726s","start":"2026-04-27T01:25:11.172146Z","end":"2026-04-27T01:25:12.195443Z","steps":["trace[2116080677] 'read index received' (duration: 1.023128244s)","trace[2116080677] 'applied index is now lower than readState.Index' (duration: 168.592µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:12.19556Z","caller":"traceutil/trace.go:171","msg":"trace[1295526732] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.024323889s","start":"2026-04-27T01:25:11.171215Z","end":"2026-04-27T01:25:12.195539Z","steps":["trace[1295526732] 'process raft request' (duration: 1.024110366s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:12.195676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:11.171194Z","time spent":"1.02440068s","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T01:25:12.195634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.834348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T01:25:12.195687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023487649s","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-27T01:25:12.195727Z","caller":"traceutil/trace.go:171","msg":"trace[1064352671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"1.0235947s","start":"2026-04-27T01:25:11.17212Z","end":"2026-04-27T01:25:12.195715Z","steps":["trace[1064352671] 'agreement among raft nodes before linearized reading' (duration: 1.023412887s)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:12.195734Z","caller":"traceutil/trace.go:171","msg":"trace[522362985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"926.97603ms","start":"2026-04-27T01:25:11.268736Z","end":"2026-04-27T01:25:12.195712Z","steps":["trace[522362985] 'agreement among raft nodes before linearized reading' (duration: 926.835968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:12.195723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.496956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T01:25:12.195763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:11.17211Z","time spent":"1.02364433s","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T01:25:12.195791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:11.268714Z","time spent":"927.062851ms","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T01:25:12.195799Z","caller":"traceutil/trace.go:171","msg":"trace[1130287718] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:507; }","duration":"376.660878ms","start":"2026-04-27T01:25:11.81912Z","end":"2026-04-27T01:25:12.195781Z","steps":["trace[1130287718] 'agreement among raft nodes before linearized reading' (duration: 376.475756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:12.195799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.247005ms","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-27T01:25:12.195841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:11.819091Z","time spent":"376.742399ms","remote":"127.0.0.1:58680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-27T01:25:12.195867Z","caller":"traceutil/trace.go:171","msg":"trace[1270624686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"501.353937ms","start":"2026-04-27T01:25:11.694496Z","end":"2026-04-27T01:25:12.19585Z","steps":["trace[1270624686] 'agreement among raft nodes before linearized reading' (duration: 501.230536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:12.195908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:11.694471Z","time spent":"501.428358ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T01:25:12.770327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332233618412875,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T01:25:12.946121Z","caller":"traceutil/trace.go:171","msg":"trace[790541010] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"746.489345ms","start":"2026-04-27T01:25:12.199607Z","end":"2026-04-27T01:25:12.946096Z","steps":["trace[790541010] 'process raft request' (duration: 746.15163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:12.946251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:12.199588Z","time spent":"746.595967ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T01:25:12.947904Z","caller":"traceutil/trace.go:171","msg":"trace[733898339] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:526; }","duration":"678.265323ms","start":"2026-04-27T01:25:12.269613Z","end":"2026-04-27T01:25:12.947879Z","steps":["trace[733898339] 'read index received' (duration: 676.204456ms)","trace[733898339] 'applied index is now lower than readState.Index' (duration: 2.060007ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:12.947991Z","caller":"traceutil/trace.go:171","msg":"trace[1605242369] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"747.777782ms","start":"2026-04-27T01:25:12.200203Z","end":"2026-04-27T01:25:12.947981Z","steps":["trace[1605242369] 'process raft request' (duration: 747.59946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:12.948062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:12.200184Z","time spent":"747.833893ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T01:25:12.948168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.559087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:25:12.948201Z","caller":"traceutil/trace.go:171","msg":"trace[765445497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"678.619587ms","start":"2026-04-27T01:25:12.269572Z","end":"2026-04-27T01:25:12.948192Z","steps":["trace[765445497] 'agreement among raft nodes before linearized reading' (duration: 678.570067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:12.94825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:12.269554Z","time spent":"678.689659ms","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T01:25:12.948268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.240865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:25:12.948328Z","caller":"traceutil/trace.go:171","msg":"trace[633496674] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:509; }","duration":"348.335016ms","start":"2026-04-27T01:25:12.599974Z","end":"2026-04-27T01:25:12.948309Z","steps":["trace[633496674] 'agreement among raft nodes before linearized reading' (duration: 348.241934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:12.94839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:12.59995Z","time spent":"348.410407ms","remote":"127.0.0.1:58680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-27T01:25:13.721763Z","caller":"traceutil/trace.go:171","msg":"trace[1312082177] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"148.454023ms","start":"2026-04-27T01:25:13.573286Z","end":"2026-04-27T01:25:13.72174Z","steps":["trace[1312082177] 'process raft request' (duration: 142.965802ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:13.722068Z","caller":"traceutil/trace.go:171","msg":"trace[1486434548] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"106.491997ms","start":"2026-04-27T01:25:13.615566Z","end":"2026-04-27T01:25:13.722057Z","steps":["trace[1486434548] 'process raft request' (duration: 106.419206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:14.701224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332233618412917,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T01:25:14.821028Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.036338704s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T01:25:14.823744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.296943ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:25:14.823926Z","caller":"traceutil/trace.go:171","msg":"trace[481908115] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:566; }","duration":"434.474535ms","start":"2026-04-27T01:25:14.389421Z","end":"2026-04-27T01:25:14.823896Z","steps":["trace[481908115] 'range keys from in-memory index tree' (duration: 434.281173ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:14.823893Z","caller":"traceutil/trace.go:171","msg":"trace[1716822899] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.039429884s","start":"2026-04-27T01:25:13.784427Z","end":"2026-04-27T01:25:14.823857Z","steps":["trace[1716822899] 'process raft request' (duration: 1.03683087s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:14.824181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:13.784398Z","time spent":"1.039672967s","remote":"127.0.0.1:58634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T01:25:14.829995Z","caller":"traceutil/trace.go:171","msg":"trace[2032658034] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:587; }","duration":"629.387284ms","start":"2026-04-27T01:25:14.200582Z","end":"2026-04-27T01:25:14.829969Z","steps":["trace[2032658034] 'read index received' (duration: 620.761552ms)","trace[2032658034] 'applied index is now lower than readState.Index' (duration: 8.624742ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:14.830089Z","caller":"traceutil/trace.go:171","msg":"trace[1248166306] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.038677684s","start":"2026-04-27T01:25:13.791403Z","end":"2026-04-27T01:25:14.83008Z","steps":["trace[1248166306] 'process raft request' (duration: 1.038457551s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:14.830183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:13.791385Z","time spent":"1.038737995s","remote":"127.0.0.1:58634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T01:25:14.83032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.467253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:25:14.830768Z","caller":"traceutil/trace.go:171","msg":"trace[204759302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"561.970239ms","start":"2026-04-27T01:25:14.268782Z","end":"2026-04-27T01:25:14.830753Z","steps":["trace[204759302] 'agreement among raft nodes before linearized reading' (duration: 561.315961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:14.830386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.750509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-27T01:25:14.831028Z","caller":"traceutil/trace.go:171","msg":"trace[2131587157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"630.485938ms","start":"2026-04-27T01:25:14.200528Z","end":"2026-04-27T01:25:14.831014Z","steps":["trace[2131587157] 'agreement among raft nodes before linearized reading' (duration: 629.678387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:14.831192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:14.200486Z","time spent":"630.692091ms","remote":"127.0.0.1:58840","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-27T01:25:14.830554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.731309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.121\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T01:25:14.831459Z","caller":"traceutil/trace.go:171","msg":"trace[2090508847] range","detail":"{range_begin:/registry/masterleases/199.19.213.121; range_end:; response_count:1; response_revision:568; }","duration":"277.690542ms","start":"2026-04-27T01:25:14.553758Z","end":"2026-04-27T01:25:14.831449Z","steps":["trace[2090508847] 'agreement among raft nodes before linearized reading' (duration: 276.70868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:14.830844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:14.268761Z","time spent":"562.06953ms","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T01:25:15.695823Z","caller":"traceutil/trace.go:171","msg":"trace[2141842436] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"131.496389ms","start":"2026-04-27T01:25:15.564297Z","end":"2026-04-27T01:25:15.695793Z","steps":["trace[2141842436] 'process raft request' (duration: 131.137864ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:15.695924Z","caller":"traceutil/trace.go:171","msg":"trace[797587219] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:611; }","duration":"122.153408ms","start":"2026-04-27T01:25:15.573735Z","end":"2026-04-27T01:25:15.695889Z","steps":["trace[797587219] 'read index received' (duration: 122.128538ms)","trace[797587219] 'applied index is now lower than readState.Index' (duration: 11.06µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:25:15.69622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.457492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-27T01:25:15.696256Z","caller":"traceutil/trace.go:171","msg":"trace[926163104] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:589; }","duration":"122.542123ms","start":"2026-04-27T01:25:15.573702Z","end":"2026-04-27T01:25:15.696245Z","steps":["trace[926163104] 'agreement among raft nodes before linearized reading' (duration: 122.296559ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:15.887203Z","caller":"traceutil/trace.go:171","msg":"trace[1641017401] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"179.524849ms","start":"2026-04-27T01:25:15.707657Z","end":"2026-04-27T01:25:15.887182Z","steps":["trace[1641017401] 'read index received' (duration: 120.575867ms)","trace[1641017401] 'applied index is now lower than readState.Index' (duration: 58.948342ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:15.887323Z","caller":"traceutil/trace.go:171","msg":"trace[1238224405] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"253.458514ms","start":"2026-04-27T01:25:15.63383Z","end":"2026-04-27T01:25:15.887288Z","steps":["trace[1238224405] 'process raft request' (duration: 194.769486ms)","trace[1238224405] 'compare' (duration: 58.285473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:25:15.887371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.696051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-04-27T01:25:15.887403Z","caller":"traceutil/trace.go:171","msg":"trace[1000829420] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:590; }","duration":"179.769993ms","start":"2026-04-27T01:25:15.707625Z","end":"2026-04-27T01:25:15.887395Z","steps":["trace[1000829420] 'agreement among raft nodes before linearized reading' (duration: 179.631841ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:18.543817Z","caller":"traceutil/trace.go:171","msg":"trace[940648561] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"246.531238ms","start":"2026-04-27T01:25:18.297261Z","end":"2026-04-27T01:25:18.543792Z","steps":["trace[940648561] 'process raft request' (duration: 245.895837ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:18.884338Z","caller":"traceutil/trace.go:171","msg":"trace[1739268867] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"278.204494ms","start":"2026-04-27T01:25:18.606098Z","end":"2026-04-27T01:25:18.884303Z","steps":["trace[1739268867] 'process raft request' (duration: 278.024625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:19.079942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.951038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T01:25:19.080153Z","caller":"traceutil/trace.go:171","msg":"trace[571811600] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"185.076575ms","start":"2026-04-27T01:25:18.895062Z","end":"2026-04-27T01:25:19.080138Z","steps":["trace[571811600] 'read index received' (duration: 50.891617ms)","trace[571811600] 'applied index is now lower than readState.Index' (duration: 134.183748ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:19.080153Z","caller":"traceutil/trace.go:171","msg":"trace[1335338862] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"191.450241ms","start":"2026-04-27T01:25:18.888677Z","end":"2026-04-27T01:25:19.080127Z","steps":["trace[1335338862] 'process raft request' (duration: 57.2252ms)","trace[1335338862] 'compare' (duration: 133.807851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:25:19.080377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.655623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T01:25:19.080258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.178421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:25:19.080415Z","caller":"traceutil/trace.go:171","msg":"trace[518873634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"109.721027ms","start":"2026-04-27T01:25:18.970683Z","end":"2026-04-27T01:25:19.080404Z","steps":["trace[518873634] 'agreement among raft nodes before linearized reading' (duration: 109.618262ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:19.080444Z","caller":"traceutil/trace.go:171","msg":"trace[1493310283] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:604; }","duration":"185.382441ms","start":"2026-04-27T01:25:18.895035Z","end":"2026-04-27T01:25:19.080418Z","steps":["trace[1493310283] 'agreement among raft nodes before linearized reading' (duration: 185.177421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:19.080552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.102621ms","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-27T01:25:19.080662Z","caller":"traceutil/trace.go:171","msg":"trace[1469056260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"107.228017ms","start":"2026-04-27T01:25:18.973411Z","end":"2026-04-27T01:25:19.080639Z","steps":["trace[1469056260] 'agreement among raft nodes before linearized reading' (duration: 107.026928ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:20.31481Z","caller":"traceutil/trace.go:171","msg":"trace[1917438964] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"143.837064ms","start":"2026-04-27T01:25:20.170938Z","end":"2026-04-27T01:25:20.314775Z","steps":["trace[1917438964] 'process raft request' (duration: 141.459082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:20.493948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.940436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T01:25:20.494317Z","caller":"traceutil/trace.go:171","msg":"trace[1161993959] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"130.021806ms","start":"2026-04-27T01:25:20.364277Z","end":"2026-04-27T01:25:20.494298Z","steps":["trace[1161993959] 'process raft request' (duration: 26.67014ms)","trace[1161993959] 'compare' (duration: 102.709626ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:23.229094Z","caller":"traceutil/trace.go:171","msg":"trace[1180027256] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"136.590575ms","start":"2026-04-27T01:25:23.092472Z","end":"2026-04-27T01:25:23.229063Z","steps":["trace[1180027256] 'read index received' (duration: 136.371045ms)","trace[1180027256] 'applied index is now lower than readState.Index' (duration: 218.58µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:23.229141Z","caller":"traceutil/trace.go:171","msg":"trace[462084234] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"435.61548ms","start":"2026-04-27T01:25:22.7935Z","end":"2026-04-27T01:25:23.229116Z","steps":["trace[462084234] 'process raft request' (duration: 435.340847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:23.229267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:22.793472Z","time spent":"435.734636ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T01:25:23.229286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.810796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-27T01:25:23.229311Z","caller":"traceutil/trace.go:171","msg":"trace[1446367480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"136.91149ms","start":"2026-04-27T01:25:23.092391Z","end":"2026-04-27T01:25:23.229302Z","steps":["trace[1446367480] 'agreement among raft nodes before linearized reading' (duration: 136.807616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:23.229423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.251693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T01:25:23.229448Z","caller":"traceutil/trace.go:171","msg":"trace[1176482023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"129.303674ms","start":"2026-04-27T01:25:23.100136Z","end":"2026-04-27T01:25:23.22944Z","steps":["trace[1176482023] 'agreement among raft nodes before linearized reading' (duration: 129.21358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:23.229577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.669425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T01:25:23.229601Z","caller":"traceutil/trace.go:171","msg":"trace[1119093201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"128.704958ms","start":"2026-04-27T01:25:23.100889Z","end":"2026-04-27T01:25:23.229594Z","steps":["trace[1119093201] 'agreement among raft nodes before linearized reading' (duration: 128.642845ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:23.398029Z","caller":"traceutil/trace.go:171","msg":"trace[2078715416] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"161.372891ms","start":"2026-04-27T01:25:23.236615Z","end":"2026-04-27T01:25:23.397988Z","steps":["trace[2078715416] 'process raft request' (duration: 62.006677ms)","trace[2078715416] 'compare' (duration: 99.109093ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:23.398109Z","caller":"traceutil/trace.go:171","msg":"trace[1626823566] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"161.13606ms","start":"2026-04-27T01:25:23.236942Z","end":"2026-04-27T01:25:23.398078Z","steps":["trace[1626823566] 'process raft request' (duration: 160.960912ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:23.398234Z","caller":"traceutil/trace.go:171","msg":"trace[1747536021] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"160.728072ms","start":"2026-04-27T01:25:23.237495Z","end":"2026-04-27T01:25:23.398224Z","steps":["trace[1747536021] 'process raft request' (duration: 160.485091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:25.309192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:24.554805Z","time spent":"754.379275ms","remote":"127.0.0.1:58620","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T01:25:25.811064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332233618413160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T01:25:26.042896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.049092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T01:25:26.043057Z","caller":"traceutil/trace.go:171","msg":"trace[1376602439] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"732.781368ms","start":"2026-04-27T01:25:25.310227Z","end":"2026-04-27T01:25:26.043009Z","steps":["trace[1376602439] 'read index received' (duration: 599.407191ms)","trace[1376602439] 'applied index is now lower than readState.Index' (duration: 133.372927ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:26.043101Z","caller":"traceutil/trace.go:171","msg":"trace[1906197313] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"732.990737ms","start":"2026-04-27T01:25:25.310075Z","end":"2026-04-27T01:25:26.043066Z","steps":["trace[1906197313] 'process raft request' (duration: 599.62671ms)","trace[1906197313] 'compare' (duration: 132.781381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:25:26.043197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.973966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T01:25:26.043227Z","caller":"traceutil/trace.go:171","msg":"trace[1296591541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"733.033249ms","start":"2026-04-27T01:25:25.310185Z","end":"2026-04-27T01:25:26.043218Z","steps":["trace[1296591541] 'agreement among raft nodes before linearized reading' (duration: 732.946555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:26.043304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:25.310169Z","time spent":"733.125233ms","remote":"127.0.0.1:58594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T01:25:26.043644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.205693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-27T01:25:26.043717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.525177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-27T01:25:26.043741Z","caller":"traceutil/trace.go:171","msg":"trace[257875436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"641.319678ms","start":"2026-04-27T01:25:25.402394Z","end":"2026-04-27T01:25:26.043713Z","steps":["trace[257875436] 'agreement among raft nodes before linearized reading' (duration: 641.110799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:26.043715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.237084ms","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-27T01:25:26.043778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:25.402383Z","time spent":"641.387071ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T01:25:26.043527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:25.310057Z","time spent":"733.116162ms","remote":"127.0.0.1:58620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T01:25:26.04378Z","caller":"traceutil/trace.go:171","msg":"trace[1306324464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"641.618671ms","start":"2026-04-27T01:25:25.402141Z","end":"2026-04-27T01:25:26.04376Z","steps":["trace[1306324464] 'agreement among raft nodes before linearized reading' (duration: 641.36173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:26.043716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.2528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T01:25:26.043814Z","caller":"traceutil/trace.go:171","msg":"trace[1364786361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"641.399632ms","start":"2026-04-27T01:25:25.402393Z","end":"2026-04-27T01:25:26.043793Z","steps":["trace[1364786361] 'agreement among raft nodes before linearized reading' (duration: 641.109129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:26.043827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:25.402122Z","time spent":"641.692574ms","remote":"127.0.0.1:58840","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":"info","ts":"2026-04-27T01:25:26.043865Z","caller":"traceutil/trace.go:171","msg":"trace[1211965269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"620.423828ms","start":"2026-04-27T01:25:25.423423Z","end":"2026-04-27T01:25:26.043847Z","steps":["trace[1211965269] 'agreement among raft nodes before linearized reading' (duration: 620.164016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:26.043875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:25.402383Z","time spent":"641.478875ms","remote":"127.0.0.1:58840","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-27T01:25:26.043913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:25.423407Z","time spent":"620.494141ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T01:25:38.086451Z","caller":"traceutil/trace.go:171","msg":"trace[2004414510] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"159.34751ms","start":"2026-04-27T01:25:37.927072Z","end":"2026-04-27T01:25:38.086419Z","steps":["trace[2004414510] 'process raft request' (duration: 124.281459ms)","trace[2004414510] 'compare' (duration: 34.842152ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:25:38.086586Z","caller":"traceutil/trace.go:171","msg":"trace[45821015] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"156.740478ms","start":"2026-04-27T01:25:37.92982Z","end":"2026-04-27T01:25:38.086561Z","steps":["trace[45821015] 'process raft request' (duration: 156.524129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:41.336438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332233618413459,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T01:25:41.836776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332233618413459,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T01:25:42.103495Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.430665068s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T01:25:42.108455Z","caller":"traceutil/trace.go:171","msg":"trace[821867008] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:820; }","duration":"1.272633933s","start":"2026-04-27T01:25:40.835788Z","end":"2026-04-27T01:25:42.108422Z","steps":["trace[821867008] 'read index received' (duration: 1.267926856s)","trace[821867008] 'applied index is now lower than readState.Index' (duration: 4.705567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:25:42.10865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27284786s","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-27T01:25:42.108506Z","caller":"traceutil/trace.go:171","msg":"trace[13640260] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.069323086s","start":"2026-04-27T01:25:41.039157Z","end":"2026-04-27T01:25:42.108481Z","steps":["trace[13640260] 'process raft request' (duration: 1.069066136s)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:25:42.108692Z","caller":"traceutil/trace.go:171","msg":"trace[1482778298] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:794; }","duration":"1.272944235s","start":"2026-04-27T01:25:40.835739Z","end":"2026-04-27T01:25:42.108684Z","steps":["trace[1482778298] 'agreement among raft nodes before linearized reading' (duration: 1.27281801s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:42.10872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:40.835719Z","time spent":"1.272995706s","remote":"127.0.0.1:58634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-27T01:25:42.108789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124017121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T01:25:42.108812Z","caller":"traceutil/trace.go:171","msg":"trace[1829583586] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:794; }","duration":"1.124068083s","start":"2026-04-27T01:25:40.984736Z","end":"2026-04-27T01:25:42.108804Z","steps":["trace[1829583586] 'agreement among raft nodes before linearized reading' (duration: 1.124022641s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:42.108832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:40.98472Z","time spent":"1.124107394s","remote":"127.0.0.1:34540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-27T01:25:42.108937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233295234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s2lbc\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-27T01:25:42.108988Z","caller":"traceutil/trace.go:171","msg":"trace[61123924] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s2lbc; range_end:; response_count:1; response_revision:794; }","duration":"1.233409038s","start":"2026-04-27T01:25:40.875565Z","end":"2026-04-27T01:25:42.108974Z","steps":["trace[61123924] 'agreement among raft nodes before linearized reading' (duration: 1.233186739s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:42.109011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.658543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T01:25:42.109036Z","caller":"traceutil/trace.go:171","msg":"trace[680056530] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:794; }","duration":"902.725095ms","start":"2026-04-27T01:25:41.206304Z","end":"2026-04-27T01:25:42.109029Z","steps":["trace[680056530] 'agreement among raft nodes before linearized reading' (duration: 902.681943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:42.109051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:41.206282Z","time spent":"902.764546ms","remote":"127.0.0.1:34542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-27T01:25:42.109038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.621985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T01:25:42.109182Z","caller":"traceutil/trace.go:171","msg":"trace[694164151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"581.825403ms","start":"2026-04-27T01:25:41.527333Z","end":"2026-04-27T01:25:42.109158Z","steps":["trace[694164151] 'agreement among raft nodes before linearized reading' (duration: 581.433958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:25:42.109212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:41.039133Z","time spent":"1.069559545s","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T01:25:42.109241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:41.5273Z","time spent":"581.926896ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T01:25:42.109039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T01:25:40.87555Z","time spent":"1.23347652s","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s2lbc\" "} {"level":"info","ts":"2026-04-27T01:25:53.234043Z","caller":"traceutil/trace.go:171","msg":"trace[1542942294] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"250.871621ms","start":"2026-04-27T01:25:52.983145Z","end":"2026-04-27T01:25:53.234017Z","steps":["trace[1542942294] 'process raft request' (duration: 250.756677ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:26:32.53921Z","caller":"traceutil/trace.go:171","msg":"trace[1015879660] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"121.484016ms","start":"2026-04-27T01:26:32.417703Z","end":"2026-04-27T01:26:32.539187Z","steps":["trace[1015879660] 'process raft request' (duration: 121.381494ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:26:32.762521Z","caller":"traceutil/trace.go:171","msg":"trace[309760189] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"172.780174ms","start":"2026-04-27T01:26:32.589701Z","end":"2026-04-27T01:26:32.762481Z","steps":["trace[309760189] 'process raft request' (duration: 172.737953ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:26:32.762573Z","caller":"traceutil/trace.go:171","msg":"trace[517043571] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"173.193405ms","start":"2026-04-27T01:26:32.589359Z","end":"2026-04-27T01:26:32.762552Z","steps":["trace[517043571] 'process raft request' (duration: 112.944609ms)","trace[517043571] 'compare' (duration: 59.787214ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:26:32.762703Z","caller":"traceutil/trace.go:171","msg":"trace[2101550843] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"173.333838ms","start":"2026-04-27T01:26:32.589365Z","end":"2026-04-27T01:26:32.762699Z","steps":["trace[2101550843] 'process raft request' (duration: 172.99803ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:27:29.843821Z","caller":"traceutil/trace.go:171","msg":"trace[1416529950] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"105.433023ms","start":"2026-04-27T01:27:29.738364Z","end":"2026-04-27T01:27:29.843797Z","steps":["trace[1416529950] 'process raft request' (duration: 105.26814ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:27:54.720719Z","caller":"traceutil/trace.go:171","msg":"trace[1219314822] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"132.329468ms","start":"2026-04-27T01:27:54.588362Z","end":"2026-04-27T01:27:54.720691Z","steps":["trace[1219314822] 'read index received' (duration: 132.102814ms)","trace[1219314822] 'applied index is now lower than readState.Index' (duration: 225.254µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T01:27:54.72093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.551943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" ","response":"range_response_count:1 size:731"} {"level":"info","ts":"2026-04-27T01:27:54.720969Z","caller":"traceutil/trace.go:171","msg":"trace[2016936707] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-controller; range_end:; response_count:1; response_revision:1407; }","duration":"132.622704ms","start":"2026-04-27T01:27:54.588331Z","end":"2026-04-27T01:27:54.720954Z","steps":["trace[2016936707] 'agreement among raft nodes before linearized reading' (duration: 132.526662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:27:54.721002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.375506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-04-27T01:27:54.721103Z","caller":"traceutil/trace.go:171","msg":"trace[590732195] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:1407; }","duration":"123.504309ms","start":"2026-04-27T01:27:54.597572Z","end":"2026-04-27T01:27:54.721077Z","steps":["trace[590732195] 'agreement among raft nodes before linearized reading' (duration: 123.309986ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:27:54.889202Z","caller":"traceutil/trace.go:171","msg":"trace[2135499271] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"167.252772ms","start":"2026-04-27T01:27:54.721925Z","end":"2026-04-27T01:27:54.889178Z","steps":["trace[2135499271] 'process raft request' (duration: 141.843931ms)","trace[2135499271] 'compare' (duration: 25.258268ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:27:55.164021Z","caller":"traceutil/trace.go:171","msg":"trace[1668662056] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"111.953354ms","start":"2026-04-27T01:27:55.052046Z","end":"2026-04-27T01:27:55.163999Z","steps":["trace[1668662056] 'read index received' (duration: 111.782051ms)","trace[1668662056] 'applied index is now lower than readState.Index' (duration: 170.453µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:27:55.164083Z","caller":"traceutil/trace.go:171","msg":"trace[224394861] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"113.096745ms","start":"2026-04-27T01:27:55.050962Z","end":"2026-04-27T01:27:55.164058Z","steps":["trace[224394861] 'process raft request' (duration: 112.888811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T01:27:55.164187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.112757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:coredns\" ","response":"range_response_count:1 size:415"} {"level":"info","ts":"2026-04-27T01:27:55.164256Z","caller":"traceutil/trace.go:171","msg":"trace[982925795] range","detail":"{range_begin:/registry/clusterrolebindings/system:coredns; range_end:; response_count:1; response_revision:1409; }","duration":"112.207059ms","start":"2026-04-27T01:27:55.05203Z","end":"2026-04-27T01:27:55.164237Z","steps":["trace[982925795] 'agreement among raft nodes before linearized reading' (duration: 112.065057ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:27:55.289105Z","caller":"traceutil/trace.go:171","msg":"trace[758711705] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"120.943747ms","start":"2026-04-27T01:27:55.168135Z","end":"2026-04-27T01:27:55.289079Z","steps":["trace[758711705] 'process raft request' (duration: 51.583083ms)","trace[758711705] 'compare' (duration: 69.1349ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T01:27:55.289256Z","caller":"traceutil/trace.go:171","msg":"trace[136478317] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"120.650602ms","start":"2026-04-27T01:27:55.168567Z","end":"2026-04-27T01:27:55.289218Z","steps":["trace[136478317] 'process raft request' (duration: 120.401367ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T01:27:55.528396Z","caller":"traceutil/trace.go:171","msg":"trace[797339870] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"100.66165ms","start":"2026-04-27T01:27:55.427717Z","end":"2026-04-27T01:27:55.528378Z","steps":["trace[797339870] 'process raft request' (duration: 100.536148ms)"],"step_count":1}