{"level":"warn","ts":"2026-04-11T17:41:51.576114Z","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-11T17:41:51.576246Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.244: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.244:2380","--initial-cluster=instance=https://199.19.213.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.244: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-11T17:41:51.576341Z","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-11T17:41:51.576353Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-04-11T17:41:51.576383Z","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-11T17:41:51.580718Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"]} {"level":"info","ts":"2026-04-11T17:41:51.580844Z","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.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.244: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-11T17:41:51.588623Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.502505ms"} {"level":"info","ts":"2026-04-11T17:41:51.601767Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95a1d32286abc938","cluster-id":"f9243fb70f4df4f4"} {"level":"info","ts":"2026-04-11T17:41:51.602089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:41:51.602116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 0"} {"level":"info","ts":"2026-04-11T17:41:51.602131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95a1d32286abc938 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:41:51.60214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 1"} {"level":"info","ts":"2026-04-11T17:41:51.602185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"warn","ts":"2026-04-11T17:41:51.609787Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:41:51.611288Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:41:51.61229Z","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-11T17:41:51.614433Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95a1d32286abc938","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:41:51.615729Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95a1d32286abc938","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:41:51.616043Z","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-11T17:41:51.61608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"info","ts":"2026-04-11T17:41:51.616131Z","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-11T17:41:51.616225Z","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-11T17:41:51.616393Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","added-peer-id":"95a1d32286abc938","added-peer-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-04-11T17:41:51.621368Z","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-11T17:41:51.621536Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-04-11T17:41:51.621594Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-04-11T17:41:51.622116Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95a1d32286abc938","initial-advertise-peer-urls":["https://199.19.213.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:41:51.622163Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:41:51.702658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:41:51.702692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:41:51.702722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgPreVoteResp from 95a1d32286abc938 at term 1"} {"level":"info","ts":"2026-04-11T17:41:51.702828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:41:51.702838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgVoteResp from 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-04-11T17:41:51.702849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became leader at term 2"} {"level":"info","ts":"2026-04-11T17:41:51.702872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95a1d32286abc938 elected leader 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-04-11T17:41:51.703821Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:41:51.705041Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95a1d32286abc938","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.244:2379]}","request-path":"/0/members/95a1d32286abc938/attributes","cluster-id":"f9243fb70f4df4f4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:41:51.705142Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:41:51.705513Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:41:51.705581Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:41:51.705256Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:41:51.705792Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:41:51.705205Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:41:51.705839Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:41:51.709248Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.244:2379"} {"level":"info","ts":"2026-04-11T17:41:51.709872Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:42:23.139559Z","caller":"traceutil/trace.go:171","msg":"trace[878669046] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"320.352933ms","start":"2026-04-11T17:42:22.819171Z","end":"2026-04-11T17:42:23.139525Z","steps":["trace[878669046] 'process raft request' (duration: 259.131129ms)","trace[878669046] 'compare' (duration: 60.683181ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:23.140023Z","caller":"traceutil/trace.go:171","msg":"trace[1416855821] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"113.053198ms","start":"2026-04-11T17:42:23.026923Z","end":"2026-04-11T17:42:23.139976Z","steps":["trace[1416855821] 'process raft request' (duration: 112.508636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:23.140051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:22.81914Z","time spent":"320.506757ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:42:28.313561Z","caller":"traceutil/trace.go:171","msg":"trace[1999259408] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"158.237887ms","start":"2026-04-11T17:42:28.155293Z","end":"2026-04-11T17:42:28.313531Z","steps":["trace[1999259408] 'process raft request' (duration: 157.673054ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:28.503083Z","caller":"traceutil/trace.go:171","msg":"trace[163168405] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"132.572396ms","start":"2026-04-11T17:42:28.370472Z","end":"2026-04-11T17:42:28.503044Z","steps":["trace[163168405] 'process raft request' (duration: 132.510525ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:28.503118Z","caller":"traceutil/trace.go:171","msg":"trace[2002887503] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"167.524625ms","start":"2026-04-11T17:42:28.335535Z","end":"2026-04-11T17:42:28.50306Z","steps":["trace[2002887503] 'process raft request' (duration: 78.463898ms)","trace[2002887503] 'compare' (duration: 88.737939ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:28.949188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.602266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vkp96\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-11T17:42:28.949408Z","caller":"traceutil/trace.go:171","msg":"trace[1522035007] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vkp96; range_end:; response_count:1; response_revision:618; }","duration":"307.872522ms","start":"2026-04-11T17:42:28.64151Z","end":"2026-04-11T17:42:28.949382Z","steps":["trace[1522035007] 'range keys from in-memory index tree' (duration: 307.486293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:28.94949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:28.641487Z","time spent":"307.989465ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-vkp96\" "} {"level":"warn","ts":"2026-04-11T17:42:28.949286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.371342ms","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-11T17:42:28.949796Z","caller":"traceutil/trace.go:171","msg":"trace[15895534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"217.915614ms","start":"2026-04-11T17:42:28.731865Z","end":"2026-04-11T17:42:28.949781Z","steps":["trace[15895534] 'range keys from in-memory index tree' (duration: 217.200408ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:29.130843Z","caller":"traceutil/trace.go:171","msg":"trace[1510891079] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"230.083311ms","start":"2026-04-11T17:42:28.900735Z","end":"2026-04-11T17:42:29.130818Z","steps":["trace[1510891079] 'process raft request' (duration: 229.558318ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:29.139453Z","caller":"traceutil/trace.go:171","msg":"trace[743734833] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"185.564887ms","start":"2026-04-11T17:42:28.953839Z","end":"2026-04-11T17:42:29.139404Z","steps":["trace[743734833] 'process raft request' (duration: 185.419653ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:29.139685Z","caller":"traceutil/trace.go:171","msg":"trace[729638321] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"177.215972ms","start":"2026-04-11T17:42:28.962445Z","end":"2026-04-11T17:42:29.139661Z","steps":["trace[729638321] 'process raft request' (duration: 176.911875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:44.635696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.491044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:42:44.635834Z","caller":"traceutil/trace.go:171","msg":"trace[1303431026] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"230.472398ms","start":"2026-04-11T17:42:44.40534Z","end":"2026-04-11T17:42:44.635812Z","steps":["trace[1303431026] 'read index received' (duration: 85.476162ms)","trace[1303431026] 'applied index is now lower than readState.Index' (duration: 144.994675ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:44.635882Z","caller":"traceutil/trace.go:171","msg":"trace[1930622742] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"252.088869ms","start":"2026-04-11T17:42:44.383746Z","end":"2026-04-11T17:42:44.635834Z","steps":["trace[1930622742] 'process raft request' (duration: 107.160405ms)","trace[1930622742] 'compare' (duration: 144.133116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:44.636138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.775304ms","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-11T17:42:44.636256Z","caller":"traceutil/trace.go:171","msg":"trace[1143503124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"230.951918ms","start":"2026-04-11T17:42:44.405278Z","end":"2026-04-11T17:42:44.63623Z","steps":["trace[1143503124] 'agreement among raft nodes before linearized reading' (duration: 230.656931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:13.036237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.837018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-11T17:43:13.03633Z","caller":"traceutil/trace.go:171","msg":"trace[830875540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1175; }","duration":"223.994202ms","start":"2026-04-11T17:43:12.812319Z","end":"2026-04-11T17:43:13.036313Z","steps":["trace[830875540] 'range keys from in-memory index tree' (duration: 223.669825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:13.03648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.200814ms","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-11T17:43:13.036561Z","caller":"traceutil/trace.go:171","msg":"trace[210162613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1175; }","duration":"220.324247ms","start":"2026-04-11T17:43:12.816218Z","end":"2026-04-11T17:43:13.036542Z","steps":["trace[210162613] 'range keys from in-memory index tree' (duration: 220.01994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:01.502477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.961107ms","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-11T17:44:01.50259Z","caller":"traceutil/trace.go:171","msg":"trace[1337427355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1852; }","duration":"147.110289ms","start":"2026-04-11T17:44:01.355458Z","end":"2026-04-11T17:44:01.502569Z","steps":["trace[1337427355] 'range keys from in-memory index tree' (duration: 146.736342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:01.502662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.87797ms","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-11T17:44:01.502745Z","caller":"traceutil/trace.go:171","msg":"trace[1894879593] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"110.992302ms","start":"2026-04-11T17:44:01.391731Z","end":"2026-04-11T17:44:01.502723Z","steps":["trace[1894879593] 'range keys from in-memory index tree' (duration: 110.776777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:01.503327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.762481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-11T17:44:01.503466Z","caller":"traceutil/trace.go:171","msg":"trace[311919699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"200.005467ms","start":"2026-04-11T17:44:01.303431Z","end":"2026-04-11T17:44:01.503436Z","steps":["trace[311919699] 'range keys from in-memory index tree' (duration: 199.443783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:02.08033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.536125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-11T17:44:02.080421Z","caller":"traceutil/trace.go:171","msg":"trace[140774923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1854; }","duration":"413.704938ms","start":"2026-04-11T17:44:01.666695Z","end":"2026-04-11T17:44:02.0804Z","steps":["trace[140774923] 'range keys from in-memory index tree' (duration: 413.256149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:02.080476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:01.666664Z","time spent":"413.796311ms","remote":"127.0.0.1:57850","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-11T17:44:02.081136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.46164ms","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-11T17:44:02.081197Z","caller":"traceutil/trace.go:171","msg":"trace[635121902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1854; }","duration":"453.545083ms","start":"2026-04-11T17:44:01.627638Z","end":"2026-04-11T17:44:02.081183Z","steps":["trace[635121902] 'range keys from in-memory index tree' (duration: 452.961608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:02.081231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:01.627622Z","time spent":"453.600944ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:44:02.081357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.018191ms","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-11T17:44:02.081394Z","caller":"traceutil/trace.go:171","msg":"trace[356077375] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"318.096473ms","start":"2026-04-11T17:44:01.763285Z","end":"2026-04-11T17:44:02.081381Z","steps":["trace[356077375] 'range keys from in-memory index tree' (duration: 316.772702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:02.081422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:01.763261Z","time spent":"318.153694ms","remote":"127.0.0.1:50902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-11T17:44:02.46436Z","caller":"traceutil/trace.go:171","msg":"trace[1165994759] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"169.059789ms","start":"2026-04-11T17:44:02.295271Z","end":"2026-04-11T17:44:02.464331Z","steps":["trace[1165994759] 'read index received' (duration: 168.748143ms)","trace[1165994759] 'applied index is now lower than readState.Index' (duration: 310.476µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:44:02.464456Z","caller":"traceutil/trace.go:171","msg":"trace[117055355] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"275.179508ms","start":"2026-04-11T17:44:02.18919Z","end":"2026-04-11T17:44:02.464369Z","steps":["trace[117055355] 'process raft request' (duration: 274.880581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:02.464765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.432167ms","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-11T17:44:02.464845Z","caller":"traceutil/trace.go:171","msg":"trace[161070557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1857; }","duration":"169.634063ms","start":"2026-04-11T17:44:02.295198Z","end":"2026-04-11T17:44:02.464832Z","steps":["trace[161070557] 'agreement among raft nodes before linearized reading' (duration: 169.266364ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:14.991539Z","caller":"traceutil/trace.go:171","msg":"trace[1476126412] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"243.266647ms","start":"2026-04-11T17:44:14.74824Z","end":"2026-04-11T17:44:14.991507Z","steps":["trace[1476126412] 'process raft request' (duration: 243.048402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:14.991816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.880439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-11T17:44:14.991873Z","caller":"traceutil/trace.go:171","msg":"trace[732291895] range","detail":"{range_begin:/registry/masterleases/199.19.213.244; range_end:; response_count:1; response_revision:1958; }","duration":"174.973942ms","start":"2026-04-11T17:44:14.81689Z","end":"2026-04-11T17:44:14.991864Z","steps":["trace[732291895] 'agreement among raft nodes before linearized reading' (duration: 174.795538ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:14.991658Z","caller":"traceutil/trace.go:171","msg":"trace[867455335] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2029; }","duration":"174.672054ms","start":"2026-04-11T17:44:14.816916Z","end":"2026-04-11T17:44:14.991588Z","steps":["trace[867455335] 'read index received' (duration: 174.44972ms)","trace[867455335] 'applied index is now lower than readState.Index' (duration: 218.674µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:44:14.992255Z","caller":"traceutil/trace.go:171","msg":"trace[1066082328] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"149.631513ms","start":"2026-04-11T17:44:14.842609Z","end":"2026-04-11T17:44:14.99224Z","steps":["trace[1066082328] 'process raft request' (duration: 149.325395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:37.645493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.176591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:44:37.645721Z","caller":"traceutil/trace.go:171","msg":"trace[470100608] linearizableReadLoop","detail":"{readStateIndex:2173; appliedIndex:2171; }","duration":"548.333394ms","start":"2026-04-11T17:44:37.097365Z","end":"2026-04-11T17:44:37.645699Z","steps":["trace[470100608] 'read index received' (duration: 100.838506ms)","trace[470100608] 'applied index is now lower than readState.Index' (duration: 447.493648ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:44:37.645836Z","caller":"traceutil/trace.go:171","msg":"trace[239228671] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"568.37443ms","start":"2026-04-11T17:44:37.077447Z","end":"2026-04-11T17:44:37.645822Z","steps":["trace[239228671] 'process raft request' (duration: 120.741849ms)","trace[239228671] 'compare' (duration: 446.939436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:44:37.645918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:37.077418Z","time spent":"568.461033ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:44:37.646026Z","caller":"traceutil/trace.go:171","msg":"trace[781627694] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"567.008038ms","start":"2026-04-11T17:44:37.078948Z","end":"2026-04-11T17:44:37.645956Z","steps":["trace[781627694] 'process raft request' (duration: 566.679971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:37.646244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:37.078913Z","time spent":"567.230583ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:44:37.64625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.847766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-11T17:44:37.646381Z","caller":"traceutil/trace.go:171","msg":"trace[219709427] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2096; }","duration":"548.99487ms","start":"2026-04-11T17:44:37.097356Z","end":"2026-04-11T17:44:37.646351Z","steps":["trace[219709427] 'agreement among raft nodes before linearized reading' (duration: 548.59329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:37.646432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:37.097301Z","time spent":"549.120272ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-11T17:44:37.647451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.516217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:44:37.647531Z","caller":"traceutil/trace.go:171","msg":"trace[49818225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2097; }","duration":"520.614669ms","start":"2026-04-11T17:44:37.126895Z","end":"2026-04-11T17:44:37.64751Z","steps":["trace[49818225] 'agreement among raft nodes before linearized reading' (duration: 520.476806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:37.647566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:37.126883Z","time spent":"520.675091ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-11T17:44:37.647598Z","caller":"traceutil/trace.go:171","msg":"trace[1313630738] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"306.639343ms","start":"2026-04-11T17:44:37.340921Z","end":"2026-04-11T17:44:37.64756Z","steps":["trace[1313630738] 'process raft request' (duration: 306.323036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:37.647787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:37.340703Z","time spent":"306.976051ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:44:37.858845Z","caller":"traceutil/trace.go:171","msg":"trace[1023799362] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"149.233431ms","start":"2026-04-11T17:44:37.709588Z","end":"2026-04-11T17:44:37.858822Z","steps":["trace[1023799362] 'process raft request' (duration: 86.130023ms)","trace[1023799362] 'compare' (duration: 62.921994ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:44:37.859072Z","caller":"traceutil/trace.go:171","msg":"trace[574755942] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"147.611853ms","start":"2026-04-11T17:44:37.711438Z","end":"2026-04-11T17:44:37.85905Z","steps":["trace[574755942] 'process raft request' (duration: 147.338047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.444219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083380532s","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-11T17:44:39.444334Z","caller":"traceutil/trace.go:171","msg":"trace[2055159447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2100; }","duration":"1.083570327s","start":"2026-04-11T17:44:38.360734Z","end":"2026-04-11T17:44:39.444304Z","steps":["trace[2055159447] 'range keys from in-memory index tree' (duration: 1.083148957s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.444386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:38.360708Z","time spent":"1.083666789s","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:44:39.444477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054391148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-11T17:44:39.444561Z","caller":"traceutil/trace.go:171","msg":"trace[1434155543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2100; }","duration":"1.054532241s","start":"2026-04-11T17:44:38.390007Z","end":"2026-04-11T17:44:39.444539Z","steps":["trace[1434155543] 'range keys from in-memory index tree' (duration: 1.054241075s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.444598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:38.389987Z","time spent":"1.054602893s","remote":"127.0.0.1:57850","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-11T17:44:39.444947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317668512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:44:39.445072Z","caller":"traceutil/trace.go:171","msg":"trace[1145408328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2100; }","duration":"1.317909907s","start":"2026-04-11T17:44:38.127111Z","end":"2026-04-11T17:44:39.445021Z","steps":["trace[1145408328] 'range keys from in-memory index tree' (duration: 1.316798272s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.445165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:38.1271Z","time spent":"1.31802317s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T17:44:39.445245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.826378ms","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-11T17:44:39.445291Z","caller":"traceutil/trace.go:171","msg":"trace[1087861275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2100; }","duration":"545.91489ms","start":"2026-04-11T17:44:38.899365Z","end":"2026-04-11T17:44:39.44528Z","steps":["trace[1087861275] 'range keys from in-memory index tree' (duration: 545.616552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.445325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:38.899333Z","time spent":"545.985702ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-11T17:44:39.445517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.062953ms","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-11T17:44:39.445554Z","caller":"traceutil/trace.go:171","msg":"trace[401965291] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2100; }","duration":"534.162416ms","start":"2026-04-11T17:44:38.91138Z","end":"2026-04-11T17:44:39.445542Z","steps":["trace[401965291] 'range keys from in-memory index tree' (duration: 533.929391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.445583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:38.911349Z","time spent":"534.226798ms","remote":"127.0.0.1:50902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-11T17:44:39.44579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.335696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T17:44:39.445845Z","caller":"traceutil/trace.go:171","msg":"trace[1168881349] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2100; }","duration":"217.454719ms","start":"2026-04-11T17:44:39.228378Z","end":"2026-04-11T17:44:39.445833Z","steps":["trace[1168881349] 'count revisions from in-memory index tree' (duration: 217.205433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.445982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.82008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:44:39.446005Z","caller":"traceutil/trace.go:171","msg":"trace[1574502038] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2100; }","duration":"316.8638ms","start":"2026-04-11T17:44:39.129133Z","end":"2026-04-11T17:44:39.445996Z","steps":["trace[1574502038] 'count revisions from in-memory index tree' (duration: 316.738338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.446022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.129114Z","time spent":"316.901292ms","remote":"127.0.0.1:50208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:44:39.446673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248189955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T17:44:39.446699Z","caller":"traceutil/trace.go:171","msg":"trace[56258928] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2100; }","duration":"1.248256637s","start":"2026-04-11T17:44:38.198435Z","end":"2026-04-11T17:44:39.446692Z","steps":["trace[56258928] 'count revisions from in-memory index tree' (duration: 1.248102623s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.446721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:38.198386Z","time spent":"1.248329738s","remote":"127.0.0.1:57898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:44:39.446811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245201136s","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-11T17:44:39.44683Z","caller":"traceutil/trace.go:171","msg":"trace[71279088] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2100; }","duration":"1.245242257s","start":"2026-04-11T17:44:38.201582Z","end":"2026-04-11T17:44:39.446824Z","steps":["trace[71279088] 'count revisions from in-memory index tree' (duration: 1.245126144s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.446845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:38.20157Z","time spent":"1.245270667s","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:44:39.447211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.707902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:44:39.447236Z","caller":"traceutil/trace.go:171","msg":"trace[79951691] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2100; }","duration":"587.734063ms","start":"2026-04-11T17:44:38.859494Z","end":"2026-04-11T17:44:39.447228Z","steps":["trace[79951691] 'count revisions from in-memory index tree' (duration: 587.544818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:39.447253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:38.859376Z","time spent":"587.871486ms","remote":"127.0.0.1:47252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-11T17:44:39.603809Z","caller":"traceutil/trace.go:171","msg":"trace[713362987] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"154.198108ms","start":"2026-04-11T17:44:39.449577Z","end":"2026-04-11T17:44:39.603775Z","steps":["trace[713362987] 'process raft request' (duration: 153.956492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:40.356063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.664676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:44:40.356258Z","caller":"traceutil/trace.go:171","msg":"trace[1849304230] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2177; }","duration":"904.4282ms","start":"2026-04-11T17:44:39.451813Z","end":"2026-04-11T17:44:40.356241Z","steps":["trace[1849304230] 'read index received' (duration: 151.692518ms)","trace[1849304230] 'applied index is now lower than readState.Index' (duration: 752.731852ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:44:40.356359Z","caller":"traceutil/trace.go:171","msg":"trace[1777162389] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"905.335782ms","start":"2026-04-11T17:44:39.451012Z","end":"2026-04-11T17:44:40.356347Z","steps":["trace[1777162389] 'process raft request' (duration: 905.159917ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:40.356388Z","caller":"traceutil/trace.go:171","msg":"trace[1352336225] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"905.549977ms","start":"2026-04-11T17:44:39.450788Z","end":"2026-04-11T17:44:40.356338Z","steps":["trace[1352336225] 'process raft request' (duration: 331.513463ms)","trace[1352336225] 'compare' (duration: 573.313008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:44:40.35642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.451003Z","time spent":"905.385093ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:44:40.356546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.450781Z","time spent":"905.68115ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:44:40.356965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.088036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:44:40.357098Z","caller":"traceutil/trace.go:171","msg":"trace[1232867788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2103; }","duration":"905.286911ms","start":"2026-04-11T17:44:39.451787Z","end":"2026-04-11T17:44:40.357073Z","steps":["trace[1232867788] 'agreement among raft nodes before linearized reading' (duration: 904.657746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:40.357182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.451779Z","time spent":"905.391903ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T17:44:41.33642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.108668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:44:41.336664Z","caller":"traceutil/trace.go:171","msg":"trace[1638727618] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"1.682870344s","start":"2026-04-11T17:44:39.653775Z","end":"2026-04-11T17:44:41.336645Z","steps":["trace[1638727618] 'process raft request' (duration: 1.682751732s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:41.336665Z","caller":"traceutil/trace.go:171","msg":"trace[1056941523] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"1.684930722s","start":"2026-04-11T17:44:39.651683Z","end":"2026-04-11T17:44:41.336614Z","steps":["trace[1056941523] 'process raft request' (duration: 963.514506ms)","trace[1056941523] 'compare' (duration: 720.690508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:44:41.336745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.653756Z","time spent":"1.682955016s","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:44:41.337137Z","caller":"traceutil/trace.go:171","msg":"trace[322214824] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"1.658598969s","start":"2026-04-11T17:44:39.678523Z","end":"2026-04-11T17:44:41.337122Z","steps":["trace[322214824] 'process raft request' (duration: 1.658278001s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:41.3371Z","caller":"traceutil/trace.go:171","msg":"trace[770683860] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"1.6827465s","start":"2026-04-11T17:44:39.654303Z","end":"2026-04-11T17:44:41.337049Z","steps":["trace[770683860] 'process raft request' (duration: 1.68230361s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:41.337209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.678512Z","time spent":"1.65866077s","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:44:41.337422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.654296Z","time spent":"1.682909785s","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:44:41.337986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.651669Z","time spent":"1.685060515s","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:44:41.383013Z","caller":"traceutil/trace.go:171","msg":"trace[378903166] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2180; }","duration":"1.026672307s","start":"2026-04-11T17:44:40.356316Z","end":"2026-04-11T17:44:41.382989Z","steps":["trace[378903166] 'read index received' (duration: 258.856842ms)","trace[378903166] 'applied index is now lower than readState.Index' (duration: 767.813535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:44:41.383413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.704811783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-11T17:44:41.383444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.520128977s","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-11T17:44:41.383508Z","caller":"traceutil/trace.go:171","msg":"trace[1738744011] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2107; }","duration":"1.704931616s","start":"2026-04-11T17:44:39.678555Z","end":"2026-04-11T17:44:41.383487Z","steps":["trace[1738744011] 'agreement among raft nodes before linearized reading' (duration: 1.7046593s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:41.383647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.678545Z","time spent":"1.70508802s","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-11T17:44:41.383769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.359058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"warn","ts":"2026-04-11T17:44:41.383163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401320373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:44:41.383893Z","caller":"traceutil/trace.go:171","msg":"trace[557192931] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2107; }","duration":"1.402103392s","start":"2026-04-11T17:44:39.981773Z","end":"2026-04-11T17:44:41.383877Z","steps":["trace[557192931] 'agreement among raft nodes before linearized reading' (duration: 1.401283712s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:41.383865Z","caller":"traceutil/trace.go:171","msg":"trace[404716204] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2107; }","duration":"958.482211ms","start":"2026-04-11T17:44:40.425368Z","end":"2026-04-11T17:44:41.38385Z","steps":["trace[404716204] 'agreement among raft nodes before linearized reading' (duration: 958.215465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:41.384011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:40.425354Z","time spent":"958.644594ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12407,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-11T17:44:41.384143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.332963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:44:41.384185Z","caller":"traceutil/trace.go:171","msg":"trace[605105264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2107; }","duration":"831.397764ms","start":"2026-04-11T17:44:40.552779Z","end":"2026-04-11T17:44:41.384176Z","steps":["trace[605105264] 'agreement among raft nodes before linearized reading' (duration: 831.330192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:41.38423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:40.552733Z","time spent":"831.482536ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T17:44:41.38394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.981762Z","time spent":"1.402163103s","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:44:41.383387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521389697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-11T17:44:41.384459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.00784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:44:41.384506Z","caller":"traceutil/trace.go:171","msg":"trace[1393277169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2107; }","duration":"257.098792ms","start":"2026-04-11T17:44:41.127397Z","end":"2026-04-11T17:44:41.384496Z","steps":["trace[1393277169] 'agreement among raft nodes before linearized reading' (duration: 257.034061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:41.384587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.777203ms","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-11T17:44:41.384643Z","caller":"traceutil/trace.go:171","msg":"trace[1981321962] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2107; }","duration":"932.880495ms","start":"2026-04-11T17:44:40.451748Z","end":"2026-04-11T17:44:41.384629Z","steps":["trace[1981321962] 'agreement among raft nodes before linearized reading' (duration: 932.718682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:41.384682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:40.451725Z","time spent":"932.949556ms","remote":"127.0.0.1:50902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-11T17:44:41.38341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.694653997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a55e718229fb33\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-11T17:44:41.384639Z","caller":"traceutil/trace.go:171","msg":"trace[415895096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2107; }","duration":"1.522551624s","start":"2026-04-11T17:44:39.861948Z","end":"2026-04-11T17:44:41.3845Z","steps":["trace[415895096] 'agreement among raft nodes before linearized reading' (duration: 1.521272654s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:41.384842Z","caller":"traceutil/trace.go:171","msg":"trace[1525591762] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a55e718229fb33; range_end:; response_count:1; response_revision:2107; }","duration":"1.696099181s","start":"2026-04-11T17:44:39.688727Z","end":"2026-04-11T17:44:41.384826Z","steps":["trace[1525591762] 'agreement among raft nodes before linearized reading' (duration: 1.694553275s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:41.38489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.688716Z","time spent":"1.696157752s","remote":"127.0.0.1:57674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a55e718229fb33\" "} {"level":"warn","ts":"2026-04-11T17:44:41.383233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263543428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:44:41.385049Z","caller":"traceutil/trace.go:171","msg":"trace[778803099] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2107; }","duration":"1.265412621s","start":"2026-04-11T17:44:40.119623Z","end":"2026-04-11T17:44:41.385035Z","steps":["trace[778803099] 'agreement among raft nodes before linearized reading' (duration: 1.263530887s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:41.383515Z","caller":"traceutil/trace.go:171","msg":"trace[350333099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2107; }","duration":"1.52022233s","start":"2026-04-11T17:44:39.863271Z","end":"2026-04-11T17:44:41.383493Z","steps":["trace[350333099] 'agreement among raft nodes before linearized reading' (duration: 1.520060186s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:41.385098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:40.11961Z","time spent":"1.265473853s","remote":"127.0.0.1:50270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:44:41.385153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.863259Z","time spent":"1.521872228s","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-11T17:44:41.38626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:44:39.861916Z","time spent":"1.522935744s","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T17:46:18.260685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.699873ms","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-11T17:46:18.260705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.79954ms","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-11T17:46:18.260711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.699969ms","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-11T17:46:18.260786Z","caller":"traceutil/trace.go:171","msg":"trace[1396436608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2731; }","duration":"334.855687ms","start":"2026-04-11T17:46:17.925911Z","end":"2026-04-11T17:46:18.260767Z","steps":["trace[1396436608] 'range keys from in-memory index tree' (duration: 334.515581ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:46:18.260812Z","caller":"traceutil/trace.go:171","msg":"trace[1837354594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2731; }","duration":"320.948033ms","start":"2026-04-11T17:46:17.939844Z","end":"2026-04-11T17:46:18.260792Z","steps":["trace[1837354594] 'range keys from in-memory index tree' (duration: 320.659857ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:46:18.260816Z","caller":"traceutil/trace.go:171","msg":"trace[1033234801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2731; }","duration":"186.863983ms","start":"2026-04-11T17:46:18.073928Z","end":"2026-04-11T17:46:18.260792Z","steps":["trace[1033234801] 'range keys from in-memory index tree' (duration: 186.479884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:46:18.260837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:46:17.925892Z","time spent":"334.928368ms","remote":"127.0.0.1:57850","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":"warn","ts":"2026-04-11T17:46:18.260856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:46:17.939826Z","time spent":"321.017375ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-11T17:46:18.261555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.739259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-11T17:46:18.261664Z","caller":"traceutil/trace.go:171","msg":"trace[1787629461] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2731; }","duration":"329.905243ms","start":"2026-04-11T17:46:17.931735Z","end":"2026-04-11T17:46:18.26164Z","steps":["trace[1787629461] 'range keys from in-memory index tree' (duration: 328.680843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:46:18.261992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:46:17.931711Z","time spent":"330.124277ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-11T17:46:23.380761Z","caller":"traceutil/trace.go:171","msg":"trace[1357474625] linearizableReadLoop","detail":"{readStateIndex:2862; appliedIndex:2861; }","duration":"123.969135ms","start":"2026-04-11T17:46:23.256765Z","end":"2026-04-11T17:46:23.380734Z","steps":["trace[1357474625] 'read index received' (duration: 123.727269ms)","trace[1357474625] 'applied index is now lower than readState.Index' (duration: 240.896µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:46:23.381096Z","caller":"traceutil/trace.go:171","msg":"trace[886136525] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"143.970832ms","start":"2026-04-11T17:46:23.237112Z","end":"2026-04-11T17:46:23.381083Z","steps":["trace[886136525] 'process raft request' (duration: 143.45662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:46:23.381149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.353214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T17:46:23.381195Z","caller":"traceutil/trace.go:171","msg":"trace[591790351] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2761; }","duration":"124.465426ms","start":"2026-04-11T17:46:23.256719Z","end":"2026-04-11T17:46:23.381184Z","steps":["trace[591790351] 'agreement among raft nodes before linearized reading' (duration: 124.360974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:10.542508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.541896ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-11T17:50:10.542634Z","caller":"traceutil/trace.go:171","msg":"trace[1491748884] linearizableReadLoop","detail":"{readStateIndex:4892; appliedIndex:4891; }","duration":"316.864052ms","start":"2026-04-11T17:50:10.22575Z","end":"2026-04-11T17:50:10.542614Z","steps":["trace[1491748884] 'read index received' (duration: 42.231µs)","trace[1491748884] 'applied index is now lower than readState.Index' (duration: 316.820061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:10.543124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.727737ms","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-11T17:50:10.543329Z","caller":"traceutil/trace.go:171","msg":"trace[976102328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4714; }","duration":"275.972121ms","start":"2026-04-11T17:50:10.26733Z","end":"2026-04-11T17:50:10.543302Z","steps":["trace[976102328] 'agreement among raft nodes before linearized reading' (duration: 275.487851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:10.544126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.479019ms","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-11T17:50:10.544201Z","caller":"traceutil/trace.go:171","msg":"trace[1691885216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4714; }","duration":"251.628582ms","start":"2026-04-11T17:50:10.292553Z","end":"2026-04-11T17:50:10.544181Z","steps":["trace[1691885216] 'agreement among raft nodes before linearized reading' (duration: 250.368943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:10.544779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.999871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-11T17:50:10.544872Z","caller":"traceutil/trace.go:171","msg":"trace[138751832] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4714; }","duration":"319.155605ms","start":"2026-04-11T17:50:10.225698Z","end":"2026-04-11T17:50:10.544853Z","steps":["trace[138751832] 'agreement among raft nodes before linearized reading' (duration: 317.038426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:10.544911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:10.225676Z","time spent":"319.228846ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2193189,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-04-11T17:50:10.669409Z","caller":"traceutil/trace.go:171","msg":"trace[200494048] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"118.330649ms","start":"2026-04-11T17:50:10.550975Z","end":"2026-04-11T17:50:10.669306Z","steps":["trace[200494048] 'process raft request' (duration: 100.011787ms)","trace[200494048] 'compare' (duration: 18.122428ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:11.588648Z","caller":"traceutil/trace.go:171","msg":"trace[81556808] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"179.819463ms","start":"2026-04-11T17:50:11.408765Z","end":"2026-04-11T17:50:11.588584Z","steps":["trace[81556808] 'process raft request' (duration: 179.590567ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:22.941403Z","caller":"traceutil/trace.go:171","msg":"trace[810997254] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"221.978595ms","start":"2026-04-11T17:50:22.719348Z","end":"2026-04-11T17:50:22.941327Z","steps":["trace[810997254] 'process raft request' (duration: 221.626787ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:22.986692Z","caller":"traceutil/trace.go:171","msg":"trace[1674453368] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"141.900595ms","start":"2026-04-11T17:50:22.844772Z","end":"2026-04-11T17:50:22.986673Z","steps":["trace[1674453368] 'process raft request' (duration: 141.805453ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:22.987081Z","caller":"traceutil/trace.go:171","msg":"trace[1213657890] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"133.971201ms","start":"2026-04-11T17:50:22.85307Z","end":"2026-04-11T17:50:22.987042Z","steps":["trace[1213657890] 'process raft request' (duration: 133.551472ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:22.987209Z","caller":"traceutil/trace.go:171","msg":"trace[726245478] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"129.385866ms","start":"2026-04-11T17:50:22.857798Z","end":"2026-04-11T17:50:22.987184Z","steps":["trace[726245478] 'process raft request' (duration: 129.156851ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:22.987128Z","caller":"traceutil/trace.go:171","msg":"trace[220803096] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"263.238705ms","start":"2026-04-11T17:50:22.723817Z","end":"2026-04-11T17:50:22.987056Z","steps":["trace[220803096] 'process raft request' (duration: 262.627192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:23.401242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.081068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-11T17:50:23.401346Z","caller":"traceutil/trace.go:171","msg":"trace[276703571] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4893; }","duration":"132.200591ms","start":"2026-04-11T17:50:23.269116Z","end":"2026-04-11T17:50:23.401317Z","steps":["trace[276703571] 'range keys from in-memory index tree' (duration: 131.925524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:23.401437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.630079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:50:23.403099Z","caller":"traceutil/trace.go:171","msg":"trace[931152058] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"133.859139ms","start":"2026-04-11T17:50:23.269207Z","end":"2026-04-11T17:50:23.403066Z","steps":["trace[931152058] 'process raft request' (duration: 16.460799ms)","trace[931152058] 'compare' (duration: 115.407314ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:23.403787Z","caller":"traceutil/trace.go:171","msg":"trace[154985996] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"133.794817ms","start":"2026-04-11T17:50:23.26997Z","end":"2026-04-11T17:50:23.403765Z","steps":["trace[154985996] 'process raft request' (duration: 132.815235ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:23.404456Z","caller":"traceutil/trace.go:171","msg":"trace[83349056] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"134.279289ms","start":"2026-04-11T17:50:23.27016Z","end":"2026-04-11T17:50:23.404439Z","steps":["trace[83349056] 'process raft request' (duration: 133.538112ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:23.404518Z","caller":"traceutil/trace.go:171","msg":"trace[1301905418] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"125.830894ms","start":"2026-04-11T17:50:23.278672Z","end":"2026-04-11T17:50:23.404503Z","steps":["trace[1301905418] 'process raft request' (duration: 125.756072ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:23.404831Z","caller":"traceutil/trace.go:171","msg":"trace[161963917] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"134.576326ms","start":"2026-04-11T17:50:23.270242Z","end":"2026-04-11T17:50:23.404819Z","steps":["trace[161963917] 'process raft request' (duration: 133.742087ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:54.019355Z","caller":"traceutil/trace.go:171","msg":"trace[1278258133] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"114.009748ms","start":"2026-04-11T17:50:53.905308Z","end":"2026-04-11T17:50:54.019318Z","steps":["trace[1278258133] 'process raft request' (duration: 90.125517ms)","trace[1278258133] 'compare' (duration: 23.698997ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:51.972433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2910} {"level":"info","ts":"2026-04-11T17:51:52.103206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2910,"took":"128.690525ms","hash":3502415374,"current-db-size-bytes":54034432,"current-db-size":"54 MB","current-db-size-in-use-bytes":29290496,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-11T17:51:52.103292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3502415374,"revision":2910,"compact-revision":-1} {"level":"info","ts":"2026-04-11T17:54:26.210052Z","caller":"traceutil/trace.go:171","msg":"trace[1745543449] transaction","detail":"{read_only:false; response_revision:6671; number_of_response:1; }","duration":"130.986578ms","start":"2026-04-11T17:54:26.079039Z","end":"2026-04-11T17:54:26.210025Z","steps":["trace[1745543449] 'process raft request' (duration: 98.29432ms)","trace[1745543449] 'compare' (duration: 32.526934ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:54:26.210319Z","caller":"traceutil/trace.go:171","msg":"trace[769592259] transaction","detail":"{read_only:false; response_revision:6672; number_of_response:1; }","duration":"125.189502ms","start":"2026-04-11T17:54:26.085105Z","end":"2026-04-11T17:54:26.210295Z","steps":["trace[769592259] 'process raft request' (duration: 124.885976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:54:26.470738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.228589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:54:26.471575Z","caller":"traceutil/trace.go:171","msg":"trace[1727608450] linearizableReadLoop","detail":"{readStateIndex:6917; appliedIndex:6916; }","duration":"165.350045ms","start":"2026-04-11T17:54:26.306211Z","end":"2026-04-11T17:54:26.471561Z","steps":["trace[1727608450] 'read index received' (duration: 16.197305ms)","trace[1727608450] 'applied index is now lower than readState.Index' (duration: 149.15158ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:54:26.471631Z","caller":"traceutil/trace.go:171","msg":"trace[868013452] transaction","detail":"{read_only:false; response_revision:6673; number_of_response:1; }","duration":"177.20667ms","start":"2026-04-11T17:54:26.294417Z","end":"2026-04-11T17:54:26.471623Z","steps":["trace[868013452] 'process raft request' (duration: 27.993219ms)","trace[868013452] 'compare' (duration: 148.018244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:54:26.472432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.203955ms","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-11T17:54:26.472501Z","caller":"traceutil/trace.go:171","msg":"trace[590351844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6673; }","duration":"166.296817ms","start":"2026-04-11T17:54:26.306195Z","end":"2026-04-11T17:54:26.472492Z","steps":["trace[590351844] 'agreement among raft nodes before linearized reading' (duration: 165.969059ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:56:51.978665Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5635} {"level":"info","ts":"2026-04-11T17:56:52.052637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5635,"took":"72.256088ms","hash":3194803389,"current-db-size-bytes":54034432,"current-db-size":"54 MB","current-db-size-in-use-bytes":18198528,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-11T17:56:52.052719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3194803389,"revision":5635,"compact-revision":2910}