{"level":"warn","ts":"2026-04-20T08:06:51.277542Z","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:06:51.277616Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.99: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.99:2380","--initial-cluster=instance=https://199.19.213.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.99: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:06:51.277674Z","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:06:51.277687Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-04-20T08:06:51.277702Z","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:06:51.27839Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"]} {"level":"info","ts":"2026-04-20T08:06:51.27846Z","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.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.99: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:06:51.283734Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.07595ms"} {"level":"info","ts":"2026-04-20T08:06:51.295012Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4da37caf00b87dfe","cluster-id":"221bed930aa36d2e"} {"level":"info","ts":"2026-04-20T08:06:51.295102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T08:06:51.295127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 0"} {"level":"info","ts":"2026-04-20T08:06:51.295138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4da37caf00b87dfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T08:06:51.295149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 1"} {"level":"info","ts":"2026-04-20T08:06:51.29519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"warn","ts":"2026-04-20T08:06:51.300183Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T08:06:51.302105Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T08:06:51.303589Z","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:06:51.30666Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4da37caf00b87dfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T08:06:51.307127Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4da37caf00b87dfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T08:06:51.307212Z","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:06:51.307239Z","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:06:51.307246Z","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:06:51.313636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"info","ts":"2026-04-20T08:06:51.313851Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","added-peer-id":"4da37caf00b87dfe","added-peer-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-04-20T08:06:51.315655Z","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:06:51.315873Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4da37caf00b87dfe","initial-advertise-peer-urls":["https://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T08:06:51.315908Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T08:06:51.316012Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-04-20T08:06:51.316033Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-04-20T08:06:51.997693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T08:06:51.997734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T08:06:51.997748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgPreVoteResp from 4da37caf00b87dfe at term 1"} {"level":"info","ts":"2026-04-20T08:06:51.997758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became candidate at term 2"} {"level":"info","ts":"2026-04-20T08:06:51.997774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgVoteResp from 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-04-20T08:06:51.997782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became leader at term 2"} {"level":"info","ts":"2026-04-20T08:06:51.997789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4da37caf00b87dfe elected leader 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-04-20T08:06:52.025291Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4da37caf00b87dfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.99:2379]}","request-path":"/0/members/4da37caf00b87dfe/attributes","cluster-id":"221bed930aa36d2e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T08:06:52.025597Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:06:52.026297Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:06:52.026441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:06:52.026614Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T08:06:52.026671Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T08:06:52.029627Z","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:06:52.02974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.99:2379"} {"level":"info","ts":"2026-04-20T08:06:52.053067Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:06:52.053347Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:06:52.05339Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:07:32.64654Z","caller":"traceutil/trace.go:171","msg":"trace[63835973] transaction","detail":"{read_only:false; number_of_response:1; response_revision:559; }","duration":"121.715349ms","start":"2026-04-20T08:07:32.524747Z","end":"2026-04-20T08:07:32.646463Z","steps":["trace[63835973] 'process raft request' (duration: 118.186725ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:32.650877Z","caller":"traceutil/trace.go:171","msg":"trace[871565557] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"116.093694ms","start":"2026-04-20T08:07:32.534763Z","end":"2026-04-20T08:07:32.650857Z","steps":["trace[871565557] 'process raft request' (duration: 115.815266ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:32.651131Z","caller":"traceutil/trace.go:171","msg":"trace[769874440] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"116.149456ms","start":"2026-04-20T08:07:32.534967Z","end":"2026-04-20T08:07:32.651116Z","steps":["trace[769874440] 'process raft request' (duration: 115.758184ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:32.651202Z","caller":"traceutil/trace.go:171","msg":"trace[403652423] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"116.097965ms","start":"2026-04-20T08:07:32.535085Z","end":"2026-04-20T08:07:32.651183Z","steps":["trace[403652423] 'process raft request' (duration: 115.714553ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:32.651317Z","caller":"traceutil/trace.go:171","msg":"trace[652354692] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"110.354066ms","start":"2026-04-20T08:07:32.540957Z","end":"2026-04-20T08:07:32.651311Z","steps":["trace[652354692] 'process raft request' (duration: 109.938174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:39.022145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.28087ms","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:07:39.022342Z","caller":"traceutil/trace.go:171","msg":"trace[1165363035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"138.618389ms","start":"2026-04-20T08:07:38.883695Z","end":"2026-04-20T08:07:39.022313Z","steps":["trace[1165363035] 'range keys from in-memory index tree' (duration: 138.124965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:39.624299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.876509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:07:39.624523Z","caller":"traceutil/trace.go:171","msg":"trace[970212996] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"588.71373ms","start":"2026-04-20T08:07:39.035755Z","end":"2026-04-20T08:07:39.624469Z","steps":["trace[970212996] 'process raft request' (duration: 119.909395ms)","trace[970212996] 'compare' (duration: 467.747266ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:39.624641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:39.035708Z","time spent":"588.862535ms","remote":"127.0.0.1:59786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:07:39.642572Z","caller":"traceutil/trace.go:171","msg":"trace[1415693779] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"243.426141ms","start":"2026-04-20T08:07:39.399093Z","end":"2026-04-20T08:07:39.642519Z","steps":["trace[1415693779] 'read index received' (duration: 237.298859ms)","trace[1415693779] 'applied index is now lower than readState.Index' (duration: 6.126332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:39.642689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.583775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:07:39.642749Z","caller":"traceutil/trace.go:171","msg":"trace[1982414549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"243.699508ms","start":"2026-04-20T08:07:39.399031Z","end":"2026-04-20T08:07:39.642731Z","steps":["trace[1982414549] 'agreement among raft nodes before linearized reading' (duration: 243.595335ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:39.642689Z","caller":"traceutil/trace.go:171","msg":"trace[1523263461] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"554.200172ms","start":"2026-04-20T08:07:39.088417Z","end":"2026-04-20T08:07:39.642617Z","steps":["trace[1523263461] 'process raft request' (duration: 548.328757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:39.642854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.177491ms","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-20T08:07:39.64291Z","caller":"traceutil/trace.go:171","msg":"trace[864102318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"131.278843ms","start":"2026-04-20T08:07:39.511621Z","end":"2026-04-20T08:07:39.6429Z","steps":["trace[864102318] 'agreement among raft nodes before linearized reading' (duration: 131.088468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:39.642941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:39.088381Z","time spent":"554.426839ms","remote":"127.0.0.1:59710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:07:54.573115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.247832ms","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:07:54.573199Z","caller":"traceutil/trace.go:171","msg":"trace[1043771267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"124.393567ms","start":"2026-04-20T08:07:54.448788Z","end":"2026-04-20T08:07:54.573182Z","steps":["trace[1043771267] 'range keys from in-memory index tree' (duration: 124.063058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:11.262723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.858337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-20T08:09:11.262769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.462536ms","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":"warn","ts":"2026-04-20T08:09:11.262804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.534199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-411dbbaa-19b5-44ff-bbf1-e7970541cb15\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-20T08:09:11.262851Z","caller":"traceutil/trace.go:171","msg":"trace[268448006] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1870; }","duration":"377.585568ms","start":"2026-04-20T08:09:10.885241Z","end":"2026-04-20T08:09:11.262827Z","steps":["trace[268448006] 'range keys from in-memory index tree' (duration: 377.201221ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:11.262881Z","caller":"traceutil/trace.go:171","msg":"trace[802322856] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-411dbbaa-19b5-44ff-bbf1-e7970541cb15; range_end:; response_count:1; response_revision:1870; }","duration":"329.654301ms","start":"2026-04-20T08:09:10.933206Z","end":"2026-04-20T08:09:11.26286Z","steps":["trace[802322856] 'range keys from in-memory index tree' (duration: 329.345525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:11.2629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:09:10.885221Z","time spent":"377.6703ms","remote":"127.0.0.1:37350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T08:09:11.262947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:09:10.933186Z","time spent":"329.746703ms","remote":"127.0.0.1:59786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-411dbbaa-19b5-44ff-bbf1-e7970541cb15\" "} {"level":"info","ts":"2026-04-20T08:09:11.263515Z","caller":"traceutil/trace.go:171","msg":"trace[999302589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1870; }","duration":"196.995659ms","start":"2026-04-20T08:09:11.065797Z","end":"2026-04-20T08:09:11.262793Z","steps":["trace[999302589] 'range keys from in-memory index tree' (duration: 196.681433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:11.716784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.230518ms","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:09:11.71689Z","caller":"traceutil/trace.go:171","msg":"trace[628662077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1873; }","duration":"152.415751ms","start":"2026-04-20T08:09:11.564451Z","end":"2026-04-20T08:09:11.716866Z","steps":["trace[628662077] 'range keys from in-memory index tree' (duration: 152.021584ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:28.476206Z","caller":"traceutil/trace.go:171","msg":"trace[774719479] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"150.415378ms","start":"2026-04-20T08:09:28.325771Z","end":"2026-04-20T08:09:28.476186Z","steps":["trace[774719479] 'process raft request' (duration: 150.295995ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:28.476737Z","caller":"traceutil/trace.go:171","msg":"trace[1827018958] linearizableReadLoop","detail":"{readStateIndex:2086; appliedIndex:2084; }","duration":"125.82818ms","start":"2026-04-20T08:09:28.350898Z","end":"2026-04-20T08:09:28.476727Z","steps":["trace[1827018958] 'read index received' (duration: 125.207798ms)","trace[1827018958] 'applied index is now lower than readState.Index' (duration: 619.842µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:09:28.47687Z","caller":"traceutil/trace.go:171","msg":"trace[1955819840] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"137.245156ms","start":"2026-04-20T08:09:28.339617Z","end":"2026-04-20T08:09:28.476862Z","steps":["trace[1955819840] 'process raft request' (duration: 137.073813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:28.477034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.122796ms","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:09:28.477063Z","caller":"traceutil/trace.go:171","msg":"trace[761519224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2009; }","duration":"126.183948ms","start":"2026-04-20T08:09:28.350871Z","end":"2026-04-20T08:09:28.477055Z","steps":["trace[761519224] 'agreement among raft nodes before linearized reading' (duration: 126.090126ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:51.164412Z","caller":"traceutil/trace.go:171","msg":"trace[2146512521] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"167.062395ms","start":"2026-04-20T08:09:50.997324Z","end":"2026-04-20T08:09:51.164386Z","steps":["trace[2146512521] 'process raft request' (duration: 166.876051ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:51.4875Z","caller":"traceutil/trace.go:171","msg":"trace[1046091520] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"119.322946ms","start":"2026-04-20T08:09:51.368148Z","end":"2026-04-20T08:09:51.487471Z","steps":["trace[1046091520] 'process raft request' (duration: 119.135442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:51.817733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.850215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:09:51.817845Z","caller":"traceutil/trace.go:171","msg":"trace[1871136110] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"298.35484ms","start":"2026-04-20T08:09:51.519472Z","end":"2026-04-20T08:09:51.817826Z","steps":["trace[1871136110] 'read index received' (duration: 41.56µs)","trace[1871136110] 'applied index is now lower than readState.Index' (duration: 298.31149ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:09:51.81794Z","caller":"traceutil/trace.go:171","msg":"trace[884429980] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"335.985784ms","start":"2026-04-20T08:09:51.481943Z","end":"2026-04-20T08:09:51.817928Z","steps":["trace[884429980] 'process raft request' (duration: 15.831974ms)","trace[884429980] 'compare' (duration: 319.510317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:09:51.81826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.793949ms","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:09:51.818276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:09:51.481915Z","time spent":"336.323121ms","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:09:51.818161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.10248ms","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:09:51.818316Z","caller":"traceutil/trace.go:171","msg":"trace[154094521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2139; }","duration":"298.87126ms","start":"2026-04-20T08:09:51.51943Z","end":"2026-04-20T08:09:51.818301Z","steps":["trace[154094521] 'agreement among raft nodes before linearized reading' (duration: 298.772289ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:51.818377Z","caller":"traceutil/trace.go:171","msg":"trace[768850572] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2139; }","duration":"174.349135ms","start":"2026-04-20T08:09:51.643987Z","end":"2026-04-20T08:09:51.818336Z","steps":["trace[768850572] 'agreement among raft nodes before linearized reading' (duration: 173.977398ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:52.065292Z","caller":"traceutil/trace.go:171","msg":"trace[306755459] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"177.80253ms","start":"2026-04-20T08:09:51.88747Z","end":"2026-04-20T08:09:52.065272Z","steps":["trace[306755459] 'process raft request' (duration: 141.821999ms)","trace[306755459] 'compare' (duration: 35.843369ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:09:52.065305Z","caller":"traceutil/trace.go:171","msg":"trace[1610271298] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"139.524214ms","start":"2026-04-20T08:09:51.925738Z","end":"2026-04-20T08:09:52.065263Z","steps":["trace[1610271298] 'read index received' (duration: 103.542603ms)","trace[1610271298] 'applied index is now lower than readState.Index' (duration: 35.980171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:09:52.065691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.940742ms","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:09:52.065749Z","caller":"traceutil/trace.go:171","msg":"trace[641667270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2141; }","duration":"140.051973ms","start":"2026-04-20T08:09:51.925684Z","end":"2026-04-20T08:09:52.065736Z","steps":["trace[641667270] 'agreement among raft nodes before linearized reading' (duration: 139.717067ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:52.249273Z","caller":"traceutil/trace.go:171","msg":"trace[1809606092] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"179.232596ms","start":"2026-04-20T08:09:52.070008Z","end":"2026-04-20T08:09:52.249241Z","steps":["trace[1809606092] 'process raft request' (duration: 174.929814ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:52.249749Z","caller":"traceutil/trace.go:171","msg":"trace[891942302] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"179.637784ms","start":"2026-04-20T08:09:52.070091Z","end":"2026-04-20T08:09:52.249729Z","steps":["trace[891942302] 'process raft request' (duration: 179.093964ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:52.24982Z","caller":"traceutil/trace.go:171","msg":"trace[190854654] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2223; }","duration":"160.108379ms","start":"2026-04-20T08:09:52.089617Z","end":"2026-04-20T08:09:52.249725Z","steps":["trace[190854654] 'read index received' (duration: 155.302897ms)","trace[190854654] 'applied index is now lower than readState.Index' (duration: 4.797182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:09:52.249951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.324833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:09:52.249997Z","caller":"traceutil/trace.go:171","msg":"trace[2010895009] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2143; }","duration":"160.409194ms","start":"2026-04-20T08:09:52.089572Z","end":"2026-04-20T08:09:52.249982Z","steps":["trace[2010895009] 'agreement among raft nodes before linearized reading' (duration: 160.311202ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:10:50.550022Z","caller":"traceutil/trace.go:171","msg":"trace[1286441761] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"264.087917ms","start":"2026-04-20T08:10:50.285913Z","end":"2026-04-20T08:10:50.550001Z","steps":["trace[1286441761] 'process raft request' (duration: 263.814826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:11:23.159785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.7279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:11:23.159908Z","caller":"traceutil/trace.go:171","msg":"trace[979214880] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2740; }","duration":"129.931547ms","start":"2026-04-20T08:11:23.029949Z","end":"2026-04-20T08:11:23.15988Z","steps":["trace[979214880] 'count revisions from in-memory index tree' (duration: 129.567875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:11:23.16011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.087648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:11:23.16044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.975467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T08:11:23.160494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.673736ms","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:11:23.160551Z","caller":"traceutil/trace.go:171","msg":"trace[248676776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2740; }","duration":"106.137552ms","start":"2026-04-20T08:11:23.05439Z","end":"2026-04-20T08:11:23.160528Z","steps":["trace[248676776] 'range keys from in-memory index tree' (duration: 105.726498ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:23.160548Z","caller":"traceutil/trace.go:171","msg":"trace[783002042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2740; }","duration":"105.752999ms","start":"2026-04-20T08:11:23.054782Z","end":"2026-04-20T08:11:23.160535Z","steps":["trace[783002042] 'range keys from in-memory index tree' (duration: 105.523291ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:23.160226Z","caller":"traceutil/trace.go:171","msg":"trace[2124487286] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2740; }","duration":"193.241933ms","start":"2026-04-20T08:11:22.966961Z","end":"2026-04-20T08:11:23.160203Z","steps":["trace[2124487286] 'count revisions from in-memory index tree' (duration: 192.894472ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:33.224294Z","caller":"traceutil/trace.go:171","msg":"trace[44704028] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"153.312409ms","start":"2026-04-20T08:15:33.070915Z","end":"2026-04-20T08:15:33.224227Z","steps":["trace[44704028] 'process raft request' (duration: 137.246603ms)","trace[44704028] 'compare' (duration: 15.8161ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:15:38.990131Z","caller":"traceutil/trace.go:171","msg":"trace[1123655906] linearizableReadLoop","detail":"{readStateIndex:5080; appliedIndex:5079; }","duration":"130.619176ms","start":"2026-04-20T08:15:38.859489Z","end":"2026-04-20T08:15:38.990109Z","steps":["trace[1123655906] 'read index received' (duration: 120.581684ms)","trace[1123655906] 'applied index is now lower than readState.Index' (duration: 10.035992ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:15:38.990338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.82381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:15:38.99037Z","caller":"traceutil/trace.go:171","msg":"trace[427646946] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4894; }","duration":"130.899261ms","start":"2026-04-20T08:15:38.859463Z","end":"2026-04-20T08:15:38.990362Z","steps":["trace[427646946] 'agreement among raft nodes before linearized reading' (duration: 130.732758ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:38.990669Z","caller":"traceutil/trace.go:171","msg":"trace[204159493] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"131.354322ms","start":"2026-04-20T08:15:38.859303Z","end":"2026-04-20T08:15:38.990657Z","steps":["trace[204159493] 'process raft request' (duration: 120.826369ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:39.350497Z","caller":"traceutil/trace.go:171","msg":"trace[2099894054] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"107.289049ms","start":"2026-04-20T08:15:39.243149Z","end":"2026-04-20T08:15:39.350438Z","steps":["trace[2099894054] 'process raft request' (duration: 104.18854ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:39.351203Z","caller":"traceutil/trace.go:171","msg":"trace[841670537] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"133.14164ms","start":"2026-04-20T08:15:39.218029Z","end":"2026-04-20T08:15:39.351171Z","steps":["trace[841670537] 'process raft request' (duration: 99.076657ms)","trace[841670537] 'compare' (duration: 28.94541ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:15:39.630183Z","caller":"traceutil/trace.go:171","msg":"trace[1874910909] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"127.346711ms","start":"2026-04-20T08:15:39.502797Z","end":"2026-04-20T08:15:39.630144Z","steps":["trace[1874910909] 'process raft request' (duration: 124.590921ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:39.630197Z","caller":"traceutil/trace.go:171","msg":"trace[1460692871] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"125.844468ms","start":"2026-04-20T08:15:39.504322Z","end":"2026-04-20T08:15:39.630166Z","steps":["trace[1460692871] 'process raft request' (duration: 125.484191ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:39.630306Z","caller":"traceutil/trace.go:171","msg":"trace[1561352352] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"126.612625ms","start":"2026-04-20T08:15:39.503672Z","end":"2026-04-20T08:15:39.630285Z","steps":["trace[1561352352] 'process raft request' (duration: 126.076143ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:40.330946Z","caller":"traceutil/trace.go:171","msg":"trace[1099530027] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"107.578234ms","start":"2026-04-20T08:15:40.223343Z","end":"2026-04-20T08:15:40.330921Z","steps":["trace[1099530027] 'process raft request' (duration: 107.451882ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:40.334509Z","caller":"traceutil/trace.go:171","msg":"trace[1008779385] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"101.804886ms","start":"2026-04-20T08:15:40.232666Z","end":"2026-04-20T08:15:40.334471Z","steps":["trace[1008779385] 'process raft request' (duration: 101.451528ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:44.394779Z","caller":"traceutil/trace.go:171","msg":"trace[565383024] linearizableReadLoop","detail":"{readStateIndex:5128; appliedIndex:5127; }","duration":"106.33774ms","start":"2026-04-20T08:15:44.288416Z","end":"2026-04-20T08:15:44.394754Z","steps":["trace[565383024] 'read index received' (duration: 105.692305ms)","trace[565383024] 'applied index is now lower than readState.Index' (duration: 644.255µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:15:44.395001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.551974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-20T08:15:44.39504Z","caller":"traceutil/trace.go:171","msg":"trace[211125344] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4941; }","duration":"106.623586ms","start":"2026-04-20T08:15:44.288405Z","end":"2026-04-20T08:15:44.395029Z","steps":["trace[211125344] 'agreement among raft nodes before linearized reading' (duration: 106.446322ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:44.395199Z","caller":"traceutil/trace.go:171","msg":"trace[1205162164] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"137.516227ms","start":"2026-04-20T08:15:44.257641Z","end":"2026-04-20T08:15:44.395157Z","steps":["trace[1205162164] 'process raft request' (duration: 136.395922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:44.546042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.198232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:15:44.546213Z","caller":"traceutil/trace.go:171","msg":"trace[1536546052] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"143.039577ms","start":"2026-04-20T08:15:44.403138Z","end":"2026-04-20T08:15:44.546177Z","steps":["trace[1536546052] 'process raft request' (duration: 14.56055ms)","trace[1536546052] 'compare' (duration: 128.081839ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:15:44.546329Z","caller":"traceutil/trace.go:171","msg":"trace[1965558200] linearizableReadLoop","detail":"{readStateIndex:5130; appliedIndex:5129; }","duration":"141.760439ms","start":"2026-04-20T08:15:44.404548Z","end":"2026-04-20T08:15:44.546308Z","steps":["trace[1965558200] 'read index received' (duration: 13.158839ms)","trace[1965558200] 'applied index is now lower than readState.Index' (duration: 128.59933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:15:44.546483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.059442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:15:44.546521Z","caller":"traceutil/trace.go:171","msg":"trace[603153526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4943; }","duration":"145.114774ms","start":"2026-04-20T08:15:44.401397Z","end":"2026-04-20T08:15:44.546512Z","steps":["trace[603153526] 'agreement among raft nodes before linearized reading' (duration: 145.047813ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:44.546988Z","caller":"traceutil/trace.go:171","msg":"trace[202224433] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"139.859858ms","start":"2026-04-20T08:15:44.407105Z","end":"2026-04-20T08:15:44.546965Z","steps":["trace[202224433] 'process raft request' (duration: 139.653944ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:44.5471Z","caller":"traceutil/trace.go:171","msg":"trace[1264783184] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"142.560858ms","start":"2026-04-20T08:15:44.404525Z","end":"2026-04-20T08:15:44.547086Z","steps":["trace[1264783184] 'process raft request' (duration: 141.660817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:44.547218Z","caller":"traceutil/trace.go:171","msg":"trace[2107211336] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"142.393963ms","start":"2026-04-20T08:15:44.404813Z","end":"2026-04-20T08:15:44.547207Z","steps":["trace[2107211336] 'process raft request' (duration: 141.7908ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:45.388295Z","caller":"traceutil/trace.go:171","msg":"trace[164234002] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"119.804915ms","start":"2026-04-20T08:15:45.268461Z","end":"2026-04-20T08:15:45.388266Z","steps":["trace[164234002] 'process raft request' (duration: 119.56419ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:13.72188Z","caller":"traceutil/trace.go:171","msg":"trace[1265837524] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"269.675559ms","start":"2026-04-20T08:16:13.452176Z","end":"2026-04-20T08:16:13.721852Z","steps":["trace[1265837524] 'process raft request' (duration: 269.504206ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:13.848704Z","caller":"traceutil/trace.go:171","msg":"trace[256989249] linearizableReadLoop","detail":"{readStateIndex:5487; appliedIndex:5486; }","duration":"120.764065ms","start":"2026-04-20T08:16:13.727922Z","end":"2026-04-20T08:16:13.848686Z","steps":["trace[256989249] 'read index received' (duration: 75.585127ms)","trace[256989249] 'applied index is now lower than readState.Index' (duration: 45.178298ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:13.848739Z","caller":"traceutil/trace.go:171","msg":"trace[49763401] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"367.388614ms","start":"2026-04-20T08:16:13.48133Z","end":"2026-04-20T08:16:13.848719Z","steps":["trace[49763401] 'process raft request' (duration: 322.172966ms)","trace[49763401] 'compare' (duration: 45.101016ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:16:13.848835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:13.481315Z","time spent":"367.461086ms","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:13.848856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.931019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-sg786\" ","response":"range_response_count:1 size:7556"} {"level":"info","ts":"2026-04-20T08:16:13.84888Z","caller":"traceutil/trace.go:171","msg":"trace[173251632] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-sg786; range_end:; response_count:1; response_revision:5290; }","duration":"121.00441ms","start":"2026-04-20T08:16:13.727868Z","end":"2026-04-20T08:16:13.848873Z","steps":["trace[173251632] 'agreement among raft nodes before linearized reading' (duration: 120.905638ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:13.853516Z","caller":"traceutil/trace.go:171","msg":"trace[86629151] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"124.650599ms","start":"2026-04-20T08:16:13.728837Z","end":"2026-04-20T08:16:13.853487Z","steps":["trace[86629151] 'process raft request' (duration: 124.477105ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:13.853571Z","caller":"traceutil/trace.go:171","msg":"trace[1166253229] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"114.663611ms","start":"2026-04-20T08:16:13.738894Z","end":"2026-04-20T08:16:13.853557Z","steps":["trace[1166253229] 'process raft request' (duration: 114.585901ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:13.853735Z","caller":"traceutil/trace.go:171","msg":"trace[1260697803] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"124.663909ms","start":"2026-04-20T08:16:13.729047Z","end":"2026-04-20T08:16:13.853711Z","steps":["trace[1260697803] 'process raft request' (duration: 124.380553ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:20.166211Z","caller":"traceutil/trace.go:171","msg":"trace[330292197] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"118.995797ms","start":"2026-04-20T08:16:20.04718Z","end":"2026-04-20T08:16:20.166176Z","steps":["trace[330292197] 'process raft request' (duration: 22.944645ms)","trace[330292197] 'compare' (duration: 95.705655ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:20.166213Z","caller":"traceutil/trace.go:171","msg":"trace[2079296232] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"116.712978ms","start":"2026-04-20T08:16:20.049478Z","end":"2026-04-20T08:16:20.166191Z","steps":["trace[2079296232] 'process raft request' (duration: 116.606106ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:52.058909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-04-20T08:16:52.133114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"73.078645ms","hash":3972069395,"current-db-size-bytes":53645312,"current-db-size":"54 MB","current-db-size-in-use-bytes":29163520,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T08:16:52.133189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3972069395,"revision":2892,"compact-revision":-1} {"level":"warn","ts":"2026-04-20T08:17:57.324507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867251986093476,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T08:17:57.427411Z","caller":"traceutil/trace.go:171","msg":"trace[387283453] linearizableReadLoop","detail":"{readStateIndex:6182; appliedIndex:6181; }","duration":"603.341074ms","start":"2026-04-20T08:17:56.824044Z","end":"2026-04-20T08:17:57.427386Z","steps":["trace[387283453] 'read index received' (duration: 603.243542ms)","trace[387283453] 'applied index is now lower than readState.Index' (duration: 96.712µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:17:57.427531Z","caller":"traceutil/trace.go:171","msg":"trace[1156100976] transaction","detail":"{read_only:false; response_revision:5962; number_of_response:1; }","duration":"822.676673ms","start":"2026-04-20T08:17:56.604844Z","end":"2026-04-20T08:17:57.427521Z","steps":["trace[1156100976] 'process raft request' (duration: 822.412468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:57.427637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:56.60482Z","time spent":"822.739763ms","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:17:57.427675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.486949ms","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:57.427714Z","caller":"traceutil/trace.go:171","msg":"trace[536808774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5962; }","duration":"207.563633ms","start":"2026-04-20T08:17:57.220141Z","end":"2026-04-20T08:17:57.427704Z","steps":["trace[536808774] 'agreement among raft nodes before linearized reading' (duration: 207.459071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:58.112052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867251986093479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:58.612556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867251986093479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:59.112831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867251986093479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:59.28082Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.8492349s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T08:17:59.2835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.887486ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:59.283664Z","caller":"traceutil/trace.go:171","msg":"trace[1542402115] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5962; }","duration":"587.063109ms","start":"2026-04-20T08:17:58.696584Z","end":"2026-04-20T08:17:59.283647Z","steps":["trace[1542402115] 'range keys from in-memory index tree' (duration: 586.871385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.284144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.460092111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T08:17:59.284182Z","caller":"traceutil/trace.go:171","msg":"trace[480353621] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5962; }","duration":"2.460175003s","start":"2026-04-20T08:17:56.823994Z","end":"2026-04-20T08:17:59.284169Z","steps":["trace[480353621] 'agreement among raft nodes before linearized reading' (duration: 603.798084ms)","trace[480353621] 'range keys from in-memory index tree' (duration: 1.856293237s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:17:59.284209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:56.823972Z","time spent":"2.460230593s","remote":"127.0.0.1:59856","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-20T08:17:59.284853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.994492703s","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:59.284897Z","caller":"traceutil/trace.go:171","msg":"trace[1967244883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5962; }","duration":"1.994586184s","start":"2026-04-20T08:17:57.290301Z","end":"2026-04-20T08:17:59.284888Z","steps":["trace[1967244883] 'agreement among raft nodes before linearized reading' (duration: 137.457759ms)","trace[1967244883] 'range keys from in-memory index tree' (duration: 1.855879949s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:17:59.284947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:57.290274Z","time spent":"1.994666205s","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T08:17:59.284959Z","caller":"traceutil/trace.go:171","msg":"trace[1852103962] transaction","detail":"{read_only:false; response_revision:5963; number_of_response:1; }","duration":"1.853436827s","start":"2026-04-20T08:17:57.431482Z","end":"2026-04-20T08:17:59.284919Z","steps":["trace[1852103962] 'process raft request' (duration: 1.849630278s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.28514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:57.431457Z","time spent":"1.85359988s","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:59.290233Z","caller":"traceutil/trace.go:171","msg":"trace[786656084] transaction","detail":"{read_only:false; response_revision:5964; number_of_response:1; }","duration":"1.231959216s","start":"2026-04-20T08:17:58.058257Z","end":"2026-04-20T08:17:59.290216Z","steps":["trace[786656084] 'process raft request' (duration: 1.231830703s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.290412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.05823Z","time spent":"1.232119718s","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:59.290522Z","caller":"traceutil/trace.go:171","msg":"trace[733619413] linearizableReadLoop","detail":"{readStateIndex:6187; appliedIndex:6182; }","duration":"1.678967616s","start":"2026-04-20T08:17:57.611535Z","end":"2026-04-20T08:17:59.290503Z","steps":["trace[733619413] 'read index received' (duration: 1.669538369s)","trace[733619413] 'applied index is now lower than readState.Index' (duration: 9.428597ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:17:59.290587Z","caller":"traceutil/trace.go:171","msg":"trace[703310102] transaction","detail":"{read_only:false; response_revision:5967; number_of_response:1; }","duration":"1.066880802s","start":"2026-04-20T08:17:58.2237Z","end":"2026-04-20T08:17:59.290581Z","steps":["trace[703310102] 'process raft request' (duration: 1.06677009s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:59.290586Z","caller":"traceutil/trace.go:171","msg":"trace[663142488] transaction","detail":"{read_only:false; response_revision:5966; number_of_response:1; }","duration":"1.066921192s","start":"2026-04-20T08:17:58.223645Z","end":"2026-04-20T08:17:59.290566Z","steps":["trace[663142488] 'process raft request' (duration: 1.066745439s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.290658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.223685Z","time spent":"1.066918112s","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:17:59.290777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.679246741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T08:17:59.290805Z","caller":"traceutil/trace.go:171","msg":"trace[1016661096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5967; }","duration":"1.679302664s","start":"2026-04-20T08:17:57.611497Z","end":"2026-04-20T08:17:59.290799Z","steps":["trace[1016661096] 'agreement among raft nodes before linearized reading' (duration: 1.679205682s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.290819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:57.611474Z","time spent":"1.679341995s","remote":"127.0.0.1:59856","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:59.29068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.223615Z","time spent":"1.067013394s","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:59.290685Z","caller":"traceutil/trace.go:171","msg":"trace[1824447634] transaction","detail":"{read_only:false; response_revision:5965; number_of_response:1; }","duration":"1.067792992s","start":"2026-04-20T08:17:58.222885Z","end":"2026-04-20T08:17:59.290678Z","steps":["trace[1824447634] 'process raft request' (duration: 1.067296341s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.290917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.222853Z","time spent":"1.068048117s","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:17:59.294267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627936311s","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:59.294325Z","caller":"traceutil/trace.go:171","msg":"trace[991085858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5969; }","duration":"1.628035613s","start":"2026-04-20T08:17:57.666277Z","end":"2026-04-20T08:17:59.294313Z","steps":["trace[991085858] 'agreement among raft nodes before linearized reading' (duration: 1.627933321s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.294359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:57.666261Z","time spent":"1.628086214s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:17:59.294935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.569342ms","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-20T08:17:59.294965Z","caller":"traceutil/trace.go:171","msg":"trace[1762933830] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5969; }","duration":"291.645274ms","start":"2026-04-20T08:17:59.003312Z","end":"2026-04-20T08:17:59.294958Z","steps":["trace[1762933830] 'agreement among raft nodes before linearized reading' (duration: 291.571114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.295152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.78264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:59.295179Z","caller":"traceutil/trace.go:171","msg":"trace[1882747663] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5969; }","duration":"963.845982ms","start":"2026-04-20T08:17:58.331326Z","end":"2026-04-20T08:17:59.295172Z","steps":["trace[1882747663] 'agreement among raft nodes before linearized reading' (duration: 963.805412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.295195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.331307Z","time spent":"963.883622ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:59.295228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.761802ms","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:59.29536Z","caller":"traceutil/trace.go:171","msg":"trace[560471936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5969; }","duration":"897.081838ms","start":"2026-04-20T08:17:58.398263Z","end":"2026-04-20T08:17:59.295345Z","steps":["trace[560471936] 'agreement among raft nodes before linearized reading' (duration: 896.749841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.295421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.398243Z","time spent":"897.15332ms","remote":"127.0.0.1:59598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:17:59.295591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413943874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-04-20T08:17:59.295617Z","caller":"traceutil/trace.go:171","msg":"trace[1187845436] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5969; }","duration":"1.414010766s","start":"2026-04-20T08:17:57.881599Z","end":"2026-04-20T08:17:59.29561Z","steps":["trace[1187845436] 'agreement among raft nodes before linearized reading' (duration: 1.413916364s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.295633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:57.881579Z","time spent":"1.414051186s","remote":"127.0.0.1:59770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-04-20T08:17:59.295732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.793485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:59.295817Z","caller":"traceutil/trace.go:171","msg":"trace[1425402282] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:5969; }","duration":"323.895098ms","start":"2026-04-20T08:17:58.971893Z","end":"2026-04-20T08:17:59.295788Z","steps":["trace[1425402282] 'agreement among raft nodes before linearized reading' (duration: 323.797486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.295847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.97187Z","time spent":"323.969269ms","remote":"127.0.0.1:55194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:59.296012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.513642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:59.296044Z","caller":"traceutil/trace.go:171","msg":"trace[1912882060] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5969; }","duration":"336.583082ms","start":"2026-04-20T08:17:58.959451Z","end":"2026-04-20T08:17:59.296034Z","steps":["trace[1912882060] 'agreement among raft nodes before linearized reading' (duration: 336.531511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.296424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.406723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-20T08:17:59.296457Z","caller":"traceutil/trace.go:171","msg":"trace[1224201589] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5969; }","duration":"572.470395ms","start":"2026-04-20T08:17:58.723979Z","end":"2026-04-20T08:17:59.29645Z","steps":["trace[1224201589] 'agreement among raft nodes before linearized reading' (duration: 572.356952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.29651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.723961Z","time spent":"572.541596ms","remote":"127.0.0.1:59786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T08:17:59.296409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.471717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T08:17:59.296602Z","caller":"traceutil/trace.go:171","msg":"trace[1085817009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5969; }","duration":"876.720033ms","start":"2026-04-20T08:17:58.419847Z","end":"2026-04-20T08:17:59.296567Z","steps":["trace[1085817009] 'agreement among raft nodes before linearized reading' (duration: 876.384266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:59.296649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.419837Z","time spent":"876.802455ms","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T08:17:59.296919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:58.959431Z","time spent":"336.680274ms","remote":"127.0.0.1:49974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-04-20T08:21:52.066702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5583} {"level":"info","ts":"2026-04-20T08:21:52.143805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5583,"took":"75.963328ms","hash":4229971321,"current-db-size-bytes":53645312,"current-db-size":"54 MB","current-db-size-in-use-bytes":18472960,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T08:21:52.143856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4229971321,"revision":5583,"compact-revision":2892}