{"level":"warn","ts":"2026-04-20T08:04:00.225505Z","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-20T08:04:00.225622Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.102:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.102:2380","--initial-cluster=instance=https://199.19.213.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.102: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-20T08:04:00.225712Z","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-20T08:04:00.225728Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-04-20T08:04:00.225754Z","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-20T08:04:00.226681Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"]} {"level":"info","ts":"2026-04-20T08:04:00.226792Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.102: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-20T08:04:00.231337Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.268683ms"} {"level":"info","ts":"2026-04-20T08:04:00.239411Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1ee87564e61d159f","cluster-id":"92a92aec402e97c3"} {"level":"info","ts":"2026-04-20T08:04:00.239469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T08:04:00.239487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 0"} {"level":"info","ts":"2026-04-20T08:04:00.239501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1ee87564e61d159f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T08:04:00.239508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 1"} {"level":"info","ts":"2026-04-20T08:04:00.239544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"warn","ts":"2026-04-20T08:04:00.246516Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T08:04:00.248628Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T08:04:00.250939Z","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-20T08:04:00.253402Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1ee87564e61d159f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T08:04:00.253849Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1ee87564e61d159f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T08:04:00.253909Z","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-20T08:04:00.253975Z","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-20T08:04:00.254003Z","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-20T08:04:00.257686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"info","ts":"2026-04-20T08:04:00.258139Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","added-peer-id":"1ee87564e61d159f","added-peer-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-04-20T08:04:00.259381Z","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-20T08:04:00.25956Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1ee87564e61d159f","initial-advertise-peer-urls":["https://199.19.213.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T08:04:00.259593Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T08:04:00.259698Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-04-20T08:04:00.25971Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-04-20T08:04:00.541059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T08:04:00.54113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T08:04:00.541184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgPreVoteResp from 1ee87564e61d159f at term 1"} {"level":"info","ts":"2026-04-20T08:04:00.541204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became candidate at term 2"} {"level":"info","ts":"2026-04-20T08:04:00.541322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgVoteResp from 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-04-20T08:04:00.54134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became leader at term 2"} {"level":"info","ts":"2026-04-20T08:04:00.541353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1ee87564e61d159f elected leader 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-04-20T08:04:00.599316Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1ee87564e61d159f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.102:2379]}","request-path":"/0/members/1ee87564e61d159f/attributes","cluster-id":"92a92aec402e97c3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T08:04:00.599329Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:04:00.599352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:04:00.599406Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:04:00.601268Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T08:04:00.601317Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T08:04:00.601323Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T08:04:00.602464Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.102:2379"} {"level":"info","ts":"2026-04-20T08:04:00.713906Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:04:00.714087Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:04:00.714211Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:04:24.319721Z","caller":"traceutil/trace.go:171","msg":"trace[1207667095] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"201.2206ms","start":"2026-04-20T08:04:24.118439Z","end":"2026-04-20T08:04:24.31966Z","steps":["trace[1207667095] 'process raft request' (duration: 200.990607ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:24.320896Z","caller":"traceutil/trace.go:171","msg":"trace[1425431289] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:471; }","duration":"192.676471ms","start":"2026-04-20T08:04:24.12819Z","end":"2026-04-20T08:04:24.320867Z","steps":["trace[1425431289] 'read index received' (duration: 191.282914ms)","trace[1425431289] 'applied index is now lower than readState.Index' (duration: 1.392527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:04:24.321106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.884915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:04:24.321245Z","caller":"traceutil/trace.go:171","msg":"trace[1698042671] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"201.549924ms","start":"2026-04-20T08:04:24.119656Z","end":"2026-04-20T08:04:24.321206Z","steps":["trace[1698042671] 'process raft request' (duration: 201.079497ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:24.32128Z","caller":"traceutil/trace.go:171","msg":"trace[1287634348] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:459; }","duration":"193.096827ms","start":"2026-04-20T08:04:24.128166Z","end":"2026-04-20T08:04:24.321263Z","steps":["trace[1287634348] 'agreement among raft nodes before linearized reading' (duration: 192.836423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:30.583877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.94903ms","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-20T08:04:30.584002Z","caller":"traceutil/trace.go:171","msg":"trace[2024723505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"125.091432ms","start":"2026-04-20T08:04:30.45889Z","end":"2026-04-20T08:04:30.583981Z","steps":["trace[2024723505] 'range keys from in-memory index tree' (duration: 124.744098ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:31.051091Z","caller":"traceutil/trace.go:171","msg":"trace[1787561936] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"229.330658ms","start":"2026-04-20T08:04:30.821742Z","end":"2026-04-20T08:04:31.051073Z","steps":["trace[1787561936] 'read index received' (duration: 229.210986ms)","trace[1787561936] 'applied index is now lower than readState.Index' (duration: 118.522µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:04:31.051161Z","caller":"traceutil/trace.go:171","msg":"trace[1627963100] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"359.958088ms","start":"2026-04-20T08:04:30.691155Z","end":"2026-04-20T08:04:31.051114Z","steps":["trace[1627963100] 'process raft request' (duration: 359.796946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:31.051302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.55033ms","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-20T08:04:31.051336Z","caller":"traceutil/trace.go:171","msg":"trace[1023553806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"229.645952ms","start":"2026-04-20T08:04:30.821682Z","end":"2026-04-20T08:04:31.051328Z","steps":["trace[1023553806] 'agreement among raft nodes before linearized reading' (duration: 229.50387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:31.051741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:04:30.69113Z","time spent":"360.13831ms","remote":"127.0.0.1:49888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:06:39.118961Z","caller":"traceutil/trace.go:171","msg":"trace[872352864] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"107.409196ms","start":"2026-04-20T08:06:39.011514Z","end":"2026-04-20T08:06:39.118923Z","steps":["trace[872352864] 'process raft request' (duration: 57.581315ms)","trace[872352864] 'compare' (duration: 49.579409ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:06:53.66333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.420917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:06:53.663511Z","caller":"traceutil/trace.go:171","msg":"trace[590780102] transaction","detail":"{read_only:false; response_revision:1662; number_of_response:1; }","duration":"196.406326ms","start":"2026-04-20T08:06:53.467076Z","end":"2026-04-20T08:06:53.663482Z","steps":["trace[590780102] 'process raft request' (duration: 48.387523ms)","trace[590780102] 'compare' (duration: 147.312027ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:06:53.675439Z","caller":"traceutil/trace.go:171","msg":"trace[2049692410] linearizableReadLoop","detail":"{readStateIndex:1716; appliedIndex:1715; }","duration":"122.545036ms","start":"2026-04-20T08:06:53.552878Z","end":"2026-04-20T08:06:53.675423Z","steps":["trace[2049692410] 'read index received' (duration: 122.384264ms)","trace[2049692410] 'applied index is now lower than readState.Index' (duration: 159.842µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:06:53.675481Z","caller":"traceutil/trace.go:171","msg":"trace[1991606121] transaction","detail":"{read_only:false; response_revision:1663; number_of_response:1; }","duration":"138.260823ms","start":"2026-04-20T08:06:53.537191Z","end":"2026-04-20T08:06:53.675452Z","steps":["trace[1991606121] 'process raft request' (duration: 138.146452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:06:53.675794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.900868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T08:06:53.6759Z","caller":"traceutil/trace.go:171","msg":"trace[1333409947] range","detail":"{range_begin:/registry/masterleases/199.19.213.102; range_end:; response_count:1; response_revision:1663; }","duration":"123.016919ms","start":"2026-04-20T08:06:53.552823Z","end":"2026-04-20T08:06:53.67584Z","steps":["trace[1333409947] 'agreement among raft nodes before linearized reading' (duration: 122.666726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:15.280536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.44663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T08:07:15.280611Z","caller":"traceutil/trace.go:171","msg":"trace[2122337068] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2166; }","duration":"175.567375ms","start":"2026-04-20T08:07:15.105027Z","end":"2026-04-20T08:07:15.280595Z","steps":["trace[2122337068] 'range keys from in-memory index tree' (duration: 175.296926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:15.280849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.201457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-20T08:07:15.280893Z","caller":"traceutil/trace.go:171","msg":"trace[183092310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2166; }","duration":"193.28972ms","start":"2026-04-20T08:07:15.087594Z","end":"2026-04-20T08:07:15.280884Z","steps":["trace[183092310] 'range keys from in-memory index tree' (duration: 193.048791ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:15.281228Z","caller":"traceutil/trace.go:171","msg":"trace[1919021379] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"163.502855ms","start":"2026-04-20T08:07:15.117675Z","end":"2026-04-20T08:07:15.281178Z","steps":["trace[1919021379] 'process raft request' (duration: 108.82141ms)","trace[1919021379] 'compare' (duration: 54.152406ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:15.433332Z","caller":"traceutil/trace.go:171","msg":"trace[554771612] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"175.195414ms","start":"2026-04-20T08:07:15.258116Z","end":"2026-04-20T08:07:15.433312Z","steps":["trace[554771612] 'process raft request' (duration: 134.621639ms)","trace[554771612] 'compare' (duration: 40.43045ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:15.433525Z","caller":"traceutil/trace.go:171","msg":"trace[703504158] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2255; }","duration":"133.905636ms","start":"2026-04-20T08:07:15.29959Z","end":"2026-04-20T08:07:15.433495Z","steps":["trace[703504158] 'read index received' (duration: 93.165856ms)","trace[703504158] 'applied index is now lower than readState.Index' (duration: 40.73746ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:15.433564Z","caller":"traceutil/trace.go:171","msg":"trace[1469317439] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"148.263955ms","start":"2026-04-20T08:07:15.285289Z","end":"2026-04-20T08:07:15.433553Z","steps":["trace[1469317439] 'process raft request' (duration: 147.994536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:15.433791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.195776ms","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-20T08:07:15.43384Z","caller":"traceutil/trace.go:171","msg":"trace[229509431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2169; }","duration":"134.296289ms","start":"2026-04-20T08:07:15.299533Z","end":"2026-04-20T08:07:15.433829Z","steps":["trace[229509431] 'agreement among raft nodes before linearized reading' (duration: 134.069942ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:49.01262Z","caller":"traceutil/trace.go:171","msg":"trace[2138644139] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"109.705286ms","start":"2026-04-20T08:07:48.902889Z","end":"2026-04-20T08:07:49.012594Z","steps":["trace[2138644139] 'process raft request' (duration: 109.449289ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:49.322603Z","caller":"traceutil/trace.go:171","msg":"trace[137566143] linearizableReadLoop","detail":"{readStateIndex:2548; appliedIndex:2547; }","duration":"131.936448ms","start":"2026-04-20T08:07:49.190642Z","end":"2026-04-20T08:07:49.322578Z","steps":["trace[137566143] 'read index received' (duration: 131.719671ms)","trace[137566143] 'applied index is now lower than readState.Index' (duration: 215.917µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:49.322659Z","caller":"traceutil/trace.go:171","msg":"trace[996344554] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"156.543643ms","start":"2026-04-20T08:07:49.166079Z","end":"2026-04-20T08:07:49.322623Z","steps":["trace[996344554] 'process raft request' (duration: 156.356338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:49.322837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.173904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-20T08:07:49.322906Z","caller":"traceutil/trace.go:171","msg":"trace[1307708164] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:2448; }","duration":"132.279027ms","start":"2026-04-20T08:07:49.190607Z","end":"2026-04-20T08:07:49.322886Z","steps":["trace[1307708164] 'agreement among raft nodes before linearized reading' (duration: 132.090232ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:49.488549Z","caller":"traceutil/trace.go:171","msg":"trace[1959544899] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"157.385826ms","start":"2026-04-20T08:07:49.331143Z","end":"2026-04-20T08:07:49.488529Z","steps":["trace[1959544899] 'process raft request' (duration: 157.327315ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:49.488718Z","caller":"traceutil/trace.go:171","msg":"trace[876851350] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"157.967783ms","start":"2026-04-20T08:07:49.330718Z","end":"2026-04-20T08:07:49.488685Z","steps":["trace[876851350] 'process raft request' (duration: 157.689685ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:49.488752Z","caller":"traceutil/trace.go:171","msg":"trace[1511351547] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"158.092046ms","start":"2026-04-20T08:07:49.330647Z","end":"2026-04-20T08:07:49.488739Z","steps":["trace[1511351547] 'process raft request' (duration: 157.718536ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:49.488765Z","caller":"traceutil/trace.go:171","msg":"trace[104737347] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"157.804979ms","start":"2026-04-20T08:07:49.330949Z","end":"2026-04-20T08:07:49.488754Z","steps":["trace[104737347] 'process raft request' (duration: 157.4908ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:49.489034Z","caller":"traceutil/trace.go:171","msg":"trace[339361696] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"158.589521ms","start":"2026-04-20T08:07:49.330415Z","end":"2026-04-20T08:07:49.489005Z","steps":["trace[339361696] 'process raft request' (duration: 66.233437ms)","trace[339361696] 'compare' (duration: 91.461909ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:49.489243Z","caller":"traceutil/trace.go:171","msg":"trace[1068427561] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"158.627402ms","start":"2026-04-20T08:07:49.330577Z","end":"2026-04-20T08:07:49.489204Z","steps":["trace[1068427561] 'process raft request' (duration: 157.733596ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:49.672178Z","caller":"traceutil/trace.go:171","msg":"trace[458549990] linearizableReadLoop","detail":"{readStateIndex:2556; appliedIndex:2555; }","duration":"105.80123ms","start":"2026-04-20T08:07:49.566354Z","end":"2026-04-20T08:07:49.672155Z","steps":["trace[458549990] 'read index received' (duration: 71.20166ms)","trace[458549990] 'applied index is now lower than readState.Index' (duration: 34.59681ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:49.672196Z","caller":"traceutil/trace.go:171","msg":"trace[792617843] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"152.993282ms","start":"2026-04-20T08:07:49.519183Z","end":"2026-04-20T08:07:49.672176Z","steps":["trace[792617843] 'process raft request' (duration: 118.440243ms)","trace[792617843] 'compare' (duration: 34.420955ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:49.672326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.967535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T08:07:49.672361Z","caller":"traceutil/trace.go:171","msg":"trace[839707835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2456; }","duration":"106.036847ms","start":"2026-04-20T08:07:49.566314Z","end":"2026-04-20T08:07:49.672351Z","steps":["trace[839707835] 'agreement among raft nodes before linearized reading' (duration: 105.913673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:49.672387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.060289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:07:49.67244Z","caller":"traceutil/trace.go:171","msg":"trace[1589361264] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2456; }","duration":"104.136002ms","start":"2026-04-20T08:07:49.568292Z","end":"2026-04-20T08:07:49.672428Z","steps":["trace[1589361264] 'agreement among raft nodes before linearized reading' (duration: 104.053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:49.973157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.608327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:07:49.973303Z","caller":"traceutil/trace.go:171","msg":"trace[2096087611] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"164.721726ms","start":"2026-04-20T08:07:49.808571Z","end":"2026-04-20T08:07:49.973293Z","steps":["trace[2096087611] 'process raft request' (duration: 164.667335ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:49.973298Z","caller":"traceutil/trace.go:171","msg":"trace[1738139606] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"221.596076ms","start":"2026-04-20T08:07:49.751679Z","end":"2026-04-20T08:07:49.973275Z","steps":["trace[1738139606] 'process raft request' (duration: 88.812903ms)","trace[1738139606] 'compare' (duration: 132.498795ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:49.973318Z","caller":"traceutil/trace.go:171","msg":"trace[887343791] linearizableReadLoop","detail":"{readStateIndex:2560; appliedIndex:2559; }","duration":"214.991586ms","start":"2026-04-20T08:07:49.758296Z","end":"2026-04-20T08:07:49.973288Z","steps":["trace[887343791] 'read index received' (duration: 82.140712ms)","trace[887343791] 'applied index is now lower than readState.Index' (duration: 132.847784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:49.973554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.239093ms","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-20T08:07:49.973584Z","caller":"traceutil/trace.go:171","msg":"trace[1717139769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2461; }","duration":"215.320165ms","start":"2026-04-20T08:07:49.758256Z","end":"2026-04-20T08:07:49.973576Z","steps":["trace[1717139769] 'agreement among raft nodes before linearized reading' (duration: 215.13196ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.097276Z","caller":"traceutil/trace.go:171","msg":"trace[1292240623] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"120.155948ms","start":"2026-04-20T08:07:49.977092Z","end":"2026-04-20T08:07:50.097248Z","steps":["trace[1292240623] 'process raft request' (duration: 103.038631ms)","trace[1292240623] 'compare' (duration: 17.007774ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:50.324266Z","caller":"traceutil/trace.go:171","msg":"trace[1572527624] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"139.256079ms","start":"2026-04-20T08:07:50.184979Z","end":"2026-04-20T08:07:50.324235Z","steps":["trace[1572527624] 'process raft request' (duration: 113.055792ms)","trace[1572527624] 'compare' (duration: 25.999811ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:50.701325Z","caller":"traceutil/trace.go:171","msg":"trace[566596637] transaction","detail":"{read_only:false; response_revision:2467; number_of_response:1; }","duration":"229.686875ms","start":"2026-04-20T08:07:50.471616Z","end":"2026-04-20T08:07:50.701303Z","steps":["trace[566596637] 'process raft request' (duration: 229.566072ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.813674Z","caller":"traceutil/trace.go:171","msg":"trace[1609187946] linearizableReadLoop","detail":"{readStateIndex:2569; appliedIndex:2566; }","duration":"134.205404ms","start":"2026-04-20T08:07:50.679432Z","end":"2026-04-20T08:07:50.813637Z","steps":["trace[1609187946] 'read index received' (duration: 21.850852ms)","trace[1609187946] 'applied index is now lower than readState.Index' (duration: 112.353172ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:50.813721Z","caller":"traceutil/trace.go:171","msg":"trace[1759776465] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"291.722273ms","start":"2026-04-20T08:07:50.521946Z","end":"2026-04-20T08:07:50.813668Z","steps":["trace[1759776465] 'process raft request' (duration: 289.924502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:50.81422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.618806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T08:07:50.814516Z","caller":"traceutil/trace.go:171","msg":"trace[230373071] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2469; }","duration":"135.08061ms","start":"2026-04-20T08:07:50.679382Z","end":"2026-04-20T08:07:50.814463Z","steps":["trace[230373071] 'agreement among raft nodes before linearized reading' (duration: 134.39576ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.814221Z","caller":"traceutil/trace.go:171","msg":"trace[1898695594] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"190.787468ms","start":"2026-04-20T08:07:50.623385Z","end":"2026-04-20T08:07:50.814172Z","steps":["trace[1898695594] 'process raft request' (duration: 190.16298ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:05.675326Z","caller":"traceutil/trace.go:171","msg":"trace[370495640] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"144.289221ms","start":"2026-04-20T08:13:05.531008Z","end":"2026-04-20T08:13:05.675297Z","steps":["trace[370495640] 'process raft request' (duration: 116.010994ms)","trace[370495640] 'compare' (duration: 28.128325ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:00.655347Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2857} {"level":"info","ts":"2026-04-20T08:14:00.766822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2857,"took":"109.251074ms","hash":1892256057,"current-db-size-bytes":53678080,"current-db-size":"54 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-20T08:14:00.767138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1892256057,"revision":2857,"compact-revision":-1} {"level":"info","ts":"2026-04-20T08:16:56.266147Z","caller":"traceutil/trace.go:171","msg":"trace[159434795] transaction","detail":"{read_only:false; response_revision:6875; number_of_response:1; }","duration":"255.878561ms","start":"2026-04-20T08:16:56.010242Z","end":"2026-04-20T08:16:56.266121Z","steps":["trace[159434795] 'process raft request' (duration: 255.711146ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:57.133097Z","caller":"traceutil/trace.go:171","msg":"trace[684581904] transaction","detail":"{read_only:false; response_revision:6879; number_of_response:1; }","duration":"224.535744ms","start":"2026-04-20T08:16:56.908525Z","end":"2026-04-20T08:16:57.133061Z","steps":["trace[684581904] 'process raft request' (duration: 224.303397ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:57.19531Z","caller":"traceutil/trace.go:171","msg":"trace[183783485] linearizableReadLoop","detail":"{readStateIndex:7132; appliedIndex:7130; }","duration":"221.050251ms","start":"2026-04-20T08:16:56.974232Z","end":"2026-04-20T08:16:57.195283Z","steps":["trace[183783485] 'read index received' (duration: 158.551647ms)","trace[183783485] 'applied index is now lower than readState.Index' (duration: 62.497404ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:57.19542Z","caller":"traceutil/trace.go:171","msg":"trace[1771651784] transaction","detail":"{read_only:false; response_revision:6880; number_of_response:1; }","duration":"239.875758ms","start":"2026-04-20T08:16:56.955478Z","end":"2026-04-20T08:16:57.195353Z","steps":["trace[1771651784] 'process raft request' (duration: 239.702613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:57.195628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.928321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:16:57.195726Z","caller":"traceutil/trace.go:171","msg":"trace[1695318911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6880; }","duration":"207.116036ms","start":"2026-04-20T08:16:56.988595Z","end":"2026-04-20T08:16:57.195711Z","steps":["trace[1695318911] 'agreement among raft nodes before linearized reading' (duration: 206.91419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:57.197143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.865329ms","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-20T08:16:57.197302Z","caller":"traceutil/trace.go:171","msg":"trace[1269753940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6880; }","duration":"223.056975ms","start":"2026-04-20T08:16:56.974187Z","end":"2026-04-20T08:16:57.197244Z","steps":["trace[1269753940] 'agreement among raft nodes before linearized reading' (duration: 221.185326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:57.198815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.229531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:28 size:604173"} {"level":"info","ts":"2026-04-20T08:16:57.198884Z","caller":"traceutil/trace.go:171","msg":"trace[1158406621] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:28; response_revision:6880; }","duration":"191.367625ms","start":"2026-04-20T08:16:57.0075Z","end":"2026-04-20T08:16:57.198867Z","steps":["trace[1158406621] 'agreement among raft nodes before linearized reading' (duration: 188.61922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:57.608127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.032799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:16:57.608612Z","caller":"traceutil/trace.go:171","msg":"trace[1084172622] linearizableReadLoop","detail":"{readStateIndex:7134; appliedIndex:7132; }","duration":"232.012269ms","start":"2026-04-20T08:16:57.376583Z","end":"2026-04-20T08:16:57.608595Z","steps":["trace[1084172622] 'read index received' (duration: 78.892µs)","trace[1084172622] 'applied index is now lower than readState.Index' (duration: 231.932017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:16:57.609023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.839826ms","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-20T08:16:57.609084Z","caller":"traceutil/trace.go:171","msg":"trace[379601454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6882; }","duration":"213.942149ms","start":"2026-04-20T08:16:57.395127Z","end":"2026-04-20T08:16:57.609069Z","steps":["trace[379601454] 'agreement among raft nodes before linearized reading' (duration: 213.772733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:57.609519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.921668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:28 size:604173"} {"level":"info","ts":"2026-04-20T08:16:57.609603Z","caller":"traceutil/trace.go:171","msg":"trace[750801781] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:28; response_revision:6882; }","duration":"233.071853ms","start":"2026-04-20T08:16:57.376512Z","end":"2026-04-20T08:16:57.609584Z","steps":["trace[750801781] 'agreement among raft nodes before linearized reading' (duration: 232.30996ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:57.609776Z","caller":"traceutil/trace.go:171","msg":"trace[1820383444] transaction","detail":"{read_only:false; response_revision:6881; number_of_response:1; }","duration":"408.315307ms","start":"2026-04-20T08:16:57.201449Z","end":"2026-04-20T08:16:57.609765Z","steps":["trace[1820383444] 'process raft request' (duration: 33.502992ms)","trace[1820383444] 'compare' (duration: 371.76412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:16:57.609857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:57.201423Z","time spent":"408.40565ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:16:57.610018Z","caller":"traceutil/trace.go:171","msg":"trace[665389657] transaction","detail":"{read_only:false; response_revision:6882; number_of_response:1; }","duration":"407.557293ms","start":"2026-04-20T08:16:57.20245Z","end":"2026-04-20T08:16:57.610007Z","steps":["trace[665389657] 'process raft request' (duration: 406.009345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:57.610152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:57.202434Z","time spent":"407.616286ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:17:00.103132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137349210287360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:00.603321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137349210287360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:01.103998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137349210287360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:01.604178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137349210287360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:01.988157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000455013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T08:17:01.988264Z","caller":"traceutil/trace.go:171","msg":"trace[643116831] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000622698s","start":"2026-04-20T08:16:59.987616Z","end":"2026-04-20T08:17:01.988239Z","steps":["trace[643116831] 'agreement among raft nodes before linearized reading' (duration: 2.000498315s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:01.988317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:59.987589Z","time spent":"2.00071759s","remote":"127.0.0.1:49676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:17:02.104995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137349210287360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:02.605964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137349210287360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:03.106185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137349210287360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:03.558583Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.418883035s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T08:17:03.559264Z","caller":"traceutil/trace.go:171","msg":"trace[185060706] transaction","detail":"{read_only:false; response_revision:6893; number_of_response:1; }","duration":"4.419660789s","start":"2026-04-20T08:16:59.139581Z","end":"2026-04-20T08:17:03.559242Z","steps":["trace[185060706] 'process raft request' (duration: 4.419477974s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.559463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:59.139567Z","time spent":"4.419760983s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:03.576836Z","caller":"traceutil/trace.go:171","msg":"trace[1643169648] linearizableReadLoop","detail":"{readStateIndex:7151; appliedIndex:7145; }","duration":"3.974297576s","start":"2026-04-20T08:16:59.602511Z","end":"2026-04-20T08:17:03.576809Z","steps":["trace[1643169648] 'read index received' (duration: 3.95654225s)","trace[1643169648] 'applied index is now lower than readState.Index' (duration: 17.754436ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:17:03.577151Z","caller":"traceutil/trace.go:171","msg":"trace[1148011790] transaction","detail":"{read_only:false; response_revision:6898; number_of_response:1; }","duration":"1.723424364s","start":"2026-04-20T08:17:01.8537Z","end":"2026-04-20T08:17:03.577125Z","steps":["trace[1148011790] 'process raft request' (duration: 1.723074034s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.577266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:01.853671Z","time spent":"1.723533838s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:03.577283Z","caller":"traceutil/trace.go:171","msg":"trace[136862] transaction","detail":"{read_only:false; response_revision:6895; number_of_response:1; }","duration":"3.19356266s","start":"2026-04-20T08:17:00.383711Z","end":"2026-04-20T08:17:03.577273Z","steps":["trace[136862] 'process raft request' (duration: 3.192962202s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:03.577304Z","caller":"traceutil/trace.go:171","msg":"trace[1901446374] transaction","detail":"{read_only:false; response_revision:6897; number_of_response:1; }","duration":"2.509893287s","start":"2026-04-20T08:17:01.067399Z","end":"2026-04-20T08:17:03.577292Z","steps":["trace[1901446374] 'process raft request' (duration: 2.50931291s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.577402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:01.067376Z","time spent":"2.509959389s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:03.577126Z","caller":"traceutil/trace.go:171","msg":"trace[108822227] transaction","detail":"{read_only:false; response_revision:6896; number_of_response:1; }","duration":"2.793013626s","start":"2026-04-20T08:17:00.784001Z","end":"2026-04-20T08:17:03.577015Z","steps":["trace[108822227] 'process raft request' (duration: 2.792692346s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.5775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:00.783983Z","time spent":"2.79349317s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:17:03.577836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.974747789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:17:03.577911Z","caller":"traceutil/trace.go:171","msg":"trace[1811648179] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:6898; }","duration":"3.975403279s","start":"2026-04-20T08:16:59.602456Z","end":"2026-04-20T08:17:03.577859Z","steps":["trace[1811648179] 'agreement among raft nodes before linearized reading' (duration: 3.974713568s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.577936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:59.602434Z","time spent":"3.975494602s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":95,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:03.578495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:00.383694Z","time spent":"3.194308422s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:03.577202Z","caller":"traceutil/trace.go:171","msg":"trace[1531029832] transaction","detail":"{read_only:false; response_revision:6894; number_of_response:1; }","duration":"3.248011472s","start":"2026-04-20T08:17:00.329185Z","end":"2026-04-20T08:17:03.577196Z","steps":["trace[1531029832] 'process raft request' (duration: 3.247372613s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.578962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:00.329161Z","time spent":"3.249775657s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:17:03.615566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108127561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:03.615646Z","caller":"traceutil/trace.go:171","msg":"trace[751956248] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:6899; }","duration":"1.108286586s","start":"2026-04-20T08:17:02.507343Z","end":"2026-04-20T08:17:03.615629Z","steps":["trace[751956248] 'agreement among raft nodes before linearized reading' (duration: 1.108142492s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.615679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:02.507316Z","time spent":"1.108354138s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:03.615904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.928210697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:17:03.615999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627208141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:03.616045Z","caller":"traceutil/trace.go:171","msg":"trace[1914682875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6899; }","duration":"1.627270053s","start":"2026-04-20T08:17:01.988756Z","end":"2026-04-20T08:17:03.616026Z","steps":["trace[1914682875] 'agreement among raft nodes before linearized reading' (duration: 1.627226422s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.616078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.999517735s","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-20T08:17:03.616099Z","caller":"traceutil/trace.go:171","msg":"trace[491100711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6899; }","duration":"3.999556097s","start":"2026-04-20T08:16:59.616537Z","end":"2026-04-20T08:17:03.616093Z","steps":["trace[491100711] 'agreement among raft nodes before linearized reading' (duration: 3.999445324s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.616101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:01.988738Z","time spent":"1.627356215s","remote":"127.0.0.1:49672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:17:03.61612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:59.616528Z","time spent":"3.999588928s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T08:17:03.616057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34912074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:03.616201Z","caller":"traceutil/trace.go:171","msg":"trace[661759752] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:6899; }","duration":"2.928352572s","start":"2026-04-20T08:17:00.687634Z","end":"2026-04-20T08:17:03.615987Z","steps":["trace[661759752] 'agreement among raft nodes before linearized reading' (duration: 2.928019452s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.616296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:00.687622Z","time spent":"2.928664461s","remote":"127.0.0.1:45408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T08:17:03.61634Z","caller":"traceutil/trace.go:171","msg":"trace[2137523353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6899; }","duration":"1.349348087s","start":"2026-04-20T08:17:02.266873Z","end":"2026-04-20T08:17:03.616221Z","steps":["trace[2137523353] 'agreement among raft nodes before linearized reading' (duration: 1.349143131s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.616353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.931249621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-04-20T08:17:03.616371Z","caller":"traceutil/trace.go:171","msg":"trace[1148831898] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6899; }","duration":"2.931305182s","start":"2026-04-20T08:17:00.685061Z","end":"2026-04-20T08:17:03.616367Z","steps":["trace[1148831898] 'agreement among raft nodes before linearized reading' (duration: 2.93122654s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.616373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:02.266844Z","time spent":"1.349522491s","remote":"127.0.0.1:49676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:17:03.61639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:00.685046Z","time spent":"2.931341364s","remote":"127.0.0.1:49836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T08:17:03.616546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.688205779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-20T08:17:03.616546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.849811327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:03.616565Z","caller":"traceutil/trace.go:171","msg":"trace[354268310] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6899; }","duration":"3.688293062s","start":"2026-04-20T08:16:59.928266Z","end":"2026-04-20T08:17:03.61656Z","steps":["trace[354268310] 'agreement among raft nodes before linearized reading' (duration: 3.68821117s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:03.616573Z","caller":"traceutil/trace.go:171","msg":"trace[802112628] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:6899; }","duration":"1.84990703s","start":"2026-04-20T08:17:01.766657Z","end":"2026-04-20T08:17:03.616564Z","steps":["trace[802112628] 'agreement among raft nodes before linearized reading' (duration: 1.849851129s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.615992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.643676711s","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-20T08:17:03.616612Z","caller":"traceutil/trace.go:171","msg":"trace[325885094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6899; }","duration":"2.644341321s","start":"2026-04-20T08:17:00.972263Z","end":"2026-04-20T08:17:03.616605Z","steps":["trace[325885094] 'agreement among raft nodes before linearized reading' (duration: 2.643618129s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.616619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.954828301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-20T08:17:03.61663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:00.972248Z","time spent":"2.644376472s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T08:17:03.615929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.318651263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:17:03.616633Z","caller":"traceutil/trace.go:171","msg":"trace[849666681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6899; }","duration":"3.954905703s","start":"2026-04-20T08:16:59.661724Z","end":"2026-04-20T08:17:03.61663Z","steps":["trace[849666681] 'agreement among raft nodes before linearized reading' (duration: 3.954861412s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.616934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:59.661704Z","time spent":"3.955224313s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T08:17:03.617001Z","caller":"traceutil/trace.go:171","msg":"trace[1768220484] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:6899; }","duration":"2.319708584s","start":"2026-04-20T08:17:01.297184Z","end":"2026-04-20T08:17:03.616892Z","steps":["trace[1768220484] 'agreement among raft nodes before linearized reading' (duration: 2.318641872s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.61705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:01.297157Z","time spent":"2.319865079s","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:03.615936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.273266972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:17:03.61717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.001966251s","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-20T08:17:03.617242Z","caller":"traceutil/trace.go:171","msg":"trace[499760572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6899; }","duration":"4.002103024s","start":"2026-04-20T08:16:59.615124Z","end":"2026-04-20T08:17:03.617227Z","steps":["trace[499760572] 'agreement among raft nodes before linearized reading' (duration: 4.001566238s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.617355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:59.615114Z","time spent":"4.002232549s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T08:17:03.618161Z","caller":"traceutil/trace.go:171","msg":"trace[257901217] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:6899; }","duration":"2.27451638s","start":"2026-04-20T08:17:01.3426Z","end":"2026-04-20T08:17:03.617116Z","steps":["trace[257901217] 'agreement among raft nodes before linearized reading' (duration: 2.273249612s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.618235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:01.342589Z","time spent":"2.275626945s","remote":"127.0.0.1:43098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:03.616577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:59.928244Z","time spent":"3.688329774s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-20T08:17:03.616594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:01.766624Z","time spent":"1.849964192s","remote":"127.0.0.1:45238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:03.619645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.032487794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:03.619738Z","caller":"traceutil/trace.go:171","msg":"trace[97528458] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:6899; }","duration":"2.036376464s","start":"2026-04-20T08:17:01.583295Z","end":"2026-04-20T08:17:03.619672Z","steps":["trace[97528458] 'agreement among raft nodes before linearized reading' (duration: 2.032506825s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:03.61976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:01.583259Z","time spent":"2.036494637s","remote":"127.0.0.1:45274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-04-20T08:17:29.934459Z","caller":"traceutil/trace.go:171","msg":"trace[553197868] linearizableReadLoop","detail":"{readStateIndex:7307; appliedIndex:7304; }","duration":"243.17761ms","start":"2026-04-20T08:17:29.691257Z","end":"2026-04-20T08:17:29.934435Z","steps":["trace[553197868] 'read index received' (duration: 7.350253ms)","trace[553197868] 'applied index is now lower than readState.Index' (duration: 235.826527ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:17:29.934751Z","caller":"traceutil/trace.go:171","msg":"trace[1229765225] transaction","detail":"{read_only:false; response_revision:7048; number_of_response:1; }","duration":"248.060042ms","start":"2026-04-20T08:17:29.686683Z","end":"2026-04-20T08:17:29.934743Z","steps":["trace[1229765225] 'process raft request' (duration: 247.686111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:29.934747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.458978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1159"} {"level":"info","ts":"2026-04-20T08:17:29.934824Z","caller":"traceutil/trace.go:171","msg":"trace[366920040] range","detail":"{range_begin:/registry/rolebindings/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:7049; }","duration":"243.582002ms","start":"2026-04-20T08:17:29.691226Z","end":"2026-04-20T08:17:29.934808Z","steps":["trace[366920040] 'agreement among raft nodes before linearized reading' (duration: 243.410847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:29.934841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.904628ms","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-20T08:17:29.934896Z","caller":"traceutil/trace.go:171","msg":"trace[632989630] transaction","detail":"{read_only:false; response_revision:7047; number_of_response:1; }","duration":"248.269758ms","start":"2026-04-20T08:17:29.686618Z","end":"2026-04-20T08:17:29.934888Z","steps":["trace[632989630] 'process raft request' (duration: 238.316788ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:29.93493Z","caller":"traceutil/trace.go:171","msg":"trace[147192565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7049; }","duration":"236.088963ms","start":"2026-04-20T08:17:29.698819Z","end":"2026-04-20T08:17:29.934908Z","steps":["trace[147192565] 'agreement among raft nodes before linearized reading' (duration: 235.856297ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:29.935028Z","caller":"traceutil/trace.go:171","msg":"trace[729334198] transaction","detail":"{read_only:false; response_revision:7049; number_of_response:1; }","duration":"237.419003ms","start":"2026-04-20T08:17:29.697599Z","end":"2026-04-20T08:17:29.935018Z","steps":["trace[729334198] 'process raft request' (duration: 236.804705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:29.935116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.033113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1478"} {"level":"info","ts":"2026-04-20T08:17:29.935133Z","caller":"traceutil/trace.go:171","msg":"trace[1144622682] range","detail":"{range_begin:/registry/roles/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:7049; }","duration":"170.061494ms","start":"2026-04-20T08:17:29.765066Z","end":"2026-04-20T08:17:29.935128Z","steps":["trace[1144622682] 'agreement among raft nodes before linearized reading' (duration: 170.022663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:29.935258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.768474ms","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-20T08:17:29.935287Z","caller":"traceutil/trace.go:171","msg":"trace[191020098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7049; }","duration":"170.825836ms","start":"2026-04-20T08:17:29.764454Z","end":"2026-04-20T08:17:29.935279Z","steps":["trace[191020098] 'agreement among raft nodes before linearized reading' (duration: 170.702473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:29.935366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.537193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/local-path-storage/\" range_end:\"/registry/persistentvolumeclaims/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:29.935383Z","caller":"traceutil/trace.go:171","msg":"trace[1205598574] range","detail":"{range_begin:/registry/persistentvolumeclaims/local-path-storage/; range_end:/registry/persistentvolumeclaims/local-path-storage0; response_count:0; response_revision:7049; }","duration":"174.574405ms","start":"2026-04-20T08:17:29.760802Z","end":"2026-04-20T08:17:29.935377Z","steps":["trace[1205598574] 'agreement among raft nodes before linearized reading' (duration: 174.535394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:29.935508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.885992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T08:17:29.935525Z","caller":"traceutil/trace.go:171","msg":"trace[1993566217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7049; }","duration":"176.942143ms","start":"2026-04-20T08:17:29.758578Z","end":"2026-04-20T08:17:29.93552Z","steps":["trace[1993566217] 'agreement among raft nodes before linearized reading' (duration: 176.884771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:29.935533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.523305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" limit:500 ","response":"range_response_count:23 size:498449"} {"level":"info","ts":"2026-04-20T08:17:29.93557Z","caller":"traceutil/trace.go:171","msg":"trace[745119340] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:7049; }","duration":"118.581477ms","start":"2026-04-20T08:17:29.81698Z","end":"2026-04-20T08:17:29.935562Z","steps":["trace[745119340] 'agreement among raft nodes before linearized reading' (duration: 118.241617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:29.935693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.773776ms","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-20T08:17:29.935725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.8543ms","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-20T08:17:29.935728Z","caller":"traceutil/trace.go:171","msg":"trace[106771275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7049; }","duration":"158.833158ms","start":"2026-04-20T08:17:29.776885Z","end":"2026-04-20T08:17:29.935718Z","steps":["trace[106771275] 'agreement among raft nodes before linearized reading' (duration: 158.737145ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:29.935748Z","caller":"traceutil/trace.go:171","msg":"trace[1235497213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7049; }","duration":"166.908151ms","start":"2026-04-20T08:17:29.768834Z","end":"2026-04-20T08:17:29.935742Z","steps":["trace[1235497213] 'agreement among raft nodes before linearized reading' (duration: 166.830849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:29.935883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.328723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:29.935914Z","caller":"traceutil/trace.go:171","msg":"trace[1733207823] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:7049; }","duration":"157.379515ms","start":"2026-04-20T08:17:29.778523Z","end":"2026-04-20T08:17:29.935902Z","steps":["trace[1733207823] 'agreement among raft nodes before linearized reading' (duration: 157.327354ms)"],"step_count":1}