{"level":"warn","ts":"2026-05-11T15:37:10.67267Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T15:37:10.67283Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.137:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.137:2380","--initial-cluster=instance=https://199.19.213.137:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.137:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.137:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T15:37:10.672954Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T15:37:10.67299Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-05-11T15:37:10.673028Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T15:37:10.673866Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"]} {"level":"info","ts":"2026-05-11T15:37:10.673998Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.137:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T15:37:10.679292Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.959017ms"} {"level":"info","ts":"2026-05-11T15:37:10.685841Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2ba8e3c2b0588654","cluster-id":"97694c3f9947e4ca"} {"level":"info","ts":"2026-05-11T15:37:10.685948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T15:37:10.685985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 0"} {"level":"info","ts":"2026-05-11T15:37:10.686023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2ba8e3c2b0588654 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T15:37:10.686062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 1"} {"level":"info","ts":"2026-05-11T15:37:10.686128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"warn","ts":"2026-05-11T15:37:10.692049Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T15:37:10.693833Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T15:37:10.694967Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T15:37:10.69774Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2ba8e3c2b0588654","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T15:37:10.697964Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2ba8e3c2b0588654","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T15:37:10.698139Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T15:37:10.699302Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T15:37:10.699818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"info","ts":"2026-05-11T15:37:10.700009Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T15:37:10.700336Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","added-peer-id":"2ba8e3c2b0588654","added-peer-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-05-11T15:37:10.704467Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T15:37:10.704568Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-05-11T15:37:10.704674Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-05-11T15:37:10.704948Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2ba8e3c2b0588654","initial-advertise-peer-urls":["https://199.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T15:37:10.705066Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T15:37:10.78707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T15:37:10.787217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T15:37:10.787267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgPreVoteResp from 2ba8e3c2b0588654 at term 1"} {"level":"info","ts":"2026-05-11T15:37:10.787317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became candidate at term 2"} {"level":"info","ts":"2026-05-11T15:37:10.787386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgVoteResp from 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-05-11T15:37:10.787488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became leader at term 2"} {"level":"info","ts":"2026-05-11T15:37:10.787582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2ba8e3c2b0588654 elected leader 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-05-11T15:37:10.788926Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T15:37:10.789767Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T15:37:10.789767Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2ba8e3c2b0588654","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.137:2379]}","request-path":"/0/members/2ba8e3c2b0588654/attributes","cluster-id":"97694c3f9947e4ca","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T15:37:10.790093Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T15:37:10.790404Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T15:37:10.790537Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T15:37:10.790601Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T15:37:10.792981Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.137:2379"} {"level":"info","ts":"2026-05-11T15:37:10.793151Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T15:37:10.79318Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T15:37:10.793999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T15:37:35.492667Z","caller":"traceutil/trace.go:171","msg":"trace[466091922] transaction","detail":"{read_only:false; number_of_response:0; response_revision:516; }","duration":"206.182774ms","start":"2026-05-11T15:37:35.28646Z","end":"2026-05-11T15:37:35.492643Z","steps":["trace[466091922] 'process raft request' (duration: 165.19609ms)","trace[466091922] 'compare' (duration: 40.936393ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:37:35.49342Z","caller":"traceutil/trace.go:171","msg":"trace[1689231168] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"205.354078ms","start":"2026-05-11T15:37:35.288044Z","end":"2026-05-11T15:37:35.493398Z","steps":["trace[1689231168] 'process raft request' (duration: 204.987884ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:37:35.493428Z","caller":"traceutil/trace.go:171","msg":"trace[1971811625] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"204.728181ms","start":"2026-05-11T15:37:35.288672Z","end":"2026-05-11T15:37:35.493401Z","steps":["trace[1971811625] 'process raft request' (duration: 204.62592ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:37:35.493724Z","caller":"traceutil/trace.go:171","msg":"trace[391544533] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"193.944212ms","start":"2026-05-11T15:37:35.299766Z","end":"2026-05-11T15:37:35.49371Z","steps":["trace[391544533] 'process raft request' (duration: 193.588229ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:37:35.493852Z","caller":"traceutil/trace.go:171","msg":"trace[264155133] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"187.967858ms","start":"2026-05-11T15:37:35.305878Z","end":"2026-05-11T15:37:35.493846Z","steps":["trace[264155133] 'process raft request' (duration: 187.773496ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:37:35.493979Z","caller":"traceutil/trace.go:171","msg":"trace[2066755512] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"185.289473ms","start":"2026-05-11T15:37:35.308661Z","end":"2026-05-11T15:37:35.49395Z","steps":["trace[2066755512] 'process raft request' (duration: 185.091622ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:37:35.494127Z","caller":"traceutil/trace.go:171","msg":"trace[161725413] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"178.620573ms","start":"2026-05-11T15:37:35.315488Z","end":"2026-05-11T15:37:35.494109Z","steps":["trace[161725413] 'process raft request' (duration: 178.34659ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:37:35.494214Z","caller":"traceutil/trace.go:171","msg":"trace[537041045] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:538; }","duration":"199.630424ms","start":"2026-05-11T15:37:35.294525Z","end":"2026-05-11T15:37:35.494156Z","steps":["trace[537041045] 'read index received' (duration: 157.226167ms)","trace[537041045] 'applied index is now lower than readState.Index' (duration: 42.403457ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:37:35.494239Z","caller":"traceutil/trace.go:171","msg":"trace[1261467529] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"175.313903ms","start":"2026-05-11T15:37:35.318908Z","end":"2026-05-11T15:37:35.494222Z","steps":["trace[1261467529] 'process raft request' (duration: 175.02646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:37:35.49435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.818206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-11T15:37:35.494407Z","caller":"traceutil/trace.go:171","msg":"trace[932948495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"199.916656ms","start":"2026-05-11T15:37:35.294484Z","end":"2026-05-11T15:37:35.4944Z","steps":["trace[932948495] 'agreement among raft nodes before linearized reading' (duration: 199.813756ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:37:42.03235Z","caller":"traceutil/trace.go:171","msg":"trace[1827094644] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"107.652056ms","start":"2026-05-11T15:37:41.924673Z","end":"2026-05-11T15:37:42.032325Z","steps":["trace[1827094644] 'process raft request' (duration: 107.159302ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:37:42.532703Z","caller":"traceutil/trace.go:171","msg":"trace[2119032166] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"335.33987ms","start":"2026-05-11T15:37:42.197324Z","end":"2026-05-11T15:37:42.532664Z","steps":["trace[2119032166] 'read index received' (duration: 250.885742ms)","trace[2119032166] 'applied index is now lower than readState.Index' (duration: 84.451958ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:37:42.53303Z","caller":"traceutil/trace.go:171","msg":"trace[2044927417] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"435.014073ms","start":"2026-05-11T15:37:42.097993Z","end":"2026-05-11T15:37:42.533007Z","steps":["trace[2044927417] 'process raft request' (duration: 350.290312ms)","trace[2044927417] 'compare' (duration: 84.122945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T15:37:42.533071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.701634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-58whk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-11T15:37:42.533129Z","caller":"traceutil/trace.go:171","msg":"trace[1119448775] range","detail":"{range_begin:/registry/pods/kube-system/cilium-58whk; range_end:; response_count:1; response_revision:605; }","duration":"335.844436ms","start":"2026-05-11T15:37:42.197271Z","end":"2026-05-11T15:37:42.533115Z","steps":["trace[1119448775] 'agreement among raft nodes before linearized reading' (duration: 335.527103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:37:42.533172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:37:42.197249Z","time spent":"335.914186ms","remote":"127.0.0.1:36374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-58whk\" "} {"level":"warn","ts":"2026-05-11T15:37:42.533484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:37:42.097964Z","time spent":"435.103654ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T15:39:09.238095Z","caller":"traceutil/trace.go:171","msg":"trace[1014234769] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1174; }","duration":"151.93918ms","start":"2026-05-11T15:39:09.086134Z","end":"2026-05-11T15:39:09.238074Z","steps":["trace[1014234769] 'read index received' (duration: 151.709948ms)","trace[1014234769] 'applied index is now lower than readState.Index' (duration: 228.142µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:39:09.23824Z","caller":"traceutil/trace.go:171","msg":"trace[967188193] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"206.449335ms","start":"2026-05-11T15:39:09.03178Z","end":"2026-05-11T15:39:09.238229Z","steps":["trace[967188193] 'process raft request' (duration: 206.146323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:39:09.238429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.265872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T15:39:09.238508Z","caller":"traceutil/trace.go:171","msg":"trace[1186722255] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1130; }","duration":"152.386453ms","start":"2026-05-11T15:39:09.086102Z","end":"2026-05-11T15:39:09.238489Z","steps":["trace[1186722255] 'agreement among raft nodes before linearized reading' (duration: 152.211552ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:39:09.609067Z","caller":"traceutil/trace.go:171","msg":"trace[2026468276] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"367.070798ms","start":"2026-05-11T15:39:09.241966Z","end":"2026-05-11T15:39:09.609036Z","steps":["trace[2026468276] 'process raft request' (duration: 366.898967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:39:09.6095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:39:09.241949Z","time spent":"367.262739ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T15:39:09.868833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.77617ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T15:39:09.868968Z","caller":"traceutil/trace.go:171","msg":"trace[1362939486] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1175; }","duration":"431.625246ms","start":"2026-05-11T15:39:09.437325Z","end":"2026-05-11T15:39:09.86895Z","steps":["trace[1362939486] 'read index received' (duration: 171.460131ms)","trace[1362939486] 'applied index is now lower than readState.Index' (duration: 260.163505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T15:39:09.869291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.941118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T15:39:09.869355Z","caller":"traceutil/trace.go:171","msg":"trace[842243700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"432.064678ms","start":"2026-05-11T15:39:09.437276Z","end":"2026-05-11T15:39:09.86934Z","steps":["trace[842243700] 'agreement among raft nodes before linearized reading' (duration: 431.718906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:39:09.869401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:39:09.437256Z","time spent":"432.13248ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T15:39:53.13569Z","caller":"traceutil/trace.go:171","msg":"trace[1330444199] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"126.309516ms","start":"2026-05-11T15:39:53.009359Z","end":"2026-05-11T15:39:53.135669Z","steps":["trace[1330444199] 'read index received' (duration: 39.546364ms)","trace[1330444199] 'applied index is now lower than readState.Index' (duration: 86.761952ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:39:53.135753Z","caller":"traceutil/trace.go:171","msg":"trace[1266784078] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"128.093667ms","start":"2026-05-11T15:39:53.007644Z","end":"2026-05-11T15:39:53.135738Z","steps":["trace[1266784078] 'process raft request' (duration: 125.971063ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:39:53.135787Z","caller":"traceutil/trace.go:171","msg":"trace[9736516] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"105.500075ms","start":"2026-05-11T15:39:53.030257Z","end":"2026-05-11T15:39:53.135757Z","steps":["trace[9736516] 'process raft request' (duration: 105.306034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:39:53.135878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.503757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cert-manager-edit\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T15:39:53.136043Z","caller":"traceutil/trace.go:171","msg":"trace[4914906] range","detail":"{range_begin:/registry/clusterroles/cert-manager-edit; range_end:; response_count:0; response_revision:1301; }","duration":"126.698368ms","start":"2026-05-11T15:39:53.009319Z","end":"2026-05-11T15:39:53.136017Z","steps":["trace[4914906] 'agreement among raft nodes before linearized reading' (duration: 126.446806ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:40:12.099498Z","caller":"traceutil/trace.go:171","msg":"trace[1962201148] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1693; }","duration":"176.998746ms","start":"2026-05-11T15:40:11.922468Z","end":"2026-05-11T15:40:12.099467Z","steps":["trace[1962201148] 'process raft request' (duration: 92.805294ms)","trace[1962201148] 'compare' (duration: 84.033881ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:40:12.099498Z","caller":"traceutil/trace.go:171","msg":"trace[301616964] linearizableReadLoop","detail":"{readStateIndex:1752; appliedIndex:1751; }","duration":"111.172065ms","start":"2026-05-11T15:40:11.988258Z","end":"2026-05-11T15:40:12.09943Z","steps":["trace[301616964] 'read index received' (duration: 26.970103ms)","trace[301616964] 'applied index is now lower than readState.Index' (duration: 84.200622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T15:40:12.099822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.496919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-11T15:40:12.099868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.474303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T15:40:12.099941Z","caller":"traceutil/trace.go:171","msg":"trace[273938659] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1693; }","duration":"109.580074ms","start":"2026-05-11T15:40:11.990343Z","end":"2026-05-11T15:40:12.099923Z","steps":["trace[273938659] 'agreement among raft nodes before linearized reading' (duration: 109.451353ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:40:12.099961Z","caller":"traceutil/trace.go:171","msg":"trace[2009728427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1693; }","duration":"111.664949ms","start":"2026-05-11T15:40:11.98821Z","end":"2026-05-11T15:40:12.099875Z","steps":["trace[2009728427] 'agreement among raft nodes before linearized reading' (duration: 111.315987ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:40:34.944475Z","caller":"traceutil/trace.go:171","msg":"trace[2002065964] linearizableReadLoop","detail":"{readStateIndex:2347; appliedIndex:2346; }","duration":"141.115349ms","start":"2026-05-11T15:40:34.803338Z","end":"2026-05-11T15:40:34.944453Z","steps":["trace[2002065964] 'read index received' (duration: 140.898477ms)","trace[2002065964] 'applied index is now lower than readState.Index' (duration: 215.952µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:40:34.944594Z","caller":"traceutil/trace.go:171","msg":"trace[2122516105] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"148.810914ms","start":"2026-05-11T15:40:34.795764Z","end":"2026-05-11T15:40:34.944575Z","steps":["trace[2122516105] 'process raft request' (duration: 148.543612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:40:34.945109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.736393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc442afb-d9ac-44eb-aaab-166fa6f01b99\" ","response":"range_response_count:1 size:3617"} {"level":"info","ts":"2026-05-11T15:40:34.945175Z","caller":"traceutil/trace.go:171","msg":"trace[817127078] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cc442afb-d9ac-44eb-aaab-166fa6f01b99; range_end:; response_count:1; response_revision:2249; }","duration":"141.841033ms","start":"2026-05-11T15:40:34.803316Z","end":"2026-05-11T15:40:34.945157Z","steps":["trace[817127078] 'agreement among raft nodes before linearized reading' (duration: 141.28012ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:41:06.688826Z","caller":"traceutil/trace.go:171","msg":"trace[1270305693] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"155.716083ms","start":"2026-05-11T15:41:06.533084Z","end":"2026-05-11T15:41:06.6888Z","steps":["trace[1270305693] 'process raft request' (duration: 147.967617ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:41:21.363113Z","caller":"traceutil/trace.go:171","msg":"trace[1434193281] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"124.322108ms","start":"2026-05-11T15:41:21.238764Z","end":"2026-05-11T15:41:21.363086Z","steps":["trace[1434193281] 'process raft request' (duration: 76.406896ms)","trace[1434193281] 'compare' (duration: 47.796771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T15:41:22.49206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.139349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T15:41:22.492531Z","caller":"traceutil/trace.go:171","msg":"trace[510690690] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2683; }","duration":"142.484798ms","start":"2026-05-11T15:41:22.350027Z","end":"2026-05-11T15:41:22.492512Z","steps":["trace[510690690] 'read index received' (duration: 25.688344ms)","trace[510690690] 'applied index is now lower than readState.Index' (duration: 116.795394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T15:41:22.49293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.86035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T15:41:22.493043Z","caller":"traceutil/trace.go:171","msg":"trace[817693504] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2577; }","duration":"143.053482ms","start":"2026-05-11T15:41:22.349964Z","end":"2026-05-11T15:41:22.493017Z","steps":["trace[817693504] 'agreement among raft nodes before linearized reading' (duration: 142.82574ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:41:22.493291Z","caller":"traceutil/trace.go:171","msg":"trace[1878322384] transaction","detail":"{read_only:false; response_revision:2577; number_of_response:1; }","duration":"169.439651ms","start":"2026-05-11T15:41:22.323835Z","end":"2026-05-11T15:41:22.493275Z","steps":["trace[1878322384] 'process raft request' (duration: 168.517904ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:41:22.492817Z","caller":"traceutil/trace.go:171","msg":"trace[175646015] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"203.285581ms","start":"2026-05-11T15:41:22.289367Z","end":"2026-05-11T15:41:22.492653Z","steps":["trace[175646015] 'process raft request' (duration: 86.456807ms)","trace[175646015] 'compare' (duration: 116.010618ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:41:22.874248Z","caller":"traceutil/trace.go:171","msg":"trace[2005906422] transaction","detail":"{read_only:false; response_revision:2579; number_of_response:1; }","duration":"115.436155ms","start":"2026-05-11T15:41:22.758727Z","end":"2026-05-11T15:41:22.874163Z","steps":["trace[2005906422] 'process raft request' (duration: 115.286704ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:41:23.187524Z","caller":"traceutil/trace.go:171","msg":"trace[1381192054] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"428.180747ms","start":"2026-05-11T15:41:22.759313Z","end":"2026-05-11T15:41:23.187493Z","steps":["trace[1381192054] 'process raft request' (duration: 428.000046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:41:23.187671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:41:22.759304Z","time spent":"428.293849ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:41:23.372063Z","caller":"traceutil/trace.go:171","msg":"trace[1984761529] linearizableReadLoop","detail":"{readStateIndex:2689; appliedIndex:2688; }","duration":"177.164786ms","start":"2026-05-11T15:41:23.19487Z","end":"2026-05-11T15:41:23.372035Z","steps":["trace[1984761529] 'read index received' (duration: 174.98314ms)","trace[1984761529] 'applied index is now lower than readState.Index' (duration: 2.180766ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:41:23.372128Z","caller":"traceutil/trace.go:171","msg":"trace[23811957] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"178.203672ms","start":"2026-05-11T15:41:23.193885Z","end":"2026-05-11T15:41:23.372089Z","steps":["trace[23811957] 'process raft request' (duration: 175.893815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:41:23.372236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.344887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-11T15:41:23.372277Z","caller":"traceutil/trace.go:171","msg":"trace[249105309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2581; }","duration":"177.398907ms","start":"2026-05-11T15:41:23.194868Z","end":"2026-05-11T15:41:23.372266Z","steps":["trace[249105309] 'agreement among raft nodes before linearized reading' (duration: 177.266636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:42:36.791543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.091526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T15:42:36.791804Z","caller":"traceutil/trace.go:171","msg":"trace[241452147] transaction","detail":"{read_only:false; response_revision:3113; number_of_response:1; }","duration":"197.010371ms","start":"2026-05-11T15:42:36.594764Z","end":"2026-05-11T15:42:36.791774Z","steps":["trace[241452147] 'process raft request' (duration: 22.584837ms)","trace[241452147] 'compare' (duration: 173.960369ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:46:26.627807Z","caller":"traceutil/trace.go:171","msg":"trace[1093580318] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"141.109ms","start":"2026-05-11T15:46:26.486643Z","end":"2026-05-11T15:46:26.627752Z","steps":["trace[1093580318] 'process raft request' (duration: 140.08512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:27.914397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:28.414797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:28.915423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:29.415979Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:29.916257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:30.41714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:30.883226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000255322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-11T15:46:30.883301Z","caller":"traceutil/trace.go:171","msg":"trace[761068287] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000380765s","start":"2026-05-11T15:46:28.882907Z","end":"2026-05-11T15:46:30.883287Z","steps":["trace[761068287] 'agreement among raft nodes before linearized reading' (duration: 2.000288203s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:30.88334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:28.882889Z","time spent":"2.000444056s","remote":"127.0.0.1:36172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/11 15:46:30 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-11T15:46:30.917538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:31.025319Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.619073758s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-11T15:46:31.05795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.189962802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T15:46:31.058025Z","caller":"traceutil/trace.go:171","msg":"trace[984687812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4665; }","duration":"4.190084344s","start":"2026-05-11T15:46:26.867922Z","end":"2026-05-11T15:46:31.058006Z","steps":["trace[984687812] 'range keys from in-memory index tree' (duration: 4.189763428s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.058063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:26.867895Z","time spent":"4.190158765s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T15:46:31.058269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.175827278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T15:46:31.058299Z","caller":"traceutil/trace.go:171","msg":"trace[471586170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4665; }","duration":"4.175890739s","start":"2026-05-11T15:46:26.882401Z","end":"2026-05-11T15:46:31.058292Z","steps":["trace[471586170] 'range keys from in-memory index tree' (duration: 4.175694046s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.058319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:26.882349Z","time spent":"4.175964931s","remote":"127.0.0.1:36178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T15:46:31.058964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.539715ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T15:46:31.059063Z","caller":"traceutil/trace.go:171","msg":"trace[388609123] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4665; }","duration":"669.648287ms","start":"2026-05-11T15:46:30.389399Z","end":"2026-05-11T15:46:31.059048Z","steps":["trace[388609123] 'range keys from in-memory index tree' (duration: 669.526484ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:31.061823Z","caller":"traceutil/trace.go:171","msg":"trace[465722818] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"3.655702696s","start":"2026-05-11T15:46:27.406103Z","end":"2026-05-11T15:46:31.061806Z","steps":["trace[465722818] 'process raft request' (duration: 3.619421504s)","trace[465722818] 'compare' (duration: 31.9862ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T15:46:31.061923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:27.406083Z","time spent":"3.655783068s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:31.073401Z","caller":"traceutil/trace.go:171","msg":"trace[1658916760] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"3.489770123s","start":"2026-05-11T15:46:27.583604Z","end":"2026-05-11T15:46:31.073375Z","steps":["trace[1658916760] 'process raft request' (duration: 3.489400786s)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:31.073422Z","caller":"traceutil/trace.go:171","msg":"trace[1677769055] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"2.440195746s","start":"2026-05-11T15:46:28.633206Z","end":"2026-05-11T15:46:31.073402Z","steps":["trace[1677769055] 'process raft request' (duration: 2.439923771s)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:31.073401Z","caller":"traceutil/trace.go:171","msg":"trace[2028098432] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"2.530723517s","start":"2026-05-11T15:46:28.542651Z","end":"2026-05-11T15:46:31.073375Z","steps":["trace[2028098432] 'process raft request' (duration: 2.530462172s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.073536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:28.633183Z","time spent":"2.440295618s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T15:46:31.073541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:27.583579Z","time spent":"3.489885796s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T15:46:31.073569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:28.542625Z","time spent":"2.530914921s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:31.073682Z","caller":"traceutil/trace.go:171","msg":"trace[1907435760] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"3.45435932s","start":"2026-05-11T15:46:27.619294Z","end":"2026-05-11T15:46:31.073653Z","steps":["trace[1907435760] 'process raft request' (duration: 3.45380141s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.073734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:27.619271Z","time spent":"3.454435891s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:31.111008Z","caller":"traceutil/trace.go:171","msg":"trace[1244264098] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"1.619392512s","start":"2026-05-11T15:46:29.491585Z","end":"2026-05-11T15:46:31.110978Z","steps":["trace[1244264098] 'process raft request' (duration: 1.619080145s)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:31.111075Z","caller":"traceutil/trace.go:171","msg":"trace[10106492] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"224.139306ms","start":"2026-05-11T15:46:30.886902Z","end":"2026-05-11T15:46:31.111041Z","steps":["trace[10106492] 'process raft request' (duration: 223.89201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.111143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:29.491547Z","time spent":"1.619527684s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:31.111232Z","caller":"traceutil/trace.go:171","msg":"trace[1941644668] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4842; }","duration":"3.697798209s","start":"2026-05-11T15:46:27.413415Z","end":"2026-05-11T15:46:31.111213Z","steps":["trace[1941644668] 'read index received' (duration: 3.612230355s)","trace[1941644668] 'applied index is now lower than readState.Index' (duration: 85.567024ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T15:46:31.111432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.698009933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T15:46:31.111463Z","caller":"traceutil/trace.go:171","msg":"trace[2136395313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4673; }","duration":"3.698082445s","start":"2026-05-11T15:46:27.413372Z","end":"2026-05-11T15:46:31.111454Z","steps":["trace[2136395313] 'agreement among raft nodes before linearized reading' (duration: 3.697965803s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.111487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:27.413355Z","time spent":"3.698125115s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T15:46:31.111587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.428179018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T15:46:31.111635Z","caller":"traceutil/trace.go:171","msg":"trace[815330149] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:4673; }","duration":"3.428255859s","start":"2026-05-11T15:46:27.683368Z","end":"2026-05-11T15:46:31.111624Z","steps":["trace[815330149] 'agreement among raft nodes before linearized reading' (duration: 3.428162947s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.111671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:27.683351Z","time spent":"3.428312401s","remote":"127.0.0.1:36524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":36,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-11T15:46:31.111834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.456140568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T15:46:31.111863Z","caller":"traceutil/trace.go:171","msg":"trace[307890430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4673; }","duration":"2.456219448s","start":"2026-05-11T15:46:28.655637Z","end":"2026-05-11T15:46:31.111856Z","steps":["trace[307890430] 'agreement among raft nodes before linearized reading' (duration: 2.456143257s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.111884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:28.655609Z","time spent":"2.45627034s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T15:46:31.111881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.849589218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T15:46:31.111921Z","caller":"traceutil/trace.go:171","msg":"trace[1042702930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4673; }","duration":"2.849656139s","start":"2026-05-11T15:46:28.262255Z","end":"2026-05-11T15:46:31.111911Z","steps":["trace[1042702930] 'agreement among raft nodes before linearized reading' (duration: 2.849541597s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.111952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:28.262239Z","time spent":"2.84970735s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T15:46:31.111985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.327632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T15:46:31.112009Z","caller":"traceutil/trace.go:171","msg":"trace[867506838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4673; }","duration":"221.368093ms","start":"2026-05-11T15:46:30.890635Z","end":"2026-05-11T15:46:31.112003Z","steps":["trace[867506838] 'agreement among raft nodes before linearized reading' (duration: 221.325372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.112096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.867000714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T15:46:31.112128Z","caller":"traceutil/trace.go:171","msg":"trace[488577782] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4673; }","duration":"2.867066515s","start":"2026-05-11T15:46:28.245054Z","end":"2026-05-11T15:46:31.11212Z","steps":["trace[488577782] 'agreement among raft nodes before linearized reading' (duration: 2.867004024s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.11215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.209902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-api-7dfbdfbf9-x8hvb\" ","response":"range_response_count:1 size:12206"} {"level":"warn","ts":"2026-05-11T15:46:31.11216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:28.245035Z","time spent":"2.867117546s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":27,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-11T15:46:31.112177Z","caller":"traceutil/trace.go:171","msg":"trace[1148263232] range","detail":"{range_begin:/registry/pods/openstack/keystone-api-7dfbdfbf9-x8hvb; range_end:; response_count:1; response_revision:4673; }","duration":"592.271163ms","start":"2026-05-11T15:46:30.519897Z","end":"2026-05-11T15:46:31.112168Z","steps":["trace[1148263232] 'agreement among raft nodes before linearized reading' (duration: 592.196222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.112199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:30.519874Z","time spent":"592.320005ms","remote":"127.0.0.1:36374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":12229,"request content":"key:\"/registry/pods/openstack/keystone-api-7dfbdfbf9-x8hvb\" "} {"level":"warn","ts":"2026-05-11T15:46:31.112338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.584027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-11T15:46:31.112363Z","caller":"traceutil/trace.go:171","msg":"trace[341306596] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4673; }","duration":"816.653229ms","start":"2026-05-11T15:46:30.295704Z","end":"2026-05-11T15:46:31.112357Z","steps":["trace[341306596] 'agreement among raft nodes before linearized reading' (duration: 816.589008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.112383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:30.295681Z","time spent":"816.69701ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-11T15:46:31.112404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.980709222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T15:46:31.112441Z","caller":"traceutil/trace.go:171","msg":"trace[726301216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4673; }","duration":"2.980783332s","start":"2026-05-11T15:46:28.131649Z","end":"2026-05-11T15:46:31.112432Z","steps":["trace[726301216] 'agreement among raft nodes before linearized reading' (duration: 2.980706411s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.112474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:28.131612Z","time spent":"2.980854204s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T15:46:31.112508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.170906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-11T15:46:31.112534Z","caller":"traceutil/trace.go:171","msg":"trace[266829250] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4673; }","duration":"947.249808ms","start":"2026-05-11T15:46:30.165278Z","end":"2026-05-11T15:46:31.112528Z","steps":["trace[266829250] 'agreement among raft nodes before linearized reading' (duration: 947.184737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.112555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:30.165247Z","time spent":"947.303269ms","remote":"127.0.0.1:36374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-11T15:46:31.112714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621123504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T15:46:31.112741Z","caller":"traceutil/trace.go:171","msg":"trace[1805071765] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4673; }","duration":"1.621186525s","start":"2026-05-11T15:46:29.491548Z","end":"2026-05-11T15:46:31.112735Z","steps":["trace[1805071765] 'agreement among raft nodes before linearized reading' (duration: 1.621092933s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.112762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:29.491483Z","time spent":"1.621273187s","remote":"127.0.0.1:36402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":72,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-11T15:46:31.112337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.931897372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-05-11T15:46:31.113035Z","caller":"traceutil/trace.go:171","msg":"trace[522586475] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:4673; }","duration":"2.932630556s","start":"2026-05-11T15:46:28.180393Z","end":"2026-05-11T15:46:31.113024Z","steps":["trace[522586475] 'agreement among raft nodes before linearized reading' (duration: 2.931875812s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.11308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:28.180346Z","time spent":"2.932721148s","remote":"127.0.0.1:36342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"info","ts":"2026-05-11T15:46:31.503839Z","caller":"traceutil/trace.go:171","msg":"trace[649333043] linearizableReadLoop","detail":"{readStateIndex:4862; appliedIndex:4861; }","duration":"212.415157ms","start":"2026-05-11T15:46:31.291402Z","end":"2026-05-11T15:46:31.503817Z","steps":["trace[649333043] 'read index received' (duration: 212.194943ms)","trace[649333043] 'applied index is now lower than readState.Index' (duration: 219.424µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:46:31.503896Z","caller":"traceutil/trace.go:171","msg":"trace[1915372077] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"219.779147ms","start":"2026-05-11T15:46:31.284087Z","end":"2026-05-11T15:46:31.503866Z","steps":["trace[1915372077] 'process raft request' (duration: 219.578703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:31.504071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.655351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-11T15:46:31.504142Z","caller":"traceutil/trace.go:171","msg":"trace[140611253] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4684; }","duration":"212.763652ms","start":"2026-05-11T15:46:31.291358Z","end":"2026-05-11T15:46:31.504122Z","steps":["trace[140611253] 'agreement among raft nodes before linearized reading' (duration: 212.553108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:37.384208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994198,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T15:46:37.58198Z","caller":"traceutil/trace.go:171","msg":"trace[1882453367] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"754.633781ms","start":"2026-05-11T15:46:36.827321Z","end":"2026-05-11T15:46:37.581954Z","steps":["trace[1882453367] 'process raft request' (duration: 754.0317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:37.582228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:36.827309Z","time spent":"754.777384ms","remote":"127.0.0.1:36374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7995,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:37.659116Z","caller":"traceutil/trace.go:171","msg":"trace[411530862] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"568.665786ms","start":"2026-05-11T15:46:37.090431Z","end":"2026-05-11T15:46:37.659097Z","steps":["trace[411530862] 'process raft request' (duration: 568.587975ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:37.659178Z","caller":"traceutil/trace.go:171","msg":"trace[1136843287] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"577.285738ms","start":"2026-05-11T15:46:37.081852Z","end":"2026-05-11T15:46:37.659138Z","steps":["trace[1136843287] 'process raft request' (duration: 574.848682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:37.659235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:37.090405Z","time spent":"568.767378ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:37.659232Z","caller":"traceutil/trace.go:171","msg":"trace[603595691] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"570.053552ms","start":"2026-05-11T15:46:37.089153Z","end":"2026-05-11T15:46:37.659207Z","steps":["trace[603595691] 'process raft request' (duration: 569.838798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:37.65935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:37.089141Z","time spent":"570.134903ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T15:46:37.65935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:37.081829Z","time spent":"577.427811ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:37.659625Z","caller":"traceutil/trace.go:171","msg":"trace[1259793178] linearizableReadLoop","detail":"{readStateIndex:4894; appliedIndex:4889; }","duration":"775.555172ms","start":"2026-05-11T15:46:36.884051Z","end":"2026-05-11T15:46:37.659606Z","steps":["trace[1259793178] 'read index received' (duration: 697.534985ms)","trace[1259793178] 'applied index is now lower than readState.Index' (duration: 78.018347ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:46:37.659712Z","caller":"traceutil/trace.go:171","msg":"trace[165681956] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"568.560044ms","start":"2026-05-11T15:46:37.091123Z","end":"2026-05-11T15:46:37.659683Z","steps":["trace[165681956] 'process raft request' (duration: 567.936962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:37.659811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.748246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T15:46:37.659848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.132047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T15:46:37.659869Z","caller":"traceutil/trace.go:171","msg":"trace[103401956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4715; }","duration":"775.837338ms","start":"2026-05-11T15:46:36.884017Z","end":"2026-05-11T15:46:37.659854Z","steps":["trace[103401956] 'agreement among raft nodes before linearized reading' (duration: 775.690755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:37.659858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:37.09111Z","time spent":"568.667627ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T15:46:37.659913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:36.884Z","time spent":"775.902909ms","remote":"127.0.0.1:36178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T15:46:37.659915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.936658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-11T15:46:37.659949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.930133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-11T15:46:37.659969Z","caller":"traceutil/trace.go:171","msg":"trace[540391589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4715; }","duration":"440.969094ms","start":"2026-05-11T15:46:37.218994Z","end":"2026-05-11T15:46:37.659963Z","steps":["trace[540391589] 'agreement among raft nodes before linearized reading' (duration: 440.910113ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:37.659963Z","caller":"traceutil/trace.go:171","msg":"trace[116088594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4715; }","duration":"520.035711ms","start":"2026-05-11T15:46:37.139913Z","end":"2026-05-11T15:46:37.659948Z","steps":["trace[116088594] 'agreement among raft nodes before linearized reading' (duration: 519.800766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:37.659987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:37.218985Z","time spent":"440.998624ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T15:46:37.660009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:37.139898Z","time spent":"520.097932ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T15:46:37.660111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.972681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T15:46:37.660133Z","caller":"traceutil/trace.go:171","msg":"trace[404292092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4715; }","duration":"443.038143ms","start":"2026-05-11T15:46:37.217086Z","end":"2026-05-11T15:46:37.660124Z","steps":["trace[404292092] 'agreement among raft nodes before linearized reading' (duration: 442.954251ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:37.659878Z","caller":"traceutil/trace.go:171","msg":"trace[279174311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4715; }","duration":"441.162137ms","start":"2026-05-11T15:46:37.218707Z","end":"2026-05-11T15:46:37.659869Z","steps":["trace[279174311] 'agreement among raft nodes before linearized reading' (duration: 441.028915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:37.660152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:37.217067Z","time spent":"443.079773ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T15:46:37.660186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:37.218673Z","time spent":"441.497453ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T15:46:37.660201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.763811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T15:46:37.660249Z","caller":"traceutil/trace.go:171","msg":"trace[1996116626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4715; }","duration":"514.825793ms","start":"2026-05-11T15:46:37.145406Z","end":"2026-05-11T15:46:37.660232Z","steps":["trace[1996116626] 'agreement among raft nodes before linearized reading' (duration: 514.697471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:37.66029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:37.145394Z","time spent":"514.885274ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T15:46:37.914381Z","caller":"traceutil/trace.go:171","msg":"trace[1336528593] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"241.95495ms","start":"2026-05-11T15:46:37.672408Z","end":"2026-05-11T15:46:37.914363Z","steps":["trace[1336528593] 'process raft request' (duration: 241.109835ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:37.914511Z","caller":"traceutil/trace.go:171","msg":"trace[267841142] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"241.93024ms","start":"2026-05-11T15:46:37.672575Z","end":"2026-05-11T15:46:37.914505Z","steps":["trace[267841142] 'process raft request' (duration: 241.740706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:39.381801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994212,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:39.882563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994212,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:40.153673Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.553965606s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T15:46:40.154237Z","caller":"traceutil/trace.go:171","msg":"trace[1185491159] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"1.554642009s","start":"2026-05-11T15:46:38.599566Z","end":"2026-05-11T15:46:40.154208Z","steps":["trace[1185491159] 'process raft request' (duration: 1.554327653s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.154441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:38.599543Z","time spent":"1.554748441s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:40.213296Z","caller":"traceutil/trace.go:171","msg":"trace[1350333550] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"1.593167356s","start":"2026-05-11T15:46:38.620103Z","end":"2026-05-11T15:46:40.213271Z","steps":["trace[1350333550] 'process raft request' (duration: 1.589965277s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.21344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:38.620086Z","time spent":"1.593284028s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:40.254573Z","caller":"traceutil/trace.go:171","msg":"trace[499619714] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"588.481962ms","start":"2026-05-11T15:46:39.66607Z","end":"2026-05-11T15:46:40.254552Z","steps":["trace[499619714] 'process raft request' (duration: 588.063725ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:40.254647Z","caller":"traceutil/trace.go:171","msg":"trace[1313015928] linearizableReadLoop","detail":"{readStateIndex:4906; appliedIndex:4899; }","duration":"1.372888181s","start":"2026-05-11T15:46:38.881738Z","end":"2026-05-11T15:46:40.254626Z","steps":["trace[1313015928] 'read index received' (duration: 1.27216129s)","trace[1313015928] 'applied index is now lower than readState.Index' (duration: 100.725941ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T15:46:40.254869Z","caller":"traceutil/trace.go:171","msg":"trace[1527629271] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"587.116147ms","start":"2026-05-11T15:46:39.667698Z","end":"2026-05-11T15:46:40.254814Z","steps":["trace[1527629271] 'process raft request' (duration: 586.892652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.254958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.015097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T15:46:40.254993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.667692Z","time spent":"587.224329ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:40.255008Z","caller":"traceutil/trace.go:171","msg":"trace[163200426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4726; }","duration":"585.096ms","start":"2026-05-11T15:46:39.6699Z","end":"2026-05-11T15:46:40.254996Z","steps":["trace[163200426] 'agreement among raft nodes before linearized reading' (duration: 584.940177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.255044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.669892Z","time spent":"585.14355ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T15:46:40.255079Z","caller":"traceutil/trace.go:171","msg":"trace[1776592419] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"588.528003ms","start":"2026-05-11T15:46:39.666532Z","end":"2026-05-11T15:46:40.25506Z","steps":["trace[1776592419] 'process raft request' (duration: 587.90201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.254707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.666044Z","time spent":"588.596595ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T15:46:40.255186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.666524Z","time spent":"588.600365ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T15:46:40.255253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.086448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T15:46:40.25527Z","caller":"traceutil/trace.go:171","msg":"trace[456453400] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"587.82881ms","start":"2026-05-11T15:46:39.667429Z","end":"2026-05-11T15:46:40.255258Z","steps":["trace[456453400] 'process raft request' (duration: 587.125746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.255324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.074871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T15:46:40.255361Z","caller":"traceutil/trace.go:171","msg":"trace[834299780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4726; }","duration":"583.126242ms","start":"2026-05-11T15:46:39.672226Z","end":"2026-05-11T15:46:40.255352Z","steps":["trace[834299780] 'agreement among raft nodes before linearized reading' (duration: 583.03087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.255357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.83907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T15:46:40.25539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.672222Z","time spent":"583.160803ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T15:46:40.255394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.257135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T15:46:40.255417Z","caller":"traceutil/trace.go:171","msg":"trace[1980507857] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:4726; }","duration":"549.929861ms","start":"2026-05-11T15:46:39.705472Z","end":"2026-05-11T15:46:40.255402Z","steps":["trace[1980507857] 'agreement among raft nodes before linearized reading' (duration: 549.782448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.255385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.667421Z","time spent":"587.877151ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:40.255464Z","caller":"traceutil/trace.go:171","msg":"trace[1642504811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4726; }","duration":"583.336586ms","start":"2026-05-11T15:46:39.672097Z","end":"2026-05-11T15:46:40.255433Z","steps":["trace[1642504811] 'agreement among raft nodes before linearized reading' (duration: 583.208653ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:40.255476Z","caller":"traceutil/trace.go:171","msg":"trace[569532617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4726; }","duration":"336.16628ms","start":"2026-05-11T15:46:39.919125Z","end":"2026-05-11T15:46:40.255292Z","steps":["trace[569532617] 'agreement among raft nodes before linearized reading' (duration: 335.923315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.255511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.672081Z","time spent":"583.416837ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T15:46:40.255549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.503324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T15:46:40.255563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.352534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T15:46:40.255467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.705459Z","time spent":"549.993863ms","remote":"127.0.0.1:36544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-05-11T15:46:40.255594Z","caller":"traceutil/trace.go:171","msg":"trace[947919136] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4726; }","duration":"336.391284ms","start":"2026-05-11T15:46:39.919193Z","end":"2026-05-11T15:46:40.255584Z","steps":["trace[947919136] 'agreement among raft nodes before linearized reading' (duration: 336.345053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.255583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.474926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-11T15:46:40.255523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.919108Z","time spent":"336.403215ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T15:46:40.255621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.919189Z","time spent":"336.423805ms","remote":"127.0.0.1:55564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-11T15:46:40.255627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373886499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T15:46:40.255633Z","caller":"traceutil/trace.go:171","msg":"trace[2108494939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4726; }","duration":"336.550677ms","start":"2026-05-11T15:46:39.91907Z","end":"2026-05-11T15:46:40.255621Z","steps":["trace[2108494939] 'agreement among raft nodes before linearized reading' (duration: 336.424015ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:46:40.25565Z","caller":"traceutil/trace.go:171","msg":"trace[733718757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4726; }","duration":"1.373936949s","start":"2026-05-11T15:46:38.881705Z","end":"2026-05-11T15:46:40.255642Z","steps":["trace[733718757] 'agreement among raft nodes before linearized reading' (duration: 1.373897279s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:40.255676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:38.88169Z","time spent":"1.373980171s","remote":"127.0.0.1:36178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T15:46:40.255678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:39.919056Z","time spent":"336.611739ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T15:46:40.255586Z","caller":"traceutil/trace.go:171","msg":"trace[176171582] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4726; }","duration":"154.581186ms","start":"2026-05-11T15:46:40.100995Z","end":"2026-05-11T15:46:40.255576Z","steps":["trace[176171582] 'agreement among raft nodes before linearized reading' (duration: 154.522755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:49.801337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9679535323689994331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T15:46:50.056933Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.049299944s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T15:46:50.057578Z","caller":"traceutil/trace.go:171","msg":"trace[231335481] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"1.050004378s","start":"2026-05-11T15:46:49.007545Z","end":"2026-05-11T15:46:50.057549Z","steps":["trace[231335481] 'process raft request' (duration: 1.049780114s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:50.057857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:49.007531Z","time spent":"1.050192361s","remote":"127.0.0.1:36252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":749,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T15:46:50.092977Z","caller":"traceutil/trace.go:171","msg":"trace[1000476143] linearizableReadLoop","detail":"{readStateIndex:4955; appliedIndex:4953; }","duration":"792.077832ms","start":"2026-05-11T15:46:49.300869Z","end":"2026-05-11T15:46:50.092947Z","steps":["trace[1000476143] 'read index received' (duration: 756.442002ms)","trace[1000476143] 'applied index is now lower than readState.Index' (duration: 35.63425ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T15:46:50.093356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.347315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T15:46:50.093432Z","caller":"traceutil/trace.go:171","msg":"trace[1981283987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4773; }","duration":"213.468248ms","start":"2026-05-11T15:46:49.879945Z","end":"2026-05-11T15:46:50.093413Z","steps":["trace[1981283987] 'agreement among raft nodes before linearized reading' (duration: 213.331455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:50.093667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.462019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-11T15:46:50.093812Z","caller":"traceutil/trace.go:171","msg":"trace[1586257394] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4773; }","duration":"792.990009ms","start":"2026-05-11T15:46:49.300798Z","end":"2026-05-11T15:46:50.093788Z","steps":["trace[1586257394] 'agreement among raft nodes before linearized reading' (duration: 792.379808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T15:46:50.09391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T15:46:49.300781Z","time spent":"793.11053ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-11T15:47:11.264084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2858} {"level":"info","ts":"2026-05-11T15:47:11.337611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2858,"took":"71.672858ms","hash":899058343,"current-db-size-bytes":39022592,"current-db-size":"39 MB","current-db-size-in-use-bytes":14589952,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-05-11T15:47:11.337736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":899058343,"revision":2858,"compact-revision":-1} {"level":"info","ts":"2026-05-11T15:49:18.173958Z","caller":"traceutil/trace.go:171","msg":"trace[1216338825] transaction","detail":"{read_only:false; response_revision:6496; number_of_response:1; }","duration":"115.231777ms","start":"2026-05-11T15:49:18.058707Z","end":"2026-05-11T15:49:18.173939Z","steps":["trace[1216338825] 'process raft request' (duration: 115.121115ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:49:18.183704Z","caller":"traceutil/trace.go:171","msg":"trace[1058254467] transaction","detail":"{read_only:false; response_revision:6497; number_of_response:1; }","duration":"124.912127ms","start":"2026-05-11T15:49:18.058777Z","end":"2026-05-11T15:49:18.183689Z","steps":["trace[1058254467] 'process raft request' (duration: 124.773895ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:49:18.183843Z","caller":"traceutil/trace.go:171","msg":"trace[724587138] transaction","detail":"{read_only:false; response_revision:6498; number_of_response:1; }","duration":"123.112013ms","start":"2026-05-11T15:49:18.060723Z","end":"2026-05-11T15:49:18.183835Z","steps":["trace[724587138] 'process raft request' (duration: 122.921699ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:49:27.159508Z","caller":"traceutil/trace.go:171","msg":"trace[498795894] transaction","detail":"{read_only:false; response_revision:6590; number_of_response:1; }","duration":"168.852489ms","start":"2026-05-11T15:49:26.990624Z","end":"2026-05-11T15:49:27.159476Z","steps":["trace[498795894] 'process raft request' (duration: 168.646865ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:49:27.159773Z","caller":"traceutil/trace.go:171","msg":"trace[1722909391] transaction","detail":"{read_only:false; response_revision:6591; number_of_response:1; }","duration":"154.297768ms","start":"2026-05-11T15:49:27.005417Z","end":"2026-05-11T15:49:27.159715Z","steps":["trace[1722909391] 'process raft request' (duration: 154.004854ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T15:49:27.161444Z","caller":"traceutil/trace.go:171","msg":"trace[450539546] transaction","detail":"{read_only:false; response_revision:6592; number_of_response:1; }","duration":"149.565009ms","start":"2026-05-11T15:49:27.011855Z","end":"2026-05-11T15:49:27.16142Z","steps":["trace[450539546] 'process raft request' (duration: 149.121673ms)"],"step_count":1}