{"level":"warn","ts":"2026-04-21T18:12:35.086727Z","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-21T18:12:35.08683Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-21T18:12:35.086929Z","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-21T18:12:35.086946Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-21T18:12:35.08699Z","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-21T18:12:35.087808Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-21T18:12:35.087894Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-21T18:12:35.093126Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.932913ms"} {"level":"info","ts":"2026-04-21T18:12:35.099576Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-21T18:12:35.099669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T18:12:35.099701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-21T18:12:35.09972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T18:12:35.099729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-21T18:12:35.099769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-21T18:12:35.108662Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T18:12:35.111604Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T18:12:35.113096Z","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-21T18:12:35.116736Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T18:12:35.117118Z","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-21T18:12:35.117497Z","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-21T18:12:35.11752Z","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-21T18:12:35.117713Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T18:12:35.118521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-21T18:12:35.118749Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-21T18:12:35.12233Z","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-21T18:12:35.12249Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-21T18:12:35.122509Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-21T18:12:35.122801Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T18:12:35.122904Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T18:12:35.300267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T18:12:35.300326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T18:12:35.300342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-21T18:12:35.300356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-21T18:12:35.300363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-21T18:12:35.300383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-21T18:12:35.300392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-21T18:12:35.302347Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T18:12:35.302689Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:12:35.302771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:12:35.302945Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:12:35.303197Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T18:12:35.303237Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T18:12:35.305618Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-21T18:12:35.308669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T18:12:35.308892Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:12:35.309027Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:12:35.309059Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:12:37.244767Z","caller":"traceutil/trace.go:171","msg":"trace[1702990269] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"117.57719ms","start":"2026-04-21T18:12:37.12716Z","end":"2026-04-21T18:12:37.244737Z","steps":["trace[1702990269] 'process raft request' (duration: 63.32551ms)","trace[1702990269] 'compare' (duration: 53.951047ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:12:37.245041Z","caller":"traceutil/trace.go:171","msg":"trace[1342413036] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"115.881776ms","start":"2026-04-21T18:12:37.12911Z","end":"2026-04-21T18:12:37.244992Z","steps":["trace[1342413036] 'process raft request' (duration: 115.5171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:12:37.644733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.434053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-21T18:12:37.644919Z","caller":"traceutil/trace.go:171","msg":"trace[1075968754] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"211.555021ms","start":"2026-04-21T18:12:37.433335Z","end":"2026-04-21T18:12:37.64489Z","steps":["trace[1075968754] 'process raft request' (duration: 81.383856ms)","trace[1075968754] 'compare' (duration: 129.180032ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:12:37.645305Z","caller":"traceutil/trace.go:171","msg":"trace[1662538037] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"210.145819ms","start":"2026-04-21T18:12:37.435146Z","end":"2026-04-21T18:12:37.645291Z","steps":["trace[1662538037] 'process raft request' (duration: 209.7083ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:38.060132Z","caller":"traceutil/trace.go:171","msg":"trace[984432575] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:73; }","duration":"239.04039ms","start":"2026-04-21T18:12:37.82106Z","end":"2026-04-21T18:12:38.0601Z","steps":["trace[984432575] 'read index received' (duration: 185.152176ms)","trace[984432575] 'applied index is now lower than readState.Index' (duration: 53.887294ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:12:38.060215Z","caller":"traceutil/trace.go:171","msg":"trace[1481138180] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"280.059178ms","start":"2026-04-21T18:12:37.780108Z","end":"2026-04-21T18:12:38.060168Z","steps":["trace[1481138180] 'process raft request' (duration: 226.175604ms)","trace[1481138180] 'compare' (duration: 53.650524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:12:38.060455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.286771ms","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-21T18:12:38.060427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.25233ms","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-21T18:12:38.060525Z","caller":"traceutil/trace.go:171","msg":"trace[728266777] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:70; }","duration":"239.382366ms","start":"2026-04-21T18:12:37.821125Z","end":"2026-04-21T18:12:38.060508Z","steps":["trace[728266777] 'agreement among raft nodes before linearized reading' (duration: 239.273161ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:38.060544Z","caller":"traceutil/trace.go:171","msg":"trace[1014988335] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:70; }","duration":"239.509421ms","start":"2026-04-21T18:12:37.821012Z","end":"2026-04-21T18:12:38.060522Z","steps":["trace[1014988335] 'agreement among raft nodes before linearized reading' (duration: 239.265761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:12:38.060548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.359309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T18:12:38.060732Z","caller":"traceutil/trace.go:171","msg":"trace[1791038713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:70; }","duration":"218.480614ms","start":"2026-04-21T18:12:37.842148Z","end":"2026-04-21T18:12:38.060629Z","steps":["trace[1791038713] 'agreement among raft nodes before linearized reading' (duration: 218.298996ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:38.745658Z","caller":"traceutil/trace.go:171","msg":"trace[1056131679] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"147.935676ms","start":"2026-04-21T18:12:38.597695Z","end":"2026-04-21T18:12:38.745631Z","steps":["trace[1056131679] 'process raft request' (duration: 126.250992ms)","trace[1056131679] 'compare' (duration: 21.541587ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:12:55.496102Z","caller":"traceutil/trace.go:171","msg":"trace[1721049650] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"146.941679ms","start":"2026-04-21T18:12:55.349135Z","end":"2026-04-21T18:12:55.496077Z","steps":["trace[1721049650] 'read index received' (duration: 61.510184ms)","trace[1721049650] 'applied index is now lower than readState.Index' (duration: 85.430535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:12:55.49628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.123395ms","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-21T18:12:55.496246Z","caller":"traceutil/trace.go:171","msg":"trace[541736348] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"185.305379ms","start":"2026-04-21T18:12:55.310903Z","end":"2026-04-21T18:12:55.496209Z","steps":["trace[541736348] 'process raft request' (duration: 99.816212ms)","trace[541736348] 'compare' (duration: 85.259689ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:12:55.496339Z","caller":"traceutil/trace.go:171","msg":"trace[1456373253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:409; }","duration":"147.23928ms","start":"2026-04-21T18:12:55.349079Z","end":"2026-04-21T18:12:55.496319Z","steps":["trace[1456373253] 'agreement among raft nodes before linearized reading' (duration: 147.133306ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:03.647732Z","caller":"traceutil/trace.go:171","msg":"trace[1097712908] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"114.191861ms","start":"2026-04-21T18:13:03.533517Z","end":"2026-04-21T18:13:03.647708Z","steps":["trace[1097712908] 'process raft request' (duration: 114.083787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:04.359068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.574045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:13:04.359201Z","caller":"traceutil/trace.go:171","msg":"trace[1724095391] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"707.26137ms","start":"2026-04-21T18:13:03.651921Z","end":"2026-04-21T18:13:04.359182Z","steps":["trace[1724095391] 'process raft request' (duration: 316.263572ms)","trace[1724095391] 'compare' (duration: 390.501452ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:04.359323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:03.651901Z","time spent":"707.366953ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:13:04.740489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.336907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:04.740604Z","caller":"traceutil/trace.go:171","msg":"trace[37416047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"148.508743ms","start":"2026-04-21T18:13:04.592076Z","end":"2026-04-21T18:13:04.740585Z","steps":["trace[37416047] 'range keys from in-memory index tree' (duration: 148.252725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:09.607689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.013603ms","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:329952"} {"level":"info","ts":"2026-04-21T18:13:09.607803Z","caller":"traceutil/trace.go:171","msg":"trace[657275582] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:472; }","duration":"205.152958ms","start":"2026-04-21T18:13:09.402628Z","end":"2026-04-21T18:13:09.607781Z","steps":["trace[657275582] 'range keys from in-memory index tree' (duration: 204.661903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:10.169168Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409884844695334,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T18:13:10.327695Z","caller":"traceutil/trace.go:171","msg":"trace[1744056009] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"658.548246ms","start":"2026-04-21T18:13:09.669118Z","end":"2026-04-21T18:13:10.327666Z","steps":["trace[1744056009] 'read index received' (duration: 658.226086ms)","trace[1744056009] 'applied index is now lower than readState.Index' (duration: 320.57µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:10.327736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:09.608894Z","time spent":"718.834703ms","remote":"127.0.0.1:47716","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-21T18:13:10.327894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.758872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T18:13:10.327939Z","caller":"traceutil/trace.go:171","msg":"trace[1501409018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"658.854715ms","start":"2026-04-21T18:13:09.669072Z","end":"2026-04-21T18:13:10.327926Z","steps":["trace[1501409018] 'agreement among raft nodes before linearized reading' (duration: 658.742892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:10.327973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:09.669052Z","time spent":"658.913276ms","remote":"127.0.0.1:47958","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-21T18:13:10.328502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.707328ms","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:329952"} {"level":"info","ts":"2026-04-21T18:13:10.328595Z","caller":"traceutil/trace.go:171","msg":"trace[1480932888] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:472; }","duration":"617.814451ms","start":"2026-04-21T18:13:09.710754Z","end":"2026-04-21T18:13:10.328568Z","steps":["trace[1480932888] 'agreement among raft nodes before linearized reading' (duration: 617.08605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:10.328646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:09.710732Z","time spent":"617.902614ms","remote":"127.0.0.1:47774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329974,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-04-21T18:13:10.662182Z","caller":"traceutil/trace.go:171","msg":"trace[1118747206] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"333.199111ms","start":"2026-04-21T18:13:10.328956Z","end":"2026-04-21T18:13:10.662155Z","steps":["trace[1118747206] 'process raft request' (duration: 332.998505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:10.662338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:10.328942Z","time spent":"333.318265ms","remote":"127.0.0.1:47716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:10.685014Z","caller":"traceutil/trace.go:171","msg":"trace[584222616] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:488; }","duration":"304.307869ms","start":"2026-04-21T18:13:10.380591Z","end":"2026-04-21T18:13:10.684899Z","steps":["trace[584222616] 'read index received' (duration: 281.378463ms)","trace[584222616] 'applied index is now lower than readState.Index' (duration: 22.928726ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:10.685104Z","caller":"traceutil/trace.go:171","msg":"trace[1030906029] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"352.83152ms","start":"2026-04-21T18:13:10.332263Z","end":"2026-04-21T18:13:10.685095Z","steps":["trace[1030906029] 'process raft request' (duration: 352.51885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:10.685184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:10.332249Z","time spent":"352.874341ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:13:10.685258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.65731ms","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-21T18:13:10.685334Z","caller":"traceutil/trace.go:171","msg":"trace[1174836169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"304.768172ms","start":"2026-04-21T18:13:10.380547Z","end":"2026-04-21T18:13:10.685316Z","steps":["trace[1174836169] 'agreement among raft nodes before linearized reading' (duration: 304.648789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:10.68537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:10.380529Z","time spent":"304.833045ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T18:13:12.558305Z","caller":"traceutil/trace.go:171","msg":"trace[1024515138] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"539.001351ms","start":"2026-04-21T18:13:12.019261Z","end":"2026-04-21T18:13:12.558262Z","steps":["trace[1024515138] 'process raft request' (duration: 444.000343ms)","trace[1024515138] 'compare' (duration: 94.539464ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:12.558356Z","caller":"traceutil/trace.go:171","msg":"trace[1849670930] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"538.59825ms","start":"2026-04-21T18:13:12.019736Z","end":"2026-04-21T18:13:12.558334Z","steps":["trace[1849670930] 'process raft request' (duration: 538.374843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:12.558479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:12.019224Z","time spent":"539.184356ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:13:12.558523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:12.019713Z","time spent":"538.695842ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:12.558677Z","caller":"traceutil/trace.go:171","msg":"trace[524497203] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"522.037022ms","start":"2026-04-21T18:13:12.036602Z","end":"2026-04-21T18:13:12.558639Z","steps":["trace[524497203] 'process raft request' (duration: 521.648231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:12.55884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:12.036573Z","time spent":"522.182687ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:12.796968Z","caller":"traceutil/trace.go:171","msg":"trace[524187923] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"222.46661ms","start":"2026-04-21T18:13:12.574475Z","end":"2026-04-21T18:13:12.796942Z","steps":["trace[524187923] 'process raft request' (duration: 219.209866ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:12.807544Z","caller":"traceutil/trace.go:171","msg":"trace[189788839] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"217.64537ms","start":"2026-04-21T18:13:12.58988Z","end":"2026-04-21T18:13:12.807525Z","steps":["trace[189788839] 'process raft request' (duration: 217.500476ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:12.807662Z","caller":"traceutil/trace.go:171","msg":"trace[1798702954] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:548; }","duration":"119.016079ms","start":"2026-04-21T18:13:12.68864Z","end":"2026-04-21T18:13:12.807656Z","steps":["trace[1798702954] 'read index received' (duration: 105.058607ms)","trace[1798702954] 'applied index is now lower than readState.Index' (duration: 13.956892ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:12.807695Z","caller":"traceutil/trace.go:171","msg":"trace[1533606803] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"192.768744ms","start":"2026-04-21T18:13:12.614921Z","end":"2026-04-21T18:13:12.80769Z","steps":["trace[1533606803] 'process raft request' (duration: 192.577229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:12.807786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.145942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T18:13:12.80781Z","caller":"traceutil/trace.go:171","msg":"trace[496075846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"119.218305ms","start":"2026-04-21T18:13:12.688588Z","end":"2026-04-21T18:13:12.807806Z","steps":["trace[496075846] 'agreement among raft nodes before linearized reading' (duration: 119.138262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:12.807908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.731129ms","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-21T18:13:12.807927Z","caller":"traceutil/trace.go:171","msg":"trace[406202559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"111.787301ms","start":"2026-04-21T18:13:12.696135Z","end":"2026-04-21T18:13:12.807923Z","steps":["trace[406202559] 'agreement among raft nodes before linearized reading' (duration: 111.75689ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:13.94227Z","caller":"traceutil/trace.go:171","msg":"trace[1858084266] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"112.795523ms","start":"2026-04-21T18:13:13.829449Z","end":"2026-04-21T18:13:13.942245Z","steps":["trace[1858084266] 'process raft request' (duration: 112.291919ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:14.404549Z","caller":"traceutil/trace.go:171","msg":"trace[205775313] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"511.780492ms","start":"2026-04-21T18:13:13.892752Z","end":"2026-04-21T18:13:14.404532Z","steps":["trace[205775313] 'process raft request' (duration: 446.272927ms)","trace[205775313] 'compare' (duration: 65.109314ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:14.40466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:13.892734Z","time spent":"511.862684ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:13:14.404814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.567135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-04-21T18:13:14.404905Z","caller":"traceutil/trace.go:171","msg":"trace[382056655] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:558; }","duration":"439.691128ms","start":"2026-04-21T18:13:13.965194Z","end":"2026-04-21T18:13:14.404885Z","steps":["trace[382056655] 'agreement among raft nodes before linearized reading' (duration: 439.476322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:14.404948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:13.965175Z","time spent":"439.76193ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":1,"response size":23103,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-04-21T18:13:14.407158Z","caller":"traceutil/trace.go:171","msg":"trace[693083319] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"439.02982ms","start":"2026-04-21T18:13:13.965232Z","end":"2026-04-21T18:13:14.404262Z","steps":["trace[693083319] 'read index received' (duration: 373.687159ms)","trace[693083319] 'applied index is now lower than readState.Index' (duration: 65.341711ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:14.803293Z","caller":"traceutil/trace.go:171","msg":"trace[2133707842] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"342.21427ms","start":"2026-04-21T18:13:14.461056Z","end":"2026-04-21T18:13:14.803271Z","steps":["trace[2133707842] 'read index received' (duration: 341.931772ms)","trace[2133707842] 'applied index is now lower than readState.Index' (duration: 281.668µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:14.803388Z","caller":"traceutil/trace.go:171","msg":"trace[356271391] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"355.808813ms","start":"2026-04-21T18:13:14.447503Z","end":"2026-04-21T18:13:14.803311Z","steps":["trace[356271391] 'process raft request' (duration: 355.535226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:14.803534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:14.447483Z","time spent":"355.976289ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:13:14.804093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.030373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-04-21T18:13:14.804127Z","caller":"traceutil/trace.go:171","msg":"trace[1962287462] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:559; }","duration":"343.086225ms","start":"2026-04-21T18:13:14.461031Z","end":"2026-04-21T18:13:14.804117Z","steps":["trace[1962287462] 'agreement among raft nodes before linearized reading' (duration: 342.326804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:14.804149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:14.461019Z","time spent":"343.124996ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65458,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-21T18:13:14.843995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.759386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:14.844066Z","caller":"traceutil/trace.go:171","msg":"trace[117608409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"249.880359ms","start":"2026-04-21T18:13:14.594173Z","end":"2026-04-21T18:13:14.844053Z","steps":["trace[117608409] 'agreement among raft nodes before linearized reading' (duration: 249.773176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:15.043855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.96829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:13:15.043951Z","caller":"traceutil/trace.go:171","msg":"trace[1165187421] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"178.852496ms","start":"2026-04-21T18:13:14.865083Z","end":"2026-04-21T18:13:15.043936Z","steps":["trace[1165187421] 'read index received' (duration: 14.134909ms)","trace[1165187421] 'applied index is now lower than readState.Index' (duration: 164.716347ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:15.043997Z","caller":"traceutil/trace.go:171","msg":"trace[56815224] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"196.592765ms","start":"2026-04-21T18:13:14.847377Z","end":"2026-04-21T18:13:15.04397Z","steps":["trace[56815224] 'process raft request' (duration: 50.441171ms)","trace[56815224] 'compare' (duration: 145.775244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:15.044097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.01413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-04-21T18:13:15.044126Z","caller":"traceutil/trace.go:171","msg":"trace[1440501981] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:561; }","duration":"179.064751ms","start":"2026-04-21T18:13:14.865052Z","end":"2026-04-21T18:13:15.044116Z","steps":["trace[1440501981] 'agreement among raft nodes before linearized reading' (duration: 178.926277ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:17.046403Z","caller":"traceutil/trace.go:171","msg":"trace[1976299407] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"162.617067ms","start":"2026-04-21T18:13:16.883762Z","end":"2026-04-21T18:13:17.046379Z","steps":["trace[1976299407] 'process raft request' (duration: 162.272317ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:17.143624Z","caller":"traceutil/trace.go:171","msg":"trace[1774341309] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"256.242297ms","start":"2026-04-21T18:13:16.887341Z","end":"2026-04-21T18:13:17.143583Z","steps":["trace[1774341309] 'process raft request' (duration: 205.786962ms)","trace[1774341309] 'compare' (duration: 50.106976ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:17.34415Z","caller":"traceutil/trace.go:171","msg":"trace[1696503937] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"196.533094ms","start":"2026-04-21T18:13:17.147587Z","end":"2026-04-21T18:13:17.34412Z","steps":["trace[1696503937] 'process raft request' (duration: 156.209786ms)","trace[1696503937] 'compare' (duration: 40.151864ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:17.7349Z","caller":"traceutil/trace.go:171","msg":"trace[1070300563] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"143.323695ms","start":"2026-04-21T18:13:17.591553Z","end":"2026-04-21T18:13:17.734876Z","steps":["trace[1070300563] 'read index received' (duration: 140.896589ms)","trace[1070300563] 'applied index is now lower than readState.Index' (duration: 2.426106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:17.73505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.47582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:17.735089Z","caller":"traceutil/trace.go:171","msg":"trace[1047936014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"143.566342ms","start":"2026-04-21T18:13:17.591511Z","end":"2026-04-21T18:13:17.735078Z","steps":["trace[1047936014] 'agreement among raft nodes before linearized reading' (duration: 143.469919ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:17.735945Z","caller":"traceutil/trace.go:171","msg":"trace[2110130122] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"200.717277ms","start":"2026-04-21T18:13:17.535202Z","end":"2026-04-21T18:13:17.735919Z","steps":["trace[2110130122] 'process raft request' (duration: 197.90189ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:17.792641Z","caller":"traceutil/trace.go:171","msg":"trace[1337872932] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"147.574341ms","start":"2026-04-21T18:13:17.64504Z","end":"2026-04-21T18:13:17.792614Z","steps":["trace[1337872932] 'process raft request' (duration: 147.219841ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:19.271257Z","caller":"traceutil/trace.go:171","msg":"trace[206076576] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"123.645466ms","start":"2026-04-21T18:13:19.147591Z","end":"2026-04-21T18:13:19.271237Z","steps":["trace[206076576] 'read index received' (duration: 68.341982ms)","trace[206076576] 'applied index is now lower than readState.Index' (duration: 55.302664ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:19.271375Z","caller":"traceutil/trace.go:171","msg":"trace[696350110] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"210.252784ms","start":"2026-04-21T18:13:19.061025Z","end":"2026-04-21T18:13:19.271278Z","steps":["trace[696350110] 'process raft request' (duration: 154.97145ms)","trace[696350110] 'compare' (duration: 55.12366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:19.271589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.975694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T18:13:19.271687Z","caller":"traceutil/trace.go:171","msg":"trace[1465207425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"124.078857ms","start":"2026-04-21T18:13:19.147586Z","end":"2026-04-21T18:13:19.271665Z","steps":["trace[1465207425] 'agreement among raft nodes before linearized reading' (duration: 123.757999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:19.603401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.689576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:13:19.603534Z","caller":"traceutil/trace.go:171","msg":"trace[1814329849] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"226.98483ms","start":"2026-04-21T18:13:19.376521Z","end":"2026-04-21T18:13:19.603506Z","steps":["trace[1814329849] 'read index received' (duration: 64.050157ms)","trace[1814329849] 'applied index is now lower than readState.Index' (duration: 162.932093ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:19.603708Z","caller":"traceutil/trace.go:171","msg":"trace[1730320054] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"252.101959ms","start":"2026-04-21T18:13:19.351554Z","end":"2026-04-21T18:13:19.603656Z","steps":["trace[1730320054] 'process raft request' (duration: 89.079414ms)","trace[1730320054] 'compare' (duration: 162.603074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:19.603786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.256507ms","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-21T18:13:19.603837Z","caller":"traceutil/trace.go:171","msg":"trace[834690995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"227.327429ms","start":"2026-04-21T18:13:19.37649Z","end":"2026-04-21T18:13:19.603818Z","steps":["trace[834690995] 'agreement among raft nodes before linearized reading' (duration: 227.150954ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:19.911263Z","caller":"traceutil/trace.go:171","msg":"trace[716394833] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"303.949481ms","start":"2026-04-21T18:13:19.607291Z","end":"2026-04-21T18:13:19.91124Z","steps":["trace[716394833] 'process raft request' (duration: 303.898569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:19.911405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:19.607281Z","time spent":"304.047473ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:19.911413Z","caller":"traceutil/trace.go:171","msg":"trace[789064150] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"304.182577ms","start":"2026-04-21T18:13:19.6072Z","end":"2026-04-21T18:13:19.911382Z","steps":["trace[789064150] 'process raft request' (duration: 271.722231ms)","trace[789064150] 'compare' (duration: 32.148247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:19.911596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:19.607179Z","time spent":"304.29572ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:19.997186Z","caller":"traceutil/trace.go:171","msg":"trace[1407195986] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"352.665738ms","start":"2026-04-21T18:13:19.644488Z","end":"2026-04-21T18:13:19.997154Z","steps":["trace[1407195986] 'process raft request' (duration: 352.250887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:19.997394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:19.64447Z","time spent":"352.833153ms","remote":"127.0.0.1:47716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:19.997586Z","caller":"traceutil/trace.go:171","msg":"trace[356921795] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"289.935417ms","start":"2026-04-21T18:13:19.707608Z","end":"2026-04-21T18:13:19.997544Z","steps":["trace[356921795] 'process raft request' (duration: 289.480875ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:20.664798Z","caller":"traceutil/trace.go:171","msg":"trace[1213087876] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"134.468795ms","start":"2026-04-21T18:13:20.530229Z","end":"2026-04-21T18:13:20.664698Z","steps":["trace[1213087876] 'process raft request' (duration: 133.740216ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:20.873609Z","caller":"traceutil/trace.go:171","msg":"trace[863344513] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"146.09251ms","start":"2026-04-21T18:13:20.727495Z","end":"2026-04-21T18:13:20.873588Z","steps":["trace[863344513] 'process raft request' (duration: 92.328763ms)","trace[863344513] 'compare' (duration: 53.608033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:21.763442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.904706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:21.763534Z","caller":"traceutil/trace.go:171","msg":"trace[391097887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"171.04684ms","start":"2026-04-21T18:13:21.592468Z","end":"2026-04-21T18:13:21.763515Z","steps":["trace[391097887] 'range keys from in-memory index tree' (duration: 170.814684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:21.763431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.826982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:13:21.763623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.396722ms","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-21T18:13:21.763632Z","caller":"traceutil/trace.go:171","msg":"trace[693038966] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:593; }","duration":"345.046777ms","start":"2026-04-21T18:13:21.418551Z","end":"2026-04-21T18:13:21.763598Z","steps":["trace[693038966] 'range keys from in-memory index tree' (duration: 341.593817ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:21.763709Z","caller":"traceutil/trace.go:171","msg":"trace[611468269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"155.517656ms","start":"2026-04-21T18:13:21.608172Z","end":"2026-04-21T18:13:21.76369Z","steps":["trace[611468269] 'range keys from in-memory index tree' (duration: 155.142956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:21.76376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:21.418536Z","time spent":"345.191982ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-21T18:13:22.12486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.424631ms","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-21T18:13:22.124966Z","caller":"traceutil/trace.go:171","msg":"trace[1011657461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"208.572395ms","start":"2026-04-21T18:13:21.916374Z","end":"2026-04-21T18:13:22.124947Z","steps":["trace[1011657461] 'range keys from in-memory index tree' (duration: 208.270267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:22.125176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.77857ms","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-21T18:13:22.125296Z","caller":"traceutil/trace.go:171","msg":"trace[1871912743] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"150.457329ms","start":"2026-04-21T18:13:21.974815Z","end":"2026-04-21T18:13:22.125273Z","steps":["trace[1871912743] 'process raft request' (duration: 131.686125ms)","trace[1871912743] 'compare' (duration: 18.459306ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:22.125355Z","caller":"traceutil/trace.go:171","msg":"trace[736812377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"208.971195ms","start":"2026-04-21T18:13:21.916322Z","end":"2026-04-21T18:13:22.125293Z","steps":["trace[736812377] 'range keys from in-memory index tree' (duration: 208.447312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:22.952747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.507697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:13:22.952867Z","caller":"traceutil/trace.go:171","msg":"trace[1473906134] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"824.109316ms","start":"2026-04-21T18:13:22.128735Z","end":"2026-04-21T18:13:22.952845Z","steps":["trace[1473906134] 'process raft request' (duration: 301.411214ms)","trace[1473906134] 'compare' (duration: 522.403674ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:22.952944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:22.128711Z","time spent":"824.195128ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:23.250378Z","caller":"traceutil/trace.go:171","msg":"trace[1609994295] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"936.731414ms","start":"2026-04-21T18:13:22.313625Z","end":"2026-04-21T18:13:23.250357Z","steps":["trace[1609994295] 'read index received' (duration: 116.456824ms)","trace[1609994295] 'applied index is now lower than readState.Index' (duration: 820.27391ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:23.250446Z","caller":"traceutil/trace.go:171","msg":"trace[1770638713] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.078309134s","start":"2026-04-21T18:13:22.172104Z","end":"2026-04-21T18:13:23.250413Z","steps":["trace[1770638713] 'process raft request' (duration: 1.059469793s)","trace[1770638713] 'compare' (duration: 18.485272ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:23.250548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.895678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-77z5l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-21T18:13:23.250584Z","caller":"traceutil/trace.go:171","msg":"trace[1519076406] range","detail":"{range_begin:/registry/pods/kube-system/cilium-77z5l; range_end:; response_count:1; response_revision:598; }","duration":"937.073062ms","start":"2026-04-21T18:13:22.313501Z","end":"2026-04-21T18:13:23.250574Z","steps":["trace[1519076406] 'agreement among raft nodes before linearized reading' (duration: 936.961319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:23.250614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:22.313472Z","time spent":"937.134444ms","remote":"127.0.0.1:47880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-77z5l\" "} {"level":"warn","ts":"2026-04-21T18:13:23.250725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.572187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:13:23.250721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:22.172068Z","time spent":"1.07854284s","remote":"127.0.0.1:47774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:23.250796Z","caller":"traceutil/trace.go:171","msg":"trace[416247928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"656.677399ms","start":"2026-04-21T18:13:22.594102Z","end":"2026-04-21T18:13:23.25078Z","steps":["trace[416247928] 'agreement among raft nodes before linearized reading' (duration: 656.493865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:23.250846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:22.59398Z","time spent":"656.859184ms","remote":"127.0.0.1:47694","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-21T18:13:23.822659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409884844695624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:13:24.323347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409884844695624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:13:24.728112Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.465818014s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T18:13:24.728777Z","caller":"traceutil/trace.go:171","msg":"trace[239028225] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.466560403s","start":"2026-04-21T18:13:23.262189Z","end":"2026-04-21T18:13:24.72875Z","steps":["trace[239028225] 'process raft request' (duration: 1.466372049s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:24.72896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:23.262171Z","time spent":"1.466687746s","remote":"127.0.0.1:47880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:24.787031Z","caller":"traceutil/trace.go:171","msg":"trace[1837723996] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"1.464841913s","start":"2026-04-21T18:13:23.322163Z","end":"2026-04-21T18:13:24.787005Z","steps":["trace[1837723996] 'read index received' (duration: 1.406399958s)","trace[1837723996] 'applied index is now lower than readState.Index' (duration: 58.440895ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:24.787135Z","caller":"traceutil/trace.go:171","msg":"trace[239678078] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.47882607s","start":"2026-04-21T18:13:23.308283Z","end":"2026-04-21T18:13:24.787109Z","steps":["trace[239678078] 'process raft request' (duration: 1.478564633s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:24.787244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:23.308266Z","time spent":"1.478919273s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:13:24.787257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193053828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:24.787307Z","caller":"traceutil/trace.go:171","msg":"trace[524203373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"1.19313777s","start":"2026-04-21T18:13:23.594154Z","end":"2026-04-21T18:13:24.787292Z","steps":["trace[524203373] 'agreement among raft nodes before linearized reading' (duration: 1.193005207s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:24.787313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465087789s","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-21T18:13:24.787352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:23.594138Z","time spent":"1.193203022s","remote":"127.0.0.1:47694","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-21T18:13:24.787356Z","caller":"traceutil/trace.go:171","msg":"trace[1457812230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.465213613s","start":"2026-04-21T18:13:23.32213Z","end":"2026-04-21T18:13:24.787344Z","steps":["trace[1457812230] 'agreement among raft nodes before linearized reading' (duration: 1.465001617s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:24.787386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:23.322112Z","time spent":"1.465264813s","remote":"127.0.0.1:47958","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-21T18:13:24.787448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.612291ms","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-21T18:13:24.787504Z","caller":"traceutil/trace.go:171","msg":"trace[504979748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"638.707312ms","start":"2026-04-21T18:13:24.14878Z","end":"2026-04-21T18:13:24.787488Z","steps":["trace[504979748] 'agreement among raft nodes before linearized reading' (duration: 638.524258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:24.787531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:24.148756Z","time spent":"638.769614ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T18:13:24.787587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.65773ms","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-21T18:13:24.787651Z","caller":"traceutil/trace.go:171","msg":"trace[1634420694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"964.777833ms","start":"2026-04-21T18:13:23.822857Z","end":"2026-04-21T18:13:24.787635Z","steps":["trace[1634420694] 'agreement among raft nodes before linearized reading' (duration: 964.683181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:24.787695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:23.822837Z","time spent":"964.844764ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T18:13:26.803121Z","caller":"traceutil/trace.go:171","msg":"trace[1734791755] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"208.962774ms","start":"2026-04-21T18:13:26.594118Z","end":"2026-04-21T18:13:26.803081Z","steps":["trace[1734791755] 'read index received' (duration: 208.675607ms)","trace[1734791755] 'applied index is now lower than readState.Index' (duration: 285.487µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:26.803303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.163612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:13:26.803328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.182799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:26.80335Z","caller":"traceutil/trace.go:171","msg":"trace[1877094350] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:638; }","duration":"182.274034ms","start":"2026-04-21T18:13:26.621061Z","end":"2026-04-21T18:13:26.803335Z","steps":["trace[1877094350] 'agreement among raft nodes before linearized reading' (duration: 182.189362ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:26.803392Z","caller":"traceutil/trace.go:171","msg":"trace[1874375452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"209.280251ms","start":"2026-04-21T18:13:26.594097Z","end":"2026-04-21T18:13:26.803377Z","steps":["trace[1874375452] 'agreement among raft nodes before linearized reading' (duration: 209.085216ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:26.803214Z","caller":"traceutil/trace.go:171","msg":"trace[1341582401] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"365.088352ms","start":"2026-04-21T18:13:26.438086Z","end":"2026-04-21T18:13:26.803174Z","steps":["trace[1341582401] 'process raft request' (duration: 364.819786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:26.803664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:26.438067Z","time spent":"365.479881ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:13:30.623384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.861947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-77z5l\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-04-21T18:13:30.623471Z","caller":"traceutil/trace.go:171","msg":"trace[1801593224] range","detail":"{range_begin:/registry/pods/kube-system/cilium-77z5l; range_end:; response_count:1; response_revision:684; }","duration":"256.98831ms","start":"2026-04-21T18:13:30.366463Z","end":"2026-04-21T18:13:30.623452Z","steps":["trace[1801593224] 'range keys from in-memory index tree' (duration: 256.591491ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:30.623782Z","caller":"traceutil/trace.go:171","msg":"trace[1369741728] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"256.273313ms","start":"2026-04-21T18:13:30.367445Z","end":"2026-04-21T18:13:30.623718Z","steps":["trace[1369741728] 'process raft request' (duration: 227.595983ms)","trace[1369741728] 'compare' (duration: 28.185208ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:30.623946Z","caller":"traceutil/trace.go:171","msg":"trace[747009235] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"202.191992ms","start":"2026-04-21T18:13:30.421734Z","end":"2026-04-21T18:13:30.623926Z","steps":["trace[747009235] 'read index received' (duration: 173.313677ms)","trace[747009235] 'applied index is now lower than readState.Index' (duration: 28.875794ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:30.624245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.495358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-21T18:13:30.62432Z","caller":"traceutil/trace.go:171","msg":"trace[771828468] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:685; }","duration":"202.611651ms","start":"2026-04-21T18:13:30.421688Z","end":"2026-04-21T18:13:30.6243Z","steps":["trace[771828468] 'agreement among raft nodes before linearized reading' (duration: 202.373256ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:30.867267Z","caller":"traceutil/trace.go:171","msg":"trace[922149730] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"230.01151ms","start":"2026-04-21T18:13:30.637236Z","end":"2026-04-21T18:13:30.867248Z","steps":["trace[922149730] 'process raft request' (duration: 228.920784ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:31.099027Z","caller":"traceutil/trace.go:171","msg":"trace[273724960] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"225.525672ms","start":"2026-04-21T18:13:30.87347Z","end":"2026-04-21T18:13:31.098996Z","steps":["trace[273724960] 'process raft request' (duration: 138.501841ms)","trace[273724960] 'compare' (duration: 86.675292ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:31.099142Z","caller":"traceutil/trace.go:171","msg":"trace[290953204] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:711; }","duration":"107.990506ms","start":"2026-04-21T18:13:30.991137Z","end":"2026-04-21T18:13:31.099127Z","steps":["trace[290953204] 'read index received' (duration: 20.801711ms)","trace[290953204] 'applied index is now lower than readState.Index' (duration: 87.187555ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:31.09929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.139919ms","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-21T18:13:31.099329Z","caller":"traceutil/trace.go:171","msg":"trace[969733163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"108.216981ms","start":"2026-04-21T18:13:30.991101Z","end":"2026-04-21T18:13:31.099318Z","steps":["trace[969733163] 'agreement among raft nodes before linearized reading' (duration: 108.082948ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:31.099421Z","caller":"traceutil/trace.go:171","msg":"trace[1392875737] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"225.707785ms","start":"2026-04-21T18:13:30.873577Z","end":"2026-04-21T18:13:31.099285Z","steps":["trace[1392875737] 'process raft request' (duration: 225.294636ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:31.337782Z","caller":"traceutil/trace.go:171","msg":"trace[1905054486] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"116.542553ms","start":"2026-04-21T18:13:31.221218Z","end":"2026-04-21T18:13:31.337761Z","steps":["trace[1905054486] 'read index received' (duration: 115.97988ms)","trace[1905054486] 'applied index is now lower than readState.Index' (duration: 561.773µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:31.337999Z","caller":"traceutil/trace.go:171","msg":"trace[393962936] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"143.464554ms","start":"2026-04-21T18:13:31.194522Z","end":"2026-04-21T18:13:31.337987Z","steps":["trace[393962936] 'process raft request' (duration: 142.715667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:31.338268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.028454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:31.338311Z","caller":"traceutil/trace.go:171","msg":"trace[1154084260] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:691; }","duration":"117.082086ms","start":"2026-04-21T18:13:31.221214Z","end":"2026-04-21T18:13:31.338296Z","steps":["trace[1154084260] 'agreement among raft nodes before linearized reading' (duration: 116.953633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:31.385329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.041637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:31.38539Z","caller":"traceutil/trace.go:171","msg":"trace[977071370] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:692; }","duration":"164.127899ms","start":"2026-04-21T18:13:31.22125Z","end":"2026-04-21T18:13:31.385378Z","steps":["trace[977071370] 'agreement among raft nodes before linearized reading' (duration: 164.011436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:31.385599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.38855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:31.385654Z","caller":"traceutil/trace.go:171","msg":"trace[1291579495] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:692; }","duration":"151.456102ms","start":"2026-04-21T18:13:31.234185Z","end":"2026-04-21T18:13:31.385641Z","steps":["trace[1291579495] 'agreement among raft nodes before linearized reading' (duration: 151.36012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:31.385754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.076934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:31.385782Z","caller":"traceutil/trace.go:171","msg":"trace[1257988144] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:692; }","duration":"105.130625ms","start":"2026-04-21T18:13:31.280644Z","end":"2026-04-21T18:13:31.385774Z","steps":["trace[1257988144] 'agreement among raft nodes before linearized reading' (duration: 105.087864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:31.385803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.741859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:13:31.385839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.552859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:31.385878Z","caller":"traceutil/trace.go:171","msg":"trace[1362413959] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:692; }","duration":"151.849741ms","start":"2026-04-21T18:13:31.234007Z","end":"2026-04-21T18:13:31.385857Z","steps":["trace[1362413959] 'agreement among raft nodes before linearized reading' (duration: 151.714158ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:31.385866Z","caller":"traceutil/trace.go:171","msg":"trace[471418984] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:692; }","duration":"164.59236ms","start":"2026-04-21T18:13:31.221268Z","end":"2026-04-21T18:13:31.38586Z","steps":["trace[471418984] 'agreement among raft nodes before linearized reading' (duration: 164.520158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:31.385891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.183077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:31.385915Z","caller":"traceutil/trace.go:171","msg":"trace[952367875] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:692; }","duration":"105.228928ms","start":"2026-04-21T18:13:31.28068Z","end":"2026-04-21T18:13:31.385909Z","steps":["trace[952367875] 'agreement among raft nodes before linearized reading' (duration: 105.192067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:31.385995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.863201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:31.386119Z","caller":"traceutil/trace.go:171","msg":"trace[1989000302] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:692; }","duration":"152.021406ms","start":"2026-04-21T18:13:31.234072Z","end":"2026-04-21T18:13:31.386093Z","steps":["trace[1989000302] 'agreement among raft nodes before linearized reading' (duration: 151.737819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:31.386039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.410742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:31.386476Z","caller":"traceutil/trace.go:171","msg":"trace[1991971833] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:692; }","duration":"105.847833ms","start":"2026-04-21T18:13:31.280593Z","end":"2026-04-21T18:13:31.386441Z","steps":["trace[1991971833] 'agreement among raft nodes before linearized reading' (duration: 105.413643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:32.632214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.184926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:13:32.632322Z","caller":"traceutil/trace.go:171","msg":"trace[1115696534] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"244.170597ms","start":"2026-04-21T18:13:32.388134Z","end":"2026-04-21T18:13:32.632305Z","steps":["trace[1115696534] 'process raft request' (duration: 68.844138ms)","trace[1115696534] 'compare' (duration: 174.962121ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:32.787863Z","caller":"traceutil/trace.go:171","msg":"trace[1099944800] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"396.195407ms","start":"2026-04-21T18:13:32.391639Z","end":"2026-04-21T18:13:32.787834Z","steps":["trace[1099944800] 'process raft request' (duration: 395.711876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:32.788088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:32.391632Z","time spent":"396.31519ms","remote":"127.0.0.1:47868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4363,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:32.805241Z","caller":"traceutil/trace.go:171","msg":"trace[431563070] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:719; }","duration":"242.608681ms","start":"2026-04-21T18:13:32.562605Z","end":"2026-04-21T18:13:32.805213Z","steps":["trace[431563070] 'read index received' (duration: 224.679075ms)","trace[431563070] 'applied index is now lower than readState.Index' (duration: 17.928716ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:32.805347Z","caller":"traceutil/trace.go:171","msg":"trace[377656390] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"326.943349ms","start":"2026-04-21T18:13:32.478394Z","end":"2026-04-21T18:13:32.805338Z","steps":["trace[377656390] 'process raft request' (duration: 326.704723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:32.805434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:32.478381Z","time spent":"326.9892ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:13:32.805569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.195499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:32.805604Z","caller":"traceutil/trace.go:171","msg":"trace[450905613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"213.26988ms","start":"2026-04-21T18:13:32.592327Z","end":"2026-04-21T18:13:32.805597Z","steps":["trace[450905613] 'agreement among raft nodes before linearized reading' (duration: 213.221309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:32.805696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.086242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-77z5l\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-04-21T18:13:32.805731Z","caller":"traceutil/trace.go:171","msg":"trace[2014716367] range","detail":"{range_begin:/registry/pods/kube-system/cilium-77z5l; range_end:; response_count:1; response_revision:697; }","duration":"243.155694ms","start":"2026-04-21T18:13:32.562566Z","end":"2026-04-21T18:13:32.805722Z","steps":["trace[2014716367] 'agreement among raft nodes before linearized reading' (duration: 242.97207ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:33.120153Z","caller":"traceutil/trace.go:171","msg":"trace[1060333709] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"313.280605ms","start":"2026-04-21T18:13:32.806848Z","end":"2026-04-21T18:13:33.120128Z","steps":["trace[1060333709] 'process raft request' (duration: 287.41194ms)","trace[1060333709] 'compare' (duration: 25.704481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:33.120298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:32.806834Z","time spent":"313.383787ms","remote":"127.0.0.1:47868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4250,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:33.341085Z","caller":"traceutil/trace.go:171","msg":"trace[679770546] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:721; }","duration":"398.705905ms","start":"2026-04-21T18:13:32.942357Z","end":"2026-04-21T18:13:33.341063Z","steps":["trace[679770546] 'read index received' (duration: 152.025057ms)","trace[679770546] 'applied index is now lower than readState.Index' (duration: 246.679418ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:33.341222Z","caller":"traceutil/trace.go:171","msg":"trace[165348789] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"524.239689ms","start":"2026-04-21T18:13:32.816952Z","end":"2026-04-21T18:13:33.341192Z","steps":["trace[165348789] 'process raft request' (duration: 517.291889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:33.341354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.975651ms","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-21T18:13:33.341468Z","caller":"traceutil/trace.go:171","msg":"trace[160390056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:699; }","duration":"399.123265ms","start":"2026-04-21T18:13:32.942326Z","end":"2026-04-21T18:13:33.34145Z","steps":["trace[160390056] 'agreement among raft nodes before linearized reading' (duration: 398.883939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:33.341528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.637937ms","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-21T18:13:33.341559Z","caller":"traceutil/trace.go:171","msg":"trace[237640105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:699; }","duration":"236.710929ms","start":"2026-04-21T18:13:33.10484Z","end":"2026-04-21T18:13:33.341551Z","steps":["trace[237640105] 'agreement among raft nodes before linearized reading' (duration: 236.591586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:33.341567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:32.942309Z","time spent":"399.242747ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T18:13:33.341596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.583072ms","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-21T18:13:33.341655Z","caller":"traceutil/trace.go:171","msg":"trace[1074977391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:699; }","duration":"188.674405ms","start":"2026-04-21T18:13:33.152967Z","end":"2026-04-21T18:13:33.341642Z","steps":["trace[1074977391] 'agreement among raft nodes before linearized reading' (duration: 188.519981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:33.341392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:32.81694Z","time spent":"524.377442ms","remote":"127.0.0.1:47880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:13:33.34177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.850442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T18:13:33.342003Z","caller":"traceutil/trace.go:171","msg":"trace[1780680087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:699; }","duration":"237.118328ms","start":"2026-04-21T18:13:33.104873Z","end":"2026-04-21T18:13:33.341992Z","steps":["trace[1780680087] 'agreement among raft nodes before linearized reading' (duration: 236.740619ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:33.464511Z","caller":"traceutil/trace.go:171","msg":"trace[2143984535] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"118.124125ms","start":"2026-04-21T18:13:33.346367Z","end":"2026-04-21T18:13:33.464491Z","steps":["trace[2143984535] 'process raft request' (duration: 108.505103ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:33.464837Z","caller":"traceutil/trace.go:171","msg":"trace[2057653737] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"117.687604ms","start":"2026-04-21T18:13:33.347078Z","end":"2026-04-21T18:13:33.464766Z","steps":["trace[2057653737] 'process raft request' (duration: 117.582972ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:33.464955Z","caller":"traceutil/trace.go:171","msg":"trace[156152089] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"118.38792ms","start":"2026-04-21T18:13:33.346383Z","end":"2026-04-21T18:13:33.464771Z","steps":["trace[156152089] 'process raft request' (duration: 118.077993ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:37.557154Z","caller":"traceutil/trace.go:171","msg":"trace[1577650378] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"130.586501ms","start":"2026-04-21T18:13:37.426524Z","end":"2026-04-21T18:13:37.55711Z","steps":["trace[1577650378] 'process raft request' (duration: 130.371477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:38.628356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.458555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:13:38.628514Z","caller":"traceutil/trace.go:171","msg":"trace[1193293704] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"183.640792ms","start":"2026-04-21T18:13:38.444838Z","end":"2026-04-21T18:13:38.628478Z","steps":["trace[1193293704] 'process raft request' (duration: 77.970344ms)","trace[1193293704] 'compare' (duration: 105.320491ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:41.719784Z","caller":"traceutil/trace.go:171","msg":"trace[302962315] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:780; }","duration":"127.218208ms","start":"2026-04-21T18:13:41.592545Z","end":"2026-04-21T18:13:41.719763Z","steps":["trace[302962315] 'read index received' (duration: 73.595021ms)","trace[302962315] 'applied index is now lower than readState.Index' (duration: 53.622257ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:41.719836Z","caller":"traceutil/trace.go:171","msg":"trace[646594637] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"137.550878ms","start":"2026-04-21T18:13:41.582247Z","end":"2026-04-21T18:13:41.719798Z","steps":["trace[646594637] 'process raft request' (duration: 137.401065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:41.719928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.363162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:13:41.720002Z","caller":"traceutil/trace.go:171","msg":"trace[1613669268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"127.471424ms","start":"2026-04-21T18:13:41.592514Z","end":"2026-04-21T18:13:41.719985Z","steps":["trace[1613669268] 'agreement among raft nodes before linearized reading' (duration: 127.330111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:41.719991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.266489ms","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-21T18:13:41.720194Z","caller":"traceutil/trace.go:171","msg":"trace[499827059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"102.448562ms","start":"2026-04-21T18:13:41.617659Z","end":"2026-04-21T18:13:41.720108Z","steps":["trace[499827059] 'agreement among raft nodes before linearized reading' (duration: 102.224308ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:42.677822Z","caller":"traceutil/trace.go:171","msg":"trace[1716443707] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"201.119654ms","start":"2026-04-21T18:13:42.476679Z","end":"2026-04-21T18:13:42.677799Z","steps":["trace[1716443707] 'read index received' (duration: 200.90997ms)","trace[1716443707] 'applied index is now lower than readState.Index' (duration: 206.954µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:42.677943Z","caller":"traceutil/trace.go:171","msg":"trace[54808207] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"257.983519ms","start":"2026-04-21T18:13:42.41995Z","end":"2026-04-21T18:13:42.677933Z","steps":["trace[54808207] 'process raft request' (duration: 257.694183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:42.678174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.478831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-21T18:13:42.678215Z","caller":"traceutil/trace.go:171","msg":"trace[506508110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"201.568253ms","start":"2026-04-21T18:13:42.476636Z","end":"2026-04-21T18:13:42.678204Z","steps":["trace[506508110] 'agreement among raft nodes before linearized reading' (duration: 201.429981ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:42.879223Z","caller":"traceutil/trace.go:171","msg":"trace[368826070] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"196.658952ms","start":"2026-04-21T18:13:42.68254Z","end":"2026-04-21T18:13:42.879199Z","steps":["trace[368826070] 'process raft request' (duration: 138.385028ms)","trace[368826070] 'compare' (duration: 58.177902ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:45.316378Z","caller":"traceutil/trace.go:171","msg":"trace[1915979540] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"708.457458ms","start":"2026-04-21T18:13:44.607896Z","end":"2026-04-21T18:13:45.316354Z","steps":["trace[1915979540] 'process raft request' (duration: 708.296985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:45.31654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:44.60788Z","time spent":"708.57077ms","remote":"127.0.0.1:47880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:13:45.32342Z","caller":"traceutil/trace.go:171","msg":"trace[1558678598] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:800; }","duration":"439.831531ms","start":"2026-04-21T18:13:44.883571Z","end":"2026-04-21T18:13:45.323403Z","steps":["trace[1558678598] 'read index received' (duration: 432.585943ms)","trace[1558678598] 'applied index is now lower than readState.Index' (duration: 7.244648ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:45.323545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.193779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:13:45.323563Z","caller":"traceutil/trace.go:171","msg":"trace[8920871] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:773; }","duration":"409.267611ms","start":"2026-04-21T18:13:44.91429Z","end":"2026-04-21T18:13:45.323558Z","steps":["trace[8920871] 'agreement among raft nodes before linearized reading' (duration: 409.20856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:45.323584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:44.914269Z","time spent":"409.309413ms","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-21T18:13:45.323592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.011314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-21T18:13:45.323651Z","caller":"traceutil/trace.go:171","msg":"trace[1305136749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"440.119177ms","start":"2026-04-21T18:13:44.883519Z","end":"2026-04-21T18:13:45.323638Z","steps":["trace[1305136749] 'agreement among raft nodes before linearized reading' (duration: 439.949253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:45.323686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:13:44.883493Z","time spent":"440.186509ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-21T18:13:52.209202Z","caller":"traceutil/trace.go:171","msg":"trace[1140833168] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"114.867507ms","start":"2026-04-21T18:13:52.094309Z","end":"2026-04-21T18:13:52.209177Z","steps":["trace[1140833168] 'process raft request' (duration: 84.72668ms)","trace[1140833168] 'compare' (duration: 30.025495ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:52.238162Z","caller":"traceutil/trace.go:171","msg":"trace[929617112] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"141.324082ms","start":"2026-04-21T18:13:52.096799Z","end":"2026-04-21T18:13:52.238123Z","steps":["trace[929617112] 'process raft request' (duration: 140.979836ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:52.238326Z","caller":"traceutil/trace.go:171","msg":"trace[785716382] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"137.315436ms","start":"2026-04-21T18:13:52.100922Z","end":"2026-04-21T18:13:52.238237Z","steps":["trace[785716382] 'process raft request' (duration: 137.02214ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:19.808304Z","caller":"traceutil/trace.go:171","msg":"trace[969498269] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"260.562396ms","start":"2026-04-21T18:14:19.547726Z","end":"2026-04-21T18:14:19.808289Z","steps":["trace[969498269] 'read index received' (duration: 260.402144ms)","trace[969498269] 'applied index is now lower than readState.Index' (duration: 159.502µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:19.808402Z","caller":"traceutil/trace.go:171","msg":"trace[575914945] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"273.313411ms","start":"2026-04-21T18:14:19.535035Z","end":"2026-04-21T18:14:19.808349Z","steps":["trace[575914945] 'process raft request' (duration: 273.151669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:19.808465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.721889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:14:19.808487Z","caller":"traceutil/trace.go:171","msg":"trace[1287781722] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1016; }","duration":"260.79478ms","start":"2026-04-21T18:14:19.547685Z","end":"2026-04-21T18:14:19.80848Z","steps":["trace[1287781722] 'agreement among raft nodes before linearized reading' (duration: 260.693279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:19.80857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.673274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:14:19.808648Z","caller":"traceutil/trace.go:171","msg":"trace[1427476469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"214.796857ms","start":"2026-04-21T18:14:19.593831Z","end":"2026-04-21T18:14:19.808628Z","steps":["trace[1427476469] 'agreement among raft nodes before linearized reading' (duration: 214.654224ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:12.566522Z","caller":"traceutil/trace.go:171","msg":"trace[1979427223] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1236; }","duration":"258.58163ms","start":"2026-04-21T18:15:12.307914Z","end":"2026-04-21T18:15:12.566496Z","steps":["trace[1979427223] 'read index received' (duration: 12.736183ms)","trace[1979427223] 'applied index is now lower than readState.Index' (duration: 245.844237ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:12.566671Z","caller":"traceutil/trace.go:171","msg":"trace[964628195] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"276.627172ms","start":"2026-04-21T18:15:12.290022Z","end":"2026-04-21T18:15:12.566649Z","steps":["trace[964628195] 'process raft request' (duration: 275.59784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:12.566979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.040665ms","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:329952"} {"level":"warn","ts":"2026-04-21T18:15:12.567044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.132991ms","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-21T18:15:12.56707Z","caller":"traceutil/trace.go:171","msg":"trace[1852638347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1191; }","duration":"230.226011ms","start":"2026-04-21T18:15:12.336837Z","end":"2026-04-21T18:15:12.567063Z","steps":["trace[1852638347] 'agreement among raft nodes before linearized reading' (duration: 230.12011ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:12.567024Z","caller":"traceutil/trace.go:171","msg":"trace[2139870709] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1191; }","duration":"259.138946ms","start":"2026-04-21T18:15:12.307871Z","end":"2026-04-21T18:15:12.56701Z","steps":["trace[2139870709] 'agreement among raft nodes before linearized reading' (duration: 258.875643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:12.56717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.135516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:15:12.56719Z","caller":"traceutil/trace.go:171","msg":"trace[944429944] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1191; }","duration":"110.156716ms","start":"2026-04-21T18:15:12.457026Z","end":"2026-04-21T18:15:12.567182Z","steps":["trace[944429944] 'agreement among raft nodes before linearized reading' (duration: 110.124705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:12.567608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.773203ms","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-21T18:15:12.567748Z","caller":"traceutil/trace.go:171","msg":"trace[432430460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1191; }","duration":"226.938085ms","start":"2026-04-21T18:15:12.340771Z","end":"2026-04-21T18:15:12.567709Z","steps":["trace[432430460] 'agreement among raft nodes before linearized reading' (duration: 226.462509ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:22.42903Z","caller":"traceutil/trace.go:171","msg":"trace[1119668861] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"102.850117ms","start":"2026-04-21T18:15:22.32616Z","end":"2026-04-21T18:15:22.42901Z","steps":["trace[1119668861] 'process raft request' (duration: 102.656034ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.056507Z","caller":"traceutil/trace.go:171","msg":"trace[1335512735] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"345.9776ms","start":"2026-04-21T18:15:34.710486Z","end":"2026-04-21T18:15:35.056464Z","steps":["trace[1335512735] 'read index received' (duration: 345.682667ms)","trace[1335512735] 'applied index is now lower than readState.Index' (duration: 293.113µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:35.056718Z","caller":"traceutil/trace.go:171","msg":"trace[143332915] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"374.14685ms","start":"2026-04-21T18:15:34.682554Z","end":"2026-04-21T18:15:35.0567Z","steps":["trace[143332915] 'process raft request' (duration: 373.654595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:35.057026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.373034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-21T18:15:35.057031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:34.682521Z","time spent":"374.315552ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:15:35.057178Z","caller":"traceutil/trace.go:171","msg":"trace[1477003432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1296; }","duration":"346.712058ms","start":"2026-04-21T18:15:34.710444Z","end":"2026-04-21T18:15:35.057156Z","steps":["trace[1477003432] 'agreement among raft nodes before linearized reading' (duration: 346.282623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:35.057569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:34.710419Z","time spent":"346.95504ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T18:15:35.124784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.914496ms","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-21T18:15:35.12491Z","caller":"traceutil/trace.go:171","msg":"trace[1511451885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1297; }","duration":"410.100308ms","start":"2026-04-21T18:15:34.714779Z","end":"2026-04-21T18:15:35.124879Z","steps":["trace[1511451885] 'agreement among raft nodes before linearized reading' (duration: 409.726655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:35.124967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:34.714756Z","time spent":"410.200419ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T18:15:41.323035Z","caller":"traceutil/trace.go:171","msg":"trace[1638815932] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"153.788118ms","start":"2026-04-21T18:15:41.16922Z","end":"2026-04-21T18:15:41.323008Z","steps":["trace[1638815932] 'process raft request' (duration: 115.947947ms)","trace[1638815932] 'compare' (duration: 37.675129ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:43.319085Z","caller":"traceutil/trace.go:171","msg":"trace[1168428405] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"125.436885ms","start":"2026-04-21T18:15:43.193623Z","end":"2026-04-21T18:15:43.319059Z","steps":["trace[1168428405] 'process raft request' (duration: 125.249843ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:44.562307Z","caller":"traceutil/trace.go:171","msg":"trace[4679861] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"214.686571ms","start":"2026-04-21T18:15:44.347604Z","end":"2026-04-21T18:15:44.56229Z","steps":["trace[4679861] 'read index received' (duration: 214.659961ms)","trace[4679861] 'applied index is now lower than readState.Index' (duration: 25.83µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:44.562387Z","caller":"traceutil/trace.go:171","msg":"trace[1341141737] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"226.809382ms","start":"2026-04-21T18:15:44.335569Z","end":"2026-04-21T18:15:44.562378Z","steps":["trace[1341141737] 'process raft request' (duration: 226.60153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:44.562582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.963304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:15:44.562616Z","caller":"traceutil/trace.go:171","msg":"trace[987433815] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1332; }","duration":"215.035015ms","start":"2026-04-21T18:15:44.347572Z","end":"2026-04-21T18:15:44.562607Z","steps":["trace[987433815] 'agreement among raft nodes before linearized reading' (duration: 214.963714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:44.614475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.721501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:15:44.614545Z","caller":"traceutil/trace.go:171","msg":"trace[27069677] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1333; }","duration":"196.855713ms","start":"2026-04-21T18:15:44.417675Z","end":"2026-04-21T18:15:44.614531Z","steps":["trace[27069677] 'agreement among raft nodes before linearized reading' (duration: 196.741962ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:44.614628Z","caller":"traceutil/trace.go:171","msg":"trace[663478508] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"118.369891ms","start":"2026-04-21T18:15:44.496226Z","end":"2026-04-21T18:15:44.614596Z","steps":["trace[663478508] 'process raft request' (duration: 117.971827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:45.144355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.087051ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-21T18:15:45.280067Z","caller":"traceutil/trace.go:171","msg":"trace[1366271841] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1389; }","duration":"164.026631ms","start":"2026-04-21T18:15:45.115974Z","end":"2026-04-21T18:15:45.28Z","steps":["trace[1366271841] 'read index received' (duration: 163.754548ms)","trace[1366271841] 'applied index is now lower than readState.Index' (duration: 271.163µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:45.280159Z","caller":"traceutil/trace.go:171","msg":"trace[1926649715] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"187.0596ms","start":"2026-04-21T18:15:45.093042Z","end":"2026-04-21T18:15:45.280101Z","steps":["trace[1926649715] 'process raft request' (duration: 186.767717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:45.280322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.310674ms","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-21T18:15:45.280416Z","caller":"traceutil/trace.go:171","msg":"trace[1630842470] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1335; }","duration":"164.424235ms","start":"2026-04-21T18:15:45.115968Z","end":"2026-04-21T18:15:45.280392Z","steps":["trace[1630842470] 'agreement among raft nodes before linearized reading' (duration: 164.248103ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:45.65124Z","caller":"traceutil/trace.go:171","msg":"trace[636308385] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"201.700796ms","start":"2026-04-21T18:15:45.44951Z","end":"2026-04-21T18:15:45.651211Z","steps":["trace[636308385] 'process raft request' (duration: 152.348805ms)","trace[636308385] 'compare' (duration: 49.199639ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:45.655701Z","caller":"traceutil/trace.go:171","msg":"trace[1851408933] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"118.981493ms","start":"2026-04-21T18:15:45.536707Z","end":"2026-04-21T18:15:45.655688Z","steps":["trace[1851408933] 'process raft request' (duration: 118.905942ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:46.946035Z","caller":"traceutil/trace.go:171","msg":"trace[1201271075] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"183.496319ms","start":"2026-04-21T18:15:46.762519Z","end":"2026-04-21T18:15:46.946015Z","steps":["trace[1201271075] 'process raft request' (duration: 183.397268ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:02.895223Z","caller":"traceutil/trace.go:171","msg":"trace[1592370158] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"170.948756ms","start":"2026-04-21T18:16:02.724249Z","end":"2026-04-21T18:16:02.895197Z","steps":["trace[1592370158] 'process raft request' (duration: 161.023341ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:17.42447Z","caller":"traceutil/trace.go:171","msg":"trace[489475592] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"294.052977ms","start":"2026-04-21T18:16:17.130392Z","end":"2026-04-21T18:16:17.424445Z","steps":["trace[489475592] 'process raft request' (duration: 293.864885ms)"],"step_count":1}