{"level":"warn","ts":"2026-04-16T06:10:54.930407Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T06:10:54.930514Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T06:10:54.930637Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T06:10:54.930674Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-16T06:10:54.930699Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T06:10:54.931707Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-04-16T06:10:54.931852Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T06:10:54.936283Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.885743ms"} {"level":"info","ts":"2026-04-16T06:10:54.946905Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-04-16T06:10:54.946996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T06:10:54.947016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-04-16T06:10:54.94703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T06:10:54.947051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-04-16T06:10:54.947106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-04-16T06:10:54.95364Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T06:10:54.955513Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T06:10:54.957498Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-16T06:10:54.9607Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T06:10:54.960917Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T06:10:54.96101Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T06:10:54.961164Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T06:10:54.961209Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T06:10:54.963564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-04-16T06:10:54.963783Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-16T06:10:54.966889Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T06:10:54.967095Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-16T06:10:54.96716Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-16T06:10:54.967243Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T06:10:54.967309Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T06:10:55.34781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T06:10:55.347952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T06:10:55.348032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-04-16T06:10:55.348065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-04-16T06:10:55.348077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-16T06:10:55.348129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-04-16T06:10:55.348147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-16T06:10:55.349438Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T06:10:55.349471Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:10:55.349504Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:10:55.349809Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:10:55.350299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T06:10:55.350332Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T06:10:55.350838Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:10:55.350987Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:10:55.351031Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:10:55.352421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T06:10:55.353665Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-04-16T06:10:56.914931Z","caller":"traceutil/trace.go:171","msg":"trace[1263806602] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"110.237523ms","start":"2026-04-16T06:10:56.804644Z","end":"2026-04-16T06:10:56.914882Z","steps":["trace[1263806602] 'process raft request' (duration: 73.767094ms)","trace[1263806602] 'compare' (duration: 36.164141ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:10:56.915018Z","caller":"traceutil/trace.go:171","msg":"trace[431397415] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"109.956626ms","start":"2026-04-16T06:10:56.805023Z","end":"2026-04-16T06:10:56.91498Z","steps":["trace[431397415] 'process raft request' (duration: 109.782681ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:10:56.915087Z","caller":"traceutil/trace.go:171","msg":"trace[324309147] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"109.571096ms","start":"2026-04-16T06:10:56.805443Z","end":"2026-04-16T06:10:56.915014Z","steps":["trace[324309147] 'process raft request' (duration: 109.449433ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:10:56.915226Z","caller":"traceutil/trace.go:171","msg":"trace[450063212] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"110.106669ms","start":"2026-04-16T06:10:56.805043Z","end":"2026-04-16T06:10:56.915149Z","steps":["trace[450063212] 'process raft request' (duration: 109.819132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:11:15.392633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.498578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T06:11:15.392884Z","caller":"traceutil/trace.go:171","msg":"trace[852303379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:453; }","duration":"163.817086ms","start":"2026-04-16T06:11:15.229041Z","end":"2026-04-16T06:11:15.392858Z","steps":["trace[852303379] 'range keys from in-memory index tree' (duration: 163.345584ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:11:32.320609Z","caller":"traceutil/trace.go:171","msg":"trace[381735280] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"245.269344ms","start":"2026-04-16T06:11:32.075301Z","end":"2026-04-16T06:11:32.32057Z","steps":["trace[381735280] 'process raft request' (duration: 245.030377ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:11:32.357951Z","caller":"traceutil/trace.go:171","msg":"trace[1652957730] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"125.061452ms","start":"2026-04-16T06:11:32.232861Z","end":"2026-04-16T06:11:32.357922Z","steps":["trace[1652957730] 'read index received' (duration: 87.515734ms)","trace[1652957730] 'applied index is now lower than readState.Index' (duration: 37.544607ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:11:32.358082Z","caller":"traceutil/trace.go:171","msg":"trace[1367087320] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"248.117379ms","start":"2026-04-16T06:11:32.109906Z","end":"2026-04-16T06:11:32.358023Z","steps":["trace[1367087320] 'process raft request' (duration: 247.39947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:11:32.358211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.317558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T06:11:32.358311Z","caller":"traceutil/trace.go:171","msg":"trace[1290814131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"125.482112ms","start":"2026-04-16T06:11:32.232806Z","end":"2026-04-16T06:11:32.358288Z","steps":["trace[1290814131] 'agreement among raft nodes before linearized reading' (duration: 125.217435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:11:32.862398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.536849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:11:32.862523Z","caller":"traceutil/trace.go:171","msg":"trace[272689576] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"352.077659ms","start":"2026-04-16T06:11:32.510427Z","end":"2026-04-16T06:11:32.862505Z","steps":["trace[272689576] 'read index received' (duration: 243.967738ms)","trace[272689576] 'applied index is now lower than readState.Index' (duration: 108.108601ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:11:32.862596Z","caller":"traceutil/trace.go:171","msg":"trace[319091668] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"454.183222ms","start":"2026-04-16T06:11:32.408377Z","end":"2026-04-16T06:11:32.86256Z","steps":["trace[319091668] 'process raft request' (duration: 346.150283ms)","trace[319091668] 'compare' (duration: 106.304153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:11:32.862732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.300016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rjljk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-16T06:11:32.862774Z","caller":"traceutil/trace.go:171","msg":"trace[1636890944] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rjljk; range_end:; response_count:1; response_revision:622; }","duration":"352.393058ms","start":"2026-04-16T06:11:32.51037Z","end":"2026-04-16T06:11:32.862763Z","steps":["trace[1636890944] 'agreement among raft nodes before linearized reading' (duration: 352.181643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:11:32.862823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:11:32.510346Z","time spent":"352.46897ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-rjljk\" "} {"level":"warn","ts":"2026-04-16T06:11:32.862854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:11:32.408358Z","time spent":"454.302314ms","remote":"127.0.0.1:42834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T06:12:18.548601Z","caller":"traceutil/trace.go:171","msg":"trace[576343490] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"157.202864ms","start":"2026-04-16T06:12:18.391373Z","end":"2026-04-16T06:12:18.548576Z","steps":["trace[576343490] 'read index received' (duration: 156.971488ms)","trace[576343490] 'applied index is now lower than readState.Index' (duration: 230.526µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:18.548733Z","caller":"traceutil/trace.go:171","msg":"trace[1747957148] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"191.832854ms","start":"2026-04-16T06:12:18.356883Z","end":"2026-04-16T06:12:18.548716Z","steps":["trace[1747957148] 'process raft request' (duration: 191.536486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:18.54902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.639317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:18.54907Z","caller":"traceutil/trace.go:171","msg":"trace[834576161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"157.759939ms","start":"2026-04-16T06:12:18.391298Z","end":"2026-04-16T06:12:18.549058Z","steps":["trace[834576161] 'agreement among raft nodes before linearized reading' (duration: 157.668097ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:51.2274Z","caller":"traceutil/trace.go:171","msg":"trace[1928311666] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"145.592732ms","start":"2026-04-16T06:12:51.081782Z","end":"2026-04-16T06:12:51.227375Z","steps":["trace[1928311666] 'process raft request' (duration: 145.459118ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:51.23318Z","caller":"traceutil/trace.go:171","msg":"trace[603740047] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"149.63711ms","start":"2026-04-16T06:12:51.083508Z","end":"2026-04-16T06:12:51.233145Z","steps":["trace[603740047] 'process raft request' (duration: 149.461156ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:51.233282Z","caller":"traceutil/trace.go:171","msg":"trace[1309516383] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"122.207267ms","start":"2026-04-16T06:12:51.111058Z","end":"2026-04-16T06:12:51.233265Z","steps":["trace[1309516383] 'process raft request' (duration: 122.011421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:53.839512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101723970170831,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T06:12:54.027905Z","caller":"traceutil/trace.go:171","msg":"trace[1262683994] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"791.017654ms","start":"2026-04-16T06:12:53.236857Z","end":"2026-04-16T06:12:54.027874Z","steps":["trace[1262683994] 'process raft request' (duration: 790.884131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:54.028268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.236835Z","time spent":"791.137597ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:12:55.692577Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.681941178s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-16T06:12:55.814198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.415625ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-16T06:12:55.814391Z","caller":"traceutil/trace.go:171","msg":"trace[1604490308] linearizableReadLoop","detail":"{readStateIndex:1710; appliedIndex:1709; }","duration":"2.497804116s","start":"2026-04-16T06:12:53.316559Z","end":"2026-04-16T06:12:55.814363Z","steps":["trace[1604490308] 'read index received' (duration: 712.032473ms)","trace[1604490308] 'applied index is now lower than readState.Index' (duration: 1.785769533s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:12:55.814785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.286646511s","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-04-16T06:12:55.81484Z","caller":"traceutil/trace.go:171","msg":"trace[790385701] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1661; }","duration":"2.286749635s","start":"2026-04-16T06:12:53.528069Z","end":"2026-04-16T06:12:55.814819Z","steps":["trace[790385701] 'agreement among raft nodes before linearized reading' (duration: 2.286508298s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.814889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.528052Z","time spent":"2.286825826s","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-16T06:12:55.814854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.799622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.81504Z","caller":"traceutil/trace.go:171","msg":"trace[1564590934] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1661; }","duration":"160.11274ms","start":"2026-04-16T06:12:55.654872Z","end":"2026-04-16T06:12:55.814985Z","steps":["trace[1564590934] 'agreement among raft nodes before linearized reading' (duration: 159.786342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.81505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.781278253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:55.815113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.528254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.815234Z","caller":"traceutil/trace.go:171","msg":"trace[1225329395] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1661; }","duration":"1.781497049s","start":"2026-04-16T06:12:54.033689Z","end":"2026-04-16T06:12:55.815186Z","steps":["trace[1225329395] 'agreement among raft nodes before linearized reading' (duration: 1.781198911s)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.81527Z","caller":"traceutil/trace.go:171","msg":"trace[1550136496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1661; }","duration":"159.685769ms","start":"2026-04-16T06:12:55.655513Z","end":"2026-04-16T06:12:55.815199Z","steps":["trace[1550136496] 'agreement among raft nodes before linearized reading' (duration: 159.383981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.815319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.03367Z","time spent":"1.781608082s","remote":"127.0.0.1:55258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:55.81538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.781995182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:55.81528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.783839882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:12:55.815452Z","caller":"traceutil/trace.go:171","msg":"trace[1931739765] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1661; }","duration":"1.782072804s","start":"2026-04-16T06:12:54.033355Z","end":"2026-04-16T06:12:55.815428Z","steps":["trace[1931739765] 'agreement among raft nodes before linearized reading' (duration: 1.781945451s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.815453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.029341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T06:12:55.815489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.163291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T06:12:55.815508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.033349Z","time spent":"1.782147976s","remote":"127.0.0.1:55396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-16T06:12:55.815526Z","caller":"traceutil/trace.go:171","msg":"trace[581354689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1661; }","duration":"160.247533ms","start":"2026-04-16T06:12:55.655268Z","end":"2026-04-16T06:12:55.815516Z","steps":["trace[581354689] 'agreement among raft nodes before linearized reading' (duration: 160.057648ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.815557Z","caller":"traceutil/trace.go:171","msg":"trace[1976935544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1661; }","duration":"159.161735ms","start":"2026-04-16T06:12:55.656342Z","end":"2026-04-16T06:12:55.815503Z","steps":["trace[1976935544] 'agreement among raft nodes before linearized reading' (duration: 158.828906ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.815549Z","caller":"traceutil/trace.go:171","msg":"trace[1756957997] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1661; }","duration":"1.784109148s","start":"2026-04-16T06:12:54.031362Z","end":"2026-04-16T06:12:55.815471Z","steps":["trace[1756957997] 'agreement among raft nodes before linearized reading' (duration: 1.783685677s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.815669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.499060449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com\" ","response":"range_response_count:1 size:4769"} {"level":"info","ts":"2026-04-16T06:12:55.815766Z","caller":"traceutil/trace.go:171","msg":"trace[1804563629] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com; range_end:; response_count:1; response_revision:1661; }","duration":"2.499244335s","start":"2026-04-16T06:12:53.316506Z","end":"2026-04-16T06:12:55.81575Z","steps":["trace[1804563629] 'agreement among raft nodes before linearized reading' (duration: 2.49795247s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.815758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074195193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-16T06:12:55.815785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.031345Z","time spent":"1.784349145s","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:55.815809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.858639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T06:12:55.81585Z","caller":"traceutil/trace.go:171","msg":"trace[916810101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1661; }","duration":"1.074264404s","start":"2026-04-16T06:12:54.741547Z","end":"2026-04-16T06:12:55.815811Z","steps":["trace[916810101] 'agreement among raft nodes before linearized reading' (duration: 1.07408655s)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.815868Z","caller":"traceutil/trace.go:171","msg":"trace[1876402372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1661; }","duration":"622.963422ms","start":"2026-04-16T06:12:55.192887Z","end":"2026-04-16T06:12:55.815851Z","steps":["trace[1876402372] 'agreement among raft nodes before linearized reading' (duration: 622.781258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.815613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.818508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:55.815911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:55.192858Z","time spent":"623.042824ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T06:12:55.815899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.51631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.815928Z","caller":"traceutil/trace.go:171","msg":"trace[1966000981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1661; }","duration":"941.174737ms","start":"2026-04-16T06:12:54.874733Z","end":"2026-04-16T06:12:55.815908Z","steps":["trace[1966000981] 'agreement among raft nodes before linearized reading' (duration: 940.819737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.815818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.316483Z","time spent":"2.499321536s","remote":"127.0.0.1:42380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":4791,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com\" "} {"level":"warn","ts":"2026-04-16T06:12:55.815977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.874709Z","time spent":"941.25728ms","remote":"127.0.0.1:42336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T06:12:55.815941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.741461Z","time spent":"1.074464219s","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T06:12:55.815975Z","caller":"traceutil/trace.go:171","msg":"trace[810718007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1661; }","duration":"154.632333ms","start":"2026-04-16T06:12:55.661324Z","end":"2026-04-16T06:12:55.815956Z","steps":["trace[810718007] 'agreement among raft nodes before linearized reading' (duration: 154.479859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.815951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925804586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T06:12:55.816156Z","caller":"traceutil/trace.go:171","msg":"trace[1840800434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1661; }","duration":"1.926050022s","start":"2026-04-16T06:12:53.89009Z","end":"2026-04-16T06:12:55.81614Z","steps":["trace[1840800434] 'agreement among raft nodes before linearized reading' (duration: 1.925664422s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.816213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.890073Z","time spent":"1.926124135s","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T06:12:55.816714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.089912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.81681Z","caller":"traceutil/trace.go:171","msg":"trace[2140690947] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1661; }","duration":"159.232156ms","start":"2026-04-16T06:12:55.657544Z","end":"2026-04-16T06:12:55.816777Z","steps":["trace[2140690947] 'agreement among raft nodes before linearized reading' (duration: 159.066052ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.589905Z","caller":"traceutil/trace.go:171","msg":"trace[2139779310] linearizableReadLoop","detail":"{readStateIndex:1721; appliedIndex:1720; }","duration":"198.325131ms","start":"2026-04-16T06:12:56.391544Z","end":"2026-04-16T06:12:56.589869Z","steps":["trace[2139779310] 'read index received' (duration: 190.019699ms)","trace[2139779310] 'applied index is now lower than readState.Index' (duration: 8.299532ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:56.59002Z","caller":"traceutil/trace.go:171","msg":"trace[354214809] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"555.582941ms","start":"2026-04-16T06:12:56.034392Z","end":"2026-04-16T06:12:56.589975Z","steps":["trace[354214809] 'process raft request' (duration: 547.238588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.590244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.034343Z","time spent":"555.794036ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:12:56.590053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.479665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.59038Z","caller":"traceutil/trace.go:171","msg":"trace[150004084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1672; }","duration":"198.852935ms","start":"2026-04-16T06:12:56.39151Z","end":"2026-04-16T06:12:56.590363Z","steps":["trace[150004084] 'agreement among raft nodes before linearized reading' (duration: 198.480465ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:13:06.118305Z","caller":"traceutil/trace.go:171","msg":"trace[1659060726] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"297.41392ms","start":"2026-04-16T06:13:05.820861Z","end":"2026-04-16T06:13:06.118275Z","steps":["trace[1659060726] 'process raft request' (duration: 297.246916ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:13:06.141273Z","caller":"traceutil/trace.go:171","msg":"trace[1785864436] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"320.074766ms","start":"2026-04-16T06:13:05.821143Z","end":"2026-04-16T06:13:06.141218Z","steps":["trace[1785864436] 'process raft request' (duration: 319.957203ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:13:06.141362Z","caller":"traceutil/trace.go:171","msg":"trace[1250116746] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1888; }","duration":"262.743693ms","start":"2026-04-16T06:13:05.878591Z","end":"2026-04-16T06:13:06.141335Z","steps":["trace[1250116746] 'read index received' (duration: 239.623875ms)","trace[1250116746] 'applied index is now lower than readState.Index' (duration: 23.117098ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:13:06.141485Z","caller":"traceutil/trace.go:171","msg":"trace[1726052210] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"318.848503ms","start":"2026-04-16T06:13:05.822571Z","end":"2026-04-16T06:13:06.141419Z","steps":["trace[1726052210] 'process raft request' (duration: 318.601676ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:13:06.141279Z","caller":"traceutil/trace.go:171","msg":"trace[1591299198] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"320.284042ms","start":"2026-04-16T06:13:05.820951Z","end":"2026-04-16T06:13:06.141235Z","steps":["trace[1591299198] 'process raft request' (duration: 319.993134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:13:06.141777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.077313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T06:13:06.141832Z","caller":"traceutil/trace.go:171","msg":"trace[2048553133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1838; }","duration":"263.295318ms","start":"2026-04-16T06:13:05.878521Z","end":"2026-04-16T06:13:06.141816Z","steps":["trace[2048553133] 'agreement among raft nodes before linearized reading' (duration: 262.918648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:13:06.141834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:13:05.822553Z","time spent":"319.11816ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:13:06.141886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:13:05.820936Z","time spent":"320.876647ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:13:06.141797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.750151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-16T06:13:06.141984Z","caller":"traceutil/trace.go:171","msg":"trace[227586723] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1838; }","duration":"165.960606ms","start":"2026-04-16T06:13:05.975992Z","end":"2026-04-16T06:13:06.141953Z","steps":["trace[227586723] 'agreement among raft nodes before linearized reading' (duration: 165.630557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:13:06.142041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.921868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-16T06:13:06.142091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.70255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T06:13:06.142133Z","caller":"traceutil/trace.go:171","msg":"trace[1172307661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1838; }","duration":"260.031581ms","start":"2026-04-16T06:13:05.882078Z","end":"2026-04-16T06:13:06.14211Z","steps":["trace[1172307661] 'agreement among raft nodes before linearized reading' (duration: 259.751344ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:13:06.142148Z","caller":"traceutil/trace.go:171","msg":"trace[1695350904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1838; }","duration":"174.757781ms","start":"2026-04-16T06:13:05.967367Z","end":"2026-04-16T06:13:06.142125Z","steps":["trace[1695350904] 'agreement among raft nodes before linearized reading' (duration: 174.677249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:13:06.142168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:13:05.821099Z","time spent":"320.912778ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1512,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:13:06.14199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.585234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T06:13:06.142264Z","caller":"traceutil/trace.go:171","msg":"trace[2009509845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1838; }","duration":"178.927313ms","start":"2026-04-16T06:13:05.963332Z","end":"2026-04-16T06:13:06.14226Z","steps":["trace[2009509845] 'agreement among raft nodes before linearized reading' (duration: 178.417299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:13:06.142844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.841969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T06:13:06.142967Z","caller":"traceutil/trace.go:171","msg":"trace[1222802456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1838; }","duration":"262.042865ms","start":"2026-04-16T06:13:05.880897Z","end":"2026-04-16T06:13:06.14294Z","steps":["trace[1222802456] 'agreement among raft nodes before linearized reading' (duration: 260.635398ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:13:06.267629Z","caller":"traceutil/trace.go:171","msg":"trace[1281300991] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"100.024683ms","start":"2026-04-16T06:13:06.167566Z","end":"2026-04-16T06:13:06.267591Z","steps":["trace[1281300991] 'process raft request' (duration: 99.821558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:13:49.215053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.314384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:13:49.215161Z","caller":"traceutil/trace.go:171","msg":"trace[511170789] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2180; }","duration":"181.440748ms","start":"2026-04-16T06:13:49.033698Z","end":"2026-04-16T06:13:49.215139Z","steps":["trace[511170789] 'count revisions from in-memory index tree' (duration: 181.205701ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:13:54.599273Z","caller":"traceutil/trace.go:171","msg":"trace[889773670] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"129.191218ms","start":"2026-04-16T06:13:54.470047Z","end":"2026-04-16T06:13:54.599238Z","steps":["trace[889773670] 'process raft request' (duration: 117.077094ms)","trace[889773670] 'compare' (duration: 11.791425ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:13:54.599566Z","caller":"traceutil/trace.go:171","msg":"trace[1119274449] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"128.071989ms","start":"2026-04-16T06:13:54.471461Z","end":"2026-04-16T06:13:54.599533Z","steps":["trace[1119274449] 'process raft request' (duration: 127.984687ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:13:54.599793Z","caller":"traceutil/trace.go:171","msg":"trace[1650327575] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"128.329116ms","start":"2026-04-16T06:13:54.471451Z","end":"2026-04-16T06:13:54.59978Z","steps":["trace[1650327575] 'process raft request' (duration: 127.891074ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:13:54.60004Z","caller":"traceutil/trace.go:171","msg":"trace[1769207051] linearizableReadLoop","detail":"{readStateIndex:2290; appliedIndex:2286; }","duration":"111.485525ms","start":"2026-04-16T06:13:54.488527Z","end":"2026-04-16T06:13:54.600012Z","steps":["trace[1769207051] 'read index received' (duration: 98.61543ms)","trace[1769207051] 'applied index is now lower than readState.Index' (duration: 12.863455ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:13:54.600103Z","caller":"traceutil/trace.go:171","msg":"trace[1137178150] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"128.51502ms","start":"2026-04-16T06:13:54.47155Z","end":"2026-04-16T06:13:54.600065Z","steps":["trace[1137178150] 'process raft request' (duration: 127.941105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:13:54.600321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.776212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-16T06:13:54.600564Z","caller":"traceutil/trace.go:171","msg":"trace[47666749] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2216; }","duration":"112.05412ms","start":"2026-04-16T06:13:54.488488Z","end":"2026-04-16T06:13:54.600543Z","steps":["trace[47666749] 'agreement among raft nodes before linearized reading' (duration: 111.66476ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:14:26.338088Z","caller":"traceutil/trace.go:171","msg":"trace[1459288769] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"206.617281ms","start":"2026-04-16T06:14:26.131441Z","end":"2026-04-16T06:14:26.338058Z","steps":["trace[1459288769] 'process raft request' (duration: 206.458086ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:15:01.769274Z","caller":"traceutil/trace.go:171","msg":"trace[63422278] linearizableReadLoop","detail":"{readStateIndex:2831; appliedIndex:2830; }","duration":"123.816806ms","start":"2026-04-16T06:15:01.645425Z","end":"2026-04-16T06:15:01.769242Z","steps":["trace[63422278] 'read index received' (duration: 123.621581ms)","trace[63422278] 'applied index is now lower than readState.Index' (duration: 193.905µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:15:01.769376Z","caller":"traceutil/trace.go:171","msg":"trace[1072656699] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"165.622121ms","start":"2026-04-16T06:15:01.603731Z","end":"2026-04-16T06:15:01.769353Z","steps":["trace[1072656699] 'process raft request' (duration: 165.329823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:15:01.769534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.117074ms","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-04-16T06:15:01.76959Z","caller":"traceutil/trace.go:171","msg":"trace[158220007] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2728; }","duration":"124.188336ms","start":"2026-04-16T06:15:01.645384Z","end":"2026-04-16T06:15:01.769573Z","steps":["trace[158220007] 'agreement among raft nodes before linearized reading' (duration: 124.009441ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:15:01.945756Z","caller":"traceutil/trace.go:171","msg":"trace[298548843] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"169.097216ms","start":"2026-04-16T06:15:01.776605Z","end":"2026-04-16T06:15:01.945702Z","steps":["trace[298548843] 'process raft request' (duration: 114.427903ms)","trace[298548843] 'compare' (duration: 54.215881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:15:05.104955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.210967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:15:05.105035Z","caller":"traceutil/trace.go:171","msg":"trace[800268670] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2748; }","duration":"248.34132ms","start":"2026-04-16T06:15:04.856673Z","end":"2026-04-16T06:15:05.105014Z","steps":["trace[800268670] 'count revisions from in-memory index tree' (duration: 248.095364ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:16:03.836064Z","caller":"traceutil/trace.go:171","msg":"trace[488857875] transaction","detail":"{read_only:false; response_revision:3323; number_of_response:1; }","duration":"137.841218ms","start":"2026-04-16T06:16:03.698194Z","end":"2026-04-16T06:16:03.836035Z","steps":["trace[488857875] 'process raft request' (duration: 137.670213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.703224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.714465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T06:17:05.703347Z","caller":"traceutil/trace.go:171","msg":"trace[2011117381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3823; }","duration":"368.899879ms","start":"2026-04-16T06:17:05.334421Z","end":"2026-04-16T06:17:05.703321Z","steps":["trace[2011117381] 'range keys from in-memory index tree' (duration: 368.503319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.703387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:05.334395Z","time spent":"368.984012ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T06:17:05.70353Z","caller":"traceutil/trace.go:171","msg":"trace[1487908872] linearizableReadLoop","detail":"{readStateIndex:3996; appliedIndex:3995; }","duration":"313.303992ms","start":"2026-04-16T06:17:05.390193Z","end":"2026-04-16T06:17:05.703497Z","steps":["trace[1487908872] 'read index received' (duration: 313.138578ms)","trace[1487908872] 'applied index is now lower than readState.Index' (duration: 164.384µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:17:05.703812Z","caller":"traceutil/trace.go:171","msg":"trace[1146588896] transaction","detail":"{read_only:false; response_revision:3824; number_of_response:1; }","duration":"362.072974ms","start":"2026-04-16T06:17:05.341718Z","end":"2026-04-16T06:17:05.703791Z","steps":["trace[1146588896] 'process raft request' (duration: 361.640662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.703933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.734064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:17:05.704003Z","caller":"traceutil/trace.go:171","msg":"trace[2106460346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3824; }","duration":"313.846567ms","start":"2026-04-16T06:17:05.390139Z","end":"2026-04-16T06:17:05.703985Z","steps":["trace[2106460346] 'agreement among raft nodes before linearized reading' (duration: 313.739364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.704051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:05.390117Z","time spent":"313.925369ms","remote":"127.0.0.1:42342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T06:17:05.703945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:05.341695Z","time spent":"362.170387ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:17:05.704294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.263821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:17:05.704354Z","caller":"traceutil/trace.go:171","msg":"trace[268538158] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:3825; }","duration":"268.384734ms","start":"2026-04-16T06:17:05.435955Z","end":"2026-04-16T06:17:05.70434Z","steps":["trace[268538158] 'agreement among raft nodes before linearized reading' (duration: 268.267741ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:05.704382Z","caller":"traceutil/trace.go:171","msg":"trace[1942580888] transaction","detail":"{read_only:false; response_revision:3825; number_of_response:1; }","duration":"183.720259ms","start":"2026-04-16T06:17:05.520592Z","end":"2026-04-16T06:17:05.704312Z","steps":["trace[1942580888] 'process raft request' (duration: 183.537954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.704474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.580391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-16T06:17:05.704517Z","caller":"traceutil/trace.go:171","msg":"trace[149182405] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3825; }","duration":"188.672253ms","start":"2026-04-16T06:17:05.515832Z","end":"2026-04-16T06:17:05.704505Z","steps":["trace[149182405] 'agreement among raft nodes before linearized reading' (duration: 188.53043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:06.231733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101723970176598,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T06:17:06.512712Z","caller":"traceutil/trace.go:171","msg":"trace[1211985668] linearizableReadLoop","detail":"{readStateIndex:4002; appliedIndex:4001; }","duration":"781.384871ms","start":"2026-04-16T06:17:05.731296Z","end":"2026-04-16T06:17:06.512681Z","steps":["trace[1211985668] 'read index received' (duration: 781.093663ms)","trace[1211985668] 'applied index is now lower than readState.Index' (duration: 290.178µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:17:06.512797Z","caller":"traceutil/trace.go:171","msg":"trace[304726260] transaction","detail":"{read_only:false; response_revision:3829; number_of_response:1; }","duration":"784.220839ms","start":"2026-04-16T06:17:05.728528Z","end":"2026-04-16T06:17:06.512748Z","steps":["trace[304726260] 'process raft request' (duration: 783.985313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:06.513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.303261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-16T06:17:06.513005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.04126ms","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-04-16T06:17:06.513023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:05.728493Z","time spent":"784.405524ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:17:06.513009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.67921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T06:17:06.513067Z","caller":"traceutil/trace.go:171","msg":"trace[281284506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3829; }","duration":"739.394113ms","start":"2026-04-16T06:17:05.773656Z","end":"2026-04-16T06:17:06.51305Z","steps":["trace[281284506] 'agreement among raft nodes before linearized reading' (duration: 739.188068ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:06.513083Z","caller":"traceutil/trace.go:171","msg":"trace[1667492575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3829; }","duration":"346.125593ms","start":"2026-04-16T06:17:06.166938Z","end":"2026-04-16T06:17:06.513064Z","steps":["trace[1667492575] 'agreement among raft nodes before linearized reading' (duration: 345.937167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:06.513082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.504268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:17:06.513117Z","caller":"traceutil/trace.go:171","msg":"trace[741016939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3829; }","duration":"781.804683ms","start":"2026-04-16T06:17:05.731282Z","end":"2026-04-16T06:17:06.513087Z","steps":["trace[741016939] 'agreement among raft nodes before linearized reading' (duration: 781.509325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:06.513118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:05.773647Z","time spent":"739.457355ms","remote":"127.0.0.1:42604","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-04-16T06:17:06.513126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:06.16595Z","time spent":"347.164911ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T06:17:06.513133Z","caller":"traceutil/trace.go:171","msg":"trace[247798411] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:3829; }","duration":"431.568569ms","start":"2026-04-16T06:17:06.081547Z","end":"2026-04-16T06:17:06.513115Z","steps":["trace[247798411] 'agreement among raft nodes before linearized reading' (duration: 431.475496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:06.513111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.56967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:17:06.513163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:05.731274Z","time spent":"781.880315ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T06:17:06.513176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:06.081539Z","time spent":"431.62736ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":20,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:17:06.513207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.332228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:17:06.513326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.583618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T06:17:06.513346Z","caller":"traceutil/trace.go:171","msg":"trace[753486660] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:3829; }","duration":"163.546893ms","start":"2026-04-16T06:17:06.349766Z","end":"2026-04-16T06:17:06.513313Z","steps":["trace[753486660] 'agreement among raft nodes before linearized reading' (duration: 163.227865ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:06.513387Z","caller":"traceutil/trace.go:171","msg":"trace[641184833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3829; }","duration":"739.65002ms","start":"2026-04-16T06:17:05.773722Z","end":"2026-04-16T06:17:06.513372Z","steps":["trace[641184833] 'agreement among raft nodes before linearized reading' (duration: 739.515186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:06.513474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:05.773718Z","time spent":"739.742402ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T06:17:06.513221Z","caller":"traceutil/trace.go:171","msg":"trace[1906407063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3829; }","duration":"123.724614ms","start":"2026-04-16T06:17:06.389469Z","end":"2026-04-16T06:17:06.513194Z","steps":["trace[1906407063] 'agreement among raft nodes before linearized reading' (duration: 123.482258ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:37.088793Z","caller":"traceutil/trace.go:171","msg":"trace[279269967] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"260.966554ms","start":"2026-04-16T06:17:36.827797Z","end":"2026-04-16T06:17:37.088764Z","steps":["trace[279269967] 'process raft request' (duration: 253.264725ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:37.089182Z","caller":"traceutil/trace.go:171","msg":"trace[159518088] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"260.81038ms","start":"2026-04-16T06:17:36.828349Z","end":"2026-04-16T06:17:37.089159Z","steps":["trace[159518088] 'process raft request' (duration: 260.643046ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:37.089175Z","caller":"traceutil/trace.go:171","msg":"trace[704764887] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4963; }","duration":"171.16216ms","start":"2026-04-16T06:17:36.917985Z","end":"2026-04-16T06:17:37.089148Z","steps":["trace[704764887] 'read index received' (duration: 163.089981ms)","trace[704764887] 'applied index is now lower than readState.Index' (duration: 8.071089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:17:37.08943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.390426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-77cq5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-16T06:17:37.089497Z","caller":"traceutil/trace.go:171","msg":"trace[133213192] range","detail":"{range_begin:/registry/pods/openstack/keepalived-77cq5; range_end:; response_count:1; response_revision:4766; }","duration":"171.524889ms","start":"2026-04-16T06:17:36.917954Z","end":"2026-04-16T06:17:37.089479Z","steps":["trace[133213192] 'agreement among raft nodes before linearized reading' (duration: 171.278722ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:44.693335Z","caller":"traceutil/trace.go:171","msg":"trace[961931342] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"151.731083ms","start":"2026-04-16T06:17:44.541567Z","end":"2026-04-16T06:17:44.693298Z","steps":["trace[961931342] 'process raft request' (duration: 151.566278ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:44.822503Z","caller":"traceutil/trace.go:171","msg":"trace[2017933461] linearizableReadLoop","detail":"{readStateIndex:5150; appliedIndex:5148; }","duration":"210.729673ms","start":"2026-04-16T06:17:44.611744Z","end":"2026-04-16T06:17:44.822474Z","steps":["trace[2017933461] 'read index received' (duration: 81.415267ms)","trace[2017933461] 'applied index is now lower than readState.Index' (duration: 129.313326ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:17:44.822667Z","caller":"traceutil/trace.go:171","msg":"trace[1266610209] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"280.892934ms","start":"2026-04-16T06:17:44.541758Z","end":"2026-04-16T06:17:44.822651Z","steps":["trace[1266610209] 'process raft request' (duration: 212.059708ms)","trace[1266610209] 'compare' (duration: 68.499817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:17:44.822894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.127653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf0e425c-d7c6-46b2-b24b-4923890728af\" ","response":"range_response_count:1 size:3763"} {"level":"warn","ts":"2026-04-16T06:17:44.82293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.650896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-dvx6l\" ","response":"range_response_count:1 size:4995"} {"level":"info","ts":"2026-04-16T06:17:44.823009Z","caller":"traceutil/trace.go:171","msg":"trace[592440033] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-dvx6l; range_end:; response_count:1; response_revision:4943; }","duration":"125.77704ms","start":"2026-04-16T06:17:44.697213Z","end":"2026-04-16T06:17:44.82299Z","steps":["trace[592440033] 'agreement among raft nodes before linearized reading' (duration: 125.523883ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:44.822966Z","caller":"traceutil/trace.go:171","msg":"trace[1930285655] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf0e425c-d7c6-46b2-b24b-4923890728af; range_end:; response_count:1; response_revision:4943; }","duration":"211.250367ms","start":"2026-04-16T06:17:44.611698Z","end":"2026-04-16T06:17:44.822948Z","steps":["trace[1930285655] 'agreement among raft nodes before linearized reading' (duration: 211.02179ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:45.033374Z","caller":"traceutil/trace.go:171","msg":"trace[1908405104] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5151; }","duration":"164.800516ms","start":"2026-04-16T06:17:44.868547Z","end":"2026-04-16T06:17:45.033347Z","steps":["trace[1908405104] 'read index received' (duration: 138.275717ms)","trace[1908405104] 'applied index is now lower than readState.Index' (duration: 26.523689ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:17:45.033562Z","caller":"traceutil/trace.go:171","msg":"trace[1021569936] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"203.321421ms","start":"2026-04-16T06:17:44.830223Z","end":"2026-04-16T06:17:45.033545Z","steps":["trace[1021569936] 'process raft request' (duration: 176.691249ms)","trace[1021569936] 'compare' (duration: 26.124418ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:17:45.033804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.930995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T06:17:45.033904Z","caller":"traceutil/trace.go:171","msg":"trace[2004697876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4945; }","duration":"146.073299ms","start":"2026-04-16T06:17:44.887807Z","end":"2026-04-16T06:17:45.033881Z","steps":["trace[2004697876] 'agreement among raft nodes before linearized reading' (duration: 145.808111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:45.033866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.29785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T06:17:45.034093Z","caller":"traceutil/trace.go:171","msg":"trace[1518844799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4945; }","duration":"165.559347ms","start":"2026-04-16T06:17:44.868514Z","end":"2026-04-16T06:17:45.034073Z","steps":["trace[1518844799] 'agreement among raft nodes before linearized reading' (duration: 165.184007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:45.03394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.412005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:17:45.034261Z","caller":"traceutil/trace.go:171","msg":"trace[750195430] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:4945; }","duration":"130.795084ms","start":"2026-04-16T06:17:44.903448Z","end":"2026-04-16T06:17:45.034243Z","steps":["trace[750195430] 'agreement among raft nodes before linearized reading' (duration: 130.353152ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:20:55.38101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3262} {"level":"info","ts":"2026-04-16T06:20:55.468834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3262,"took":"85.776984ms","hash":1576448251,"current-db-size-bytes":61370368,"current-db-size":"61 MB","current-db-size-in-use-bytes":33820672,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-16T06:20:55.468915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1576448251,"revision":3262,"compact-revision":-1} {"level":"info","ts":"2026-04-16T06:23:22.956098Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000020eb.wal"} {"level":"info","ts":"2026-04-16T06:23:27.008612Z","caller":"traceutil/trace.go:171","msg":"trace[1302099172] linearizableReadLoop","detail":"{readStateIndex:8454; appliedIndex:8453; }","duration":"269.162463ms","start":"2026-04-16T06:23:26.739418Z","end":"2026-04-16T06:23:27.008581Z","steps":["trace[1302099172] 'read index received' (duration: 268.998518ms)","trace[1302099172] 'applied index is now lower than readState.Index' (duration: 162.845µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:23:27.008668Z","caller":"traceutil/trace.go:171","msg":"trace[840240637] transaction","detail":"{read_only:false; response_revision:8100; number_of_response:1; }","duration":"271.156428ms","start":"2026-04-16T06:23:26.737486Z","end":"2026-04-16T06:23:27.008643Z","steps":["trace[840240637] 'process raft request' (duration: 270.945112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:23:27.008948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.498542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T06:23:27.009046Z","caller":"traceutil/trace.go:171","msg":"trace[2053278823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8100; }","duration":"269.606436ms","start":"2026-04-16T06:23:26.739412Z","end":"2026-04-16T06:23:27.009019Z","steps":["trace[2053278823] 'agreement among raft nodes before linearized reading' (duration: 269.335718ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:23:27.124924Z","caller":"traceutil/trace.go:171","msg":"trace[1894403475] transaction","detail":"{read_only:false; response_revision:8102; number_of_response:1; }","duration":"111.390966ms","start":"2026-04-16T06:23:27.0135Z","end":"2026-04-16T06:23:27.124891Z","steps":["trace[1894403475] 'process raft request' (duration: 111.1643ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:23:27.125238Z","caller":"traceutil/trace.go:171","msg":"trace[1790685520] transaction","detail":"{read_only:false; response_revision:8103; number_of_response:1; }","duration":"111.596712ms","start":"2026-04-16T06:23:27.013627Z","end":"2026-04-16T06:23:27.125224Z","steps":["trace[1790685520] 'process raft request' (duration: 111.196661ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:23:27.125567Z","caller":"traceutil/trace.go:171","msg":"trace[1417161535] transaction","detail":"{read_only:false; response_revision:8104; number_of_response:1; }","duration":"111.16688ms","start":"2026-04-16T06:23:27.014348Z","end":"2026-04-16T06:23:27.125515Z","steps":["trace[1417161535] 'process raft request' (duration: 110.824341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:23:27.125797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.586918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-77cq5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-16T06:23:27.125888Z","caller":"traceutil/trace.go:171","msg":"trace[1810496109] range","detail":"{range_begin:/registry/pods/openstack/keepalived-77cq5; range_end:; response_count:1; response_revision:8104; }","duration":"102.724301ms","start":"2026-04-16T06:23:27.023144Z","end":"2026-04-16T06:23:27.125868Z","steps":["trace[1810496109] 'agreement among raft nodes before linearized reading' (duration: 102.501675ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:23:27.126284Z","caller":"traceutil/trace.go:171","msg":"trace[851434797] linearizableReadLoop","detail":"{readStateIndex:8458; appliedIndex:8454; }","duration":"102.169805ms","start":"2026-04-16T06:23:27.023179Z","end":"2026-04-16T06:23:27.125349Z","steps":["trace[851434797] 'read index received' (duration: 17.584227ms)","trace[851434797] 'applied index is now lower than readState.Index' (duration: 84.584288ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:23:38.688603Z","caller":"traceutil/trace.go:171","msg":"trace[90921866] linearizableReadLoop","detail":"{readStateIndex:8536; appliedIndex:8535; }","duration":"153.09614ms","start":"2026-04-16T06:23:38.535468Z","end":"2026-04-16T06:23:38.688564Z","steps":["trace[90921866] 'read index received' (duration: 152.783191ms)","trace[90921866] 'applied index is now lower than readState.Index' (duration: 311.589µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:23:38.688757Z","caller":"traceutil/trace.go:171","msg":"trace[1412041133] transaction","detail":"{read_only:false; response_revision:8177; number_of_response:1; }","duration":"203.877269ms","start":"2026-04-16T06:23:38.484804Z","end":"2026-04-16T06:23:38.688682Z","steps":["trace[1412041133] 'process raft request' (duration: 203.53797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:23:38.688899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.398508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-16T06:23:38.688948Z","caller":"traceutil/trace.go:171","msg":"trace[1922075741] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:8177; }","duration":"153.48109ms","start":"2026-04-16T06:23:38.535452Z","end":"2026-04-16T06:23:38.688933Z","steps":["trace[1922075741] 'agreement among raft nodes before linearized reading' (duration: 153.261424ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:23:38.934971Z","caller":"traceutil/trace.go:171","msg":"trace[1657473275] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"234.225544ms","start":"2026-04-16T06:23:38.700717Z","end":"2026-04-16T06:23:38.934943Z","steps":["trace[1657473275] 'process raft request' (duration: 234.158402ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:23:38.935226Z","caller":"traceutil/trace.go:171","msg":"trace[626001492] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"237.632417ms","start":"2026-04-16T06:23:38.697581Z","end":"2026-04-16T06:23:38.935213Z","steps":["trace[626001492] 'process raft request' (duration: 163.528823ms)","trace[626001492] 'compare' (duration: 73.623181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:23:39.639127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.460823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-16T06:23:39.639179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.766761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T06:23:39.639258Z","caller":"traceutil/trace.go:171","msg":"trace[106952514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8181; }","duration":"452.756481ms","start":"2026-04-16T06:23:39.186474Z","end":"2026-04-16T06:23:39.639231Z","steps":["trace[106952514] 'range keys from in-memory index tree' (duration: 452.169144ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:23:39.639303Z","caller":"traceutil/trace.go:171","msg":"trace[557882103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8181; }","duration":"452.930985ms","start":"2026-04-16T06:23:39.186336Z","end":"2026-04-16T06:23:39.639267Z","steps":["trace[557882103] 'range keys from in-memory index tree' (duration: 452.473073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:23:39.639292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.643058ms","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-04-16T06:23:39.639323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:23:39.186453Z","time spent":"452.856573ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T06:23:39.639371Z","caller":"traceutil/trace.go:171","msg":"trace[1947854570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8181; }","duration":"452.791741ms","start":"2026-04-16T06:23:39.18656Z","end":"2026-04-16T06:23:39.639351Z","steps":["trace[1947854570] 'range keys from in-memory index tree' (duration: 452.37047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:23:39.63937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:23:39.18632Z","time spent":"453.034838ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T06:23:39.639419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:23:39.186513Z","time spent":"452.897095ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T06:23:39.666368Z","caller":"traceutil/trace.go:171","msg":"trace[591730364] linearizableReadLoop","detail":"{readStateIndex:8542; appliedIndex:8541; }","duration":"446.656605ms","start":"2026-04-16T06:23:39.21968Z","end":"2026-04-16T06:23:39.666337Z","steps":["trace[591730364] 'read index received' (duration: 446.529342ms)","trace[591730364] 'applied index is now lower than readState.Index' (duration: 125.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:23:39.666477Z","caller":"traceutil/trace.go:171","msg":"trace[1160307730] transaction","detail":"{read_only:false; response_revision:8182; number_of_response:1; }","duration":"474.239524ms","start":"2026-04-16T06:23:39.19221Z","end":"2026-04-16T06:23:39.66645Z","steps":["trace[1160307730] 'process raft request' (duration: 473.963777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:23:39.666637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.940982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-77cq5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-16T06:23:39.666686Z","caller":"traceutil/trace.go:171","msg":"trace[1357917178] range","detail":"{range_begin:/registry/pods/openstack/keepalived-77cq5; range_end:; response_count:1; response_revision:8182; }","duration":"447.052755ms","start":"2026-04-16T06:23:39.219621Z","end":"2026-04-16T06:23:39.666674Z","steps":["trace[1357917178] 'agreement among raft nodes before linearized reading' (duration: 446.8515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:23:39.666715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:23:39.2196Z","time spent":"447.108487ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-77cq5\" "} {"level":"warn","ts":"2026-04-16T06:23:39.666635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:23:39.19219Z","time spent":"474.349037ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:23:39.673084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.836297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:23:39.673122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.39594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-16T06:23:39.673125Z","caller":"traceutil/trace.go:171","msg":"trace[950938231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8185; }","duration":"281.911929ms","start":"2026-04-16T06:23:39.391203Z","end":"2026-04-16T06:23:39.673115Z","steps":["trace[950938231] 'agreement among raft nodes before linearized reading' (duration: 281.814287ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:23:39.673158Z","caller":"traceutil/trace.go:171","msg":"trace[179044554] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8185; }","duration":"343.456062ms","start":"2026-04-16T06:23:39.329689Z","end":"2026-04-16T06:23:39.673145Z","steps":["trace[179044554] 'agreement among raft nodes before linearized reading' (duration: 343.36868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:23:39.673181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:23:39.32967Z","time spent":"343.506743ms","remote":"127.0.0.1:42428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-16T06:24:06.9508Z","caller":"traceutil/trace.go:171","msg":"trace[1907826542] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"104.491529ms","start":"2026-04-16T06:24:06.846282Z","end":"2026-04-16T06:24:06.950774Z","steps":["trace[1907826542] 'process raft request' (duration: 104.332864ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:24:20.252343Z","caller":"traceutil/trace.go:171","msg":"trace[196441457] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"113.938006ms","start":"2026-04-16T06:24:20.138337Z","end":"2026-04-16T06:24:20.252275Z","steps":["trace[196441457] 'process raft request' (duration: 95.576427ms)","trace[196441457] 'compare' (duration: 18.240576ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:24:20.744347Z","caller":"traceutil/trace.go:171","msg":"trace[688886683] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"158.000573ms","start":"2026-04-16T06:24:20.586317Z","end":"2026-04-16T06:24:20.744318Z","steps":["trace[688886683] 'process raft request' (duration: 140.148978ms)","trace[688886683] 'compare' (duration: 17.580158ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:24:20.74454Z","caller":"traceutil/trace.go:171","msg":"trace[225307193] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"121.692387ms","start":"2026-04-16T06:24:20.622806Z","end":"2026-04-16T06:24:20.744498Z","steps":["trace[225307193] 'process raft request' (duration: 121.44995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:24:21.62833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.427575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:24:21.628898Z","caller":"traceutil/trace.go:171","msg":"trace[1982597704] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"195.865121ms","start":"2026-04-16T06:24:21.432967Z","end":"2026-04-16T06:24:21.628832Z","steps":["trace[1982597704] 'process raft request' (duration: 68.815479ms)","trace[1982597704] 'compare' (duration: 126.176598ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:25:55.395787Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6591} {"level":"info","ts":"2026-04-16T06:25:55.490048Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6591,"took":"92.042371ms","hash":1652992196,"current-db-size-bytes":61370368,"current-db-size":"61 MB","current-db-size-in-use-bytes":26951680,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-16T06:25:55.490125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1652992196,"revision":6591,"compact-revision":3262} {"level":"info","ts":"2026-04-16T06:25:59.981685Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T06:25:59.990571Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-16T06:25:59.990678Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-16T06:30:55.401651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9522} {"level":"info","ts":"2026-04-16T06:30:55.484583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9522,"took":"80.435122ms","hash":2932402883,"current-db-size-bytes":61370368,"current-db-size":"61 MB","current-db-size-in-use-bytes":25247744,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T06:30:55.484657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2932402883,"revision":9522,"compact-revision":6591}