{"level":"warn","ts":"2026-07-14T17:40:11.504573Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:40:11.504697Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.192: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.192:2380","--initial-cluster=instance=https://199.19.213.192:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.192:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.192: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-07-14T17:40:11.504994Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:40:11.505021Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-07-14T17:40:11.505195Z","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-07-14T17:40:11.506171Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"]} {"level":"info","ts":"2026-07-14T17:40:11.50644Z","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.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.192: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-07-14T17:40:11.511354Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.525595ms"} {"level":"info","ts":"2026-07-14T17:40:11.591265Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"341a3703c39c1f5b","cluster-id":"65341987fa712ba2"} {"level":"info","ts":"2026-07-14T17:40:11.591432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T17:40:11.591547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 0"} {"level":"info","ts":"2026-07-14T17:40:11.59167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 341a3703c39c1f5b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T17:40:11.59173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 1"} {"level":"info","ts":"2026-07-14T17:40:11.591819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"warn","ts":"2026-07-14T17:40:11.666335Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T17:40:11.671766Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T17:40:11.689031Z","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-07-14T17:40:11.704608Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"341a3703c39c1f5b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T17:40:11.706326Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"341a3703c39c1f5b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T17:40:11.706497Z","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-07-14T17:40:11.706577Z","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-07-14T17:40:11.706596Z","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-07-14T17:40:11.710458Z","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-07-14T17:40:11.710606Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-07-14T17:40:11.710653Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-07-14T17:40:11.710809Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"341a3703c39c1f5b","initial-advertise-peer-urls":["https://199.19.213.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T17:40:11.710854Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T17:40:11.719061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"info","ts":"2026-07-14T17:40:11.71919Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","added-peer-id":"341a3703c39c1f5b","added-peer-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-07-14T17:40:12.592874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T17:40:12.592929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T17:40:12.592963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgPreVoteResp from 341a3703c39c1f5b at term 1"} {"level":"info","ts":"2026-07-14T17:40:12.59298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became candidate at term 2"} {"level":"info","ts":"2026-07-14T17:40:12.593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgVoteResp from 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-07-14T17:40:12.593015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became leader at term 2"} {"level":"info","ts":"2026-07-14T17:40:12.593026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 341a3703c39c1f5b elected leader 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-07-14T17:40:12.595493Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"341a3703c39c1f5b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.192:2379]}","request-path":"/0/members/341a3703c39c1f5b/attributes","cluster-id":"65341987fa712ba2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T17:40:12.595508Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:40:12.595509Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:40:12.595654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:40:12.595985Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T17:40:12.596019Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T17:40:12.598503Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.192:2379"} {"level":"info","ts":"2026-07-14T17:40:12.598885Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T17:40:12.599938Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:40:12.600017Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:40:12.60004Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:40:14.063236Z","caller":"traceutil/trace.go:171","msg":"trace[1359131736] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"112.339178ms","start":"2026-07-14T17:40:13.950851Z","end":"2026-07-14T17:40:14.06319Z","steps":["trace[1359131736] 'process raft request' (duration: 112.09946ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:14.063371Z","caller":"traceutil/trace.go:171","msg":"trace[1171735524] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"112.472592ms","start":"2026-07-14T17:40:13.950813Z","end":"2026-07-14T17:40:14.063285Z","steps":["trace[1171735524] 'process raft request' (duration: 76.773603ms)","trace[1171735524] 'compare' (duration: 35.005057ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:14.063541Z","caller":"traceutil/trace.go:171","msg":"trace[19114764] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"112.709349ms","start":"2026-07-14T17:40:13.950818Z","end":"2026-07-14T17:40:14.063528Z","steps":["trace[19114764] 'process raft request' (duration: 111.959196ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:14.063785Z","caller":"traceutil/trace.go:171","msg":"trace[891487897] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"104.435237ms","start":"2026-07-14T17:40:13.959323Z","end":"2026-07-14T17:40:14.063759Z","steps":["trace[891487897] 'process raft request' (duration: 103.926311ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:14.063842Z","caller":"traceutil/trace.go:171","msg":"trace[840616784] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:18; }","duration":"110.490479ms","start":"2026-07-14T17:40:13.953336Z","end":"2026-07-14T17:40:14.063827Z","steps":["trace[840616784] 'read index received' (duration: 11.320389ms)","trace[840616784] 'applied index is now lower than readState.Index' (duration: 99.1685ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:14.064001Z","caller":"traceutil/trace.go:171","msg":"trace[63474528] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"109.965083ms","start":"2026-07-14T17:40:13.954023Z","end":"2026-07-14T17:40:14.063988Z","steps":["trace[63474528] 'process raft request' (duration: 109.162848ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:14.064351Z","caller":"traceutil/trace.go:171","msg":"trace[1574578845] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"112.125671ms","start":"2026-07-14T17:40:13.952212Z","end":"2026-07-14T17:40:14.064337Z","steps":["trace[1574578845] 'process raft request' (duration: 110.82215ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:14.064388Z","caller":"traceutil/trace.go:171","msg":"trace[1830139197] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"111.003586ms","start":"2026-07-14T17:40:13.953361Z","end":"2026-07-14T17:40:14.064364Z","steps":["trace[1830139197] 'process raft request' (duration: 109.761927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:14.064349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.991024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-07-14T17:40:14.06445Z","caller":"traceutil/trace.go:171","msg":"trace[1640974200] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24; }","duration":"111.121029ms","start":"2026-07-14T17:40:13.953314Z","end":"2026-07-14T17:40:14.064435Z","steps":["trace[1640974200] 'agreement among raft nodes before linearized reading' (duration: 110.84003ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:14.064465Z","caller":"traceutil/trace.go:171","msg":"trace[589892647] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"112.169682ms","start":"2026-07-14T17:40:13.952282Z","end":"2026-07-14T17:40:14.064451Z","steps":["trace[589892647] 'process raft request' (duration: 110.81076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:14.064609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.039526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:40:14.064683Z","caller":"traceutil/trace.go:171","msg":"trace[1859166125] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:24; }","duration":"111.145199ms","start":"2026-07-14T17:40:13.953523Z","end":"2026-07-14T17:40:14.064668Z","steps":["trace[1859166125] 'agreement among raft nodes before linearized reading' (duration: 110.976204ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:14.064019Z","caller":"traceutil/trace.go:171","msg":"trace[172106198] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"113.090581ms","start":"2026-07-14T17:40:13.950886Z","end":"2026-07-14T17:40:14.063977Z","steps":["trace[172106198] 'process raft request' (duration: 112.123891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:15.265107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.946733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:15.265223Z","caller":"traceutil/trace.go:171","msg":"trace[389411982] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"153.861114ms","start":"2026-07-14T17:40:15.111342Z","end":"2026-07-14T17:40:15.265204Z","steps":["trace[389411982] 'process raft request' (duration: 35.344503ms)","trace[389411982] 'compare' (duration: 117.822629ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:15.820137Z","caller":"traceutil/trace.go:171","msg":"trace[1308862515] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"145.967515ms","start":"2026-07-14T17:40:15.674146Z","end":"2026-07-14T17:40:15.820113Z","steps":["trace[1308862515] 'process raft request' (duration: 138.975135ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:17.353869Z","caller":"traceutil/trace.go:171","msg":"trace[1682748136] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"130.122804ms","start":"2026-07-14T17:40:17.223723Z","end":"2026-07-14T17:40:17.353846Z","steps":["trace[1682748136] 'process raft request' (duration: 110.223473ms)","trace[1682748136] 'compare' (duration: 19.785478ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:19.543733Z","caller":"traceutil/trace.go:171","msg":"trace[1683514586] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"100.838575ms","start":"2026-07-14T17:40:19.44287Z","end":"2026-07-14T17:40:19.543709Z","steps":["trace[1683514586] 'process raft request' (duration: 100.717531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:23.412335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.226672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:23.412418Z","caller":"traceutil/trace.go:171","msg":"trace[772062749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:384; }","duration":"291.358357ms","start":"2026-07-14T17:40:23.121041Z","end":"2026-07-14T17:40:23.412399Z","steps":["trace[772062749] 'range keys from in-memory index tree' (duration: 291.119229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:23.412493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.539685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-14T17:40:23.412547Z","caller":"traceutil/trace.go:171","msg":"trace[182702743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"258.629968ms","start":"2026-07-14T17:40:23.153905Z","end":"2026-07-14T17:40:23.412535Z","steps":["trace[182702743] 'range keys from in-memory index tree' (duration: 258.441632ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:23.701665Z","caller":"traceutil/trace.go:171","msg":"trace[354852636] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"285.492389ms","start":"2026-07-14T17:40:23.416148Z","end":"2026-07-14T17:40:23.701641Z","steps":["trace[354852636] 'process raft request' (duration: 285.351314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:24.370705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.234379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:24.370905Z","caller":"traceutil/trace.go:171","msg":"trace[515375580] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"949.438268ms","start":"2026-07-14T17:40:23.421415Z","end":"2026-07-14T17:40:24.370853Z","steps":["trace[515375580] 'process raft request' (duration: 786.064694ms)","trace[515375580] 'compare' (duration: 161.729594ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:24.371057Z","caller":"traceutil/trace.go:171","msg":"trace[1959842064] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"337.567708ms","start":"2026-07-14T17:40:24.033469Z","end":"2026-07-14T17:40:24.371037Z","steps":["trace[1959842064] 'process raft request' (duration: 337.351151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:24.371134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:23.421397Z","time spent":"949.619734ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:24.371223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:24.033434Z","time spent":"337.705532ms","remote":"127.0.0.1:57270","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-07-14T17:40:24.371513Z","caller":"traceutil/trace.go:171","msg":"trace[105282531] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"644.58137ms","start":"2026-07-14T17:40:23.726918Z","end":"2026-07-14T17:40:24.3715Z","steps":["trace[105282531] 'read index received' (duration: 480.465133ms)","trace[105282531] 'applied index is now lower than readState.Index' (duration: 164.112276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:24.371712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.788086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:24.371772Z","caller":"traceutil/trace.go:171","msg":"trace[1294260464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:387; }","duration":"644.841087ms","start":"2026-07-14T17:40:23.726912Z","end":"2026-07-14T17:40:24.371753Z","steps":["trace[1294260464] 'agreement among raft nodes before linearized reading' (duration: 644.708783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:24.371815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:23.726855Z","time spent":"644.94894ms","remote":"127.0.0.1:57010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:40:42.599248Z","caller":"traceutil/trace.go:171","msg":"trace[2022667929] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"114.918935ms","start":"2026-07-14T17:40:42.484305Z","end":"2026-07-14T17:40:42.599224Z","steps":["trace[2022667929] 'process raft request' (duration: 114.72754ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:21.019464Z","caller":"traceutil/trace.go:171","msg":"trace[1427876881] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"100.106747ms","start":"2026-07-14T17:41:20.919342Z","end":"2026-07-14T17:41:21.019448Z","steps":["trace[1427876881] 'process raft request' (duration: 100.014605ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:21.486539Z","caller":"traceutil/trace.go:171","msg":"trace[271933170] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"293.151074ms","start":"2026-07-14T17:41:21.193369Z","end":"2026-07-14T17:41:21.48652Z","steps":["trace[271933170] 'process raft request' (duration: 231.414542ms)","trace[271933170] 'compare' (duration: 61.62893ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:24.581463Z","caller":"traceutil/trace.go:171","msg":"trace[631540432] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"427.948914ms","start":"2026-07-14T17:42:24.153489Z","end":"2026-07-14T17:42:24.581438Z","steps":["trace[631540432] 'process raft request' (duration: 427.727409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:24.581724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.153466Z","time spent":"428.160679ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:42:24.589229Z","caller":"traceutil/trace.go:171","msg":"trace[28227876] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"430.543729ms","start":"2026-07-14T17:42:24.158666Z","end":"2026-07-14T17:42:24.58921Z","steps":["trace[28227876] 'process raft request' (duration: 430.389575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:24.589351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.158647Z","time spent":"430.63233ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:42:24.589587Z","caller":"traceutil/trace.go:171","msg":"trace[1187804277] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"423.157042ms","start":"2026-07-14T17:42:24.166381Z","end":"2026-07-14T17:42:24.589538Z","steps":["trace[1187804277] 'process raft request' (duration: 422.863895ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:24.589623Z","caller":"traceutil/trace.go:171","msg":"trace[1736645091] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"428.822602ms","start":"2026-07-14T17:42:24.160781Z","end":"2026-07-14T17:42:24.589603Z","steps":["trace[1736645091] 'process raft request' (duration: 428.376033ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:24.589671Z","caller":"traceutil/trace.go:171","msg":"trace[1139464664] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"420.116387ms","start":"2026-07-14T17:42:24.169545Z","end":"2026-07-14T17:42:24.589662Z","steps":["trace[1139464664] 'process raft request' (duration: 419.75875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:24.589722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.160764Z","time spent":"428.905423ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:42:24.589707Z","caller":"traceutil/trace.go:171","msg":"trace[1271159733] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"404.816622ms","start":"2026-07-14T17:42:24.184876Z","end":"2026-07-14T17:42:24.589693Z","steps":["trace[1271159733] 'process raft request' (duration: 404.460724ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:24.589714Z","caller":"traceutil/trace.go:171","msg":"trace[858248607] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"424.556352ms","start":"2026-07-14T17:42:24.165075Z","end":"2026-07-14T17:42:24.589631Z","steps":["trace[858248607] 'process raft request' (duration: 424.142733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:24.589805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.184854Z","time spent":"404.922095ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:42:24.589827Z","caller":"traceutil/trace.go:171","msg":"trace[1647168063] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"420.529716ms","start":"2026-07-14T17:42:24.169285Z","end":"2026-07-14T17:42:24.589814Z","steps":["trace[1647168063] 'process raft request' (duration: 419.993454ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:24.589587Z","caller":"traceutil/trace.go:171","msg":"trace[1533885886] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"425.818748ms","start":"2026-07-14T17:42:24.163726Z","end":"2026-07-14T17:42:24.589544Z","steps":["trace[1533885886] 'process raft request' (duration: 425.46121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:24.589868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.169278Z","time spent":"420.569257ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:42:24.589913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.163715Z","time spent":"426.184816ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:42:24.589932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.166355Z","time spent":"423.317746ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:42:24.589781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.16954Z","time spent":"420.166068ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:42:24.589967Z","caller":"traceutil/trace.go:171","msg":"trace[918197943] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"260.147938ms","start":"2026-07-14T17:42:24.329811Z","end":"2026-07-14T17:42:24.589959Z","steps":["trace[918197943] 'process raft request' (duration: 259.77395ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:24.59Z","caller":"traceutil/trace.go:171","msg":"trace[267474470] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"395.431122ms","start":"2026-07-14T17:42:24.194564Z","end":"2026-07-14T17:42:24.589995Z","steps":["trace[267474470] 'process raft request' (duration: 394.82804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:24.590265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.194548Z","time spent":"395.696748ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:42:24.589965Z","caller":"traceutil/trace.go:171","msg":"trace[1271476702] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"359.157062ms","start":"2026-07-14T17:42:24.230779Z","end":"2026-07-14T17:42:24.589936Z","steps":["trace[1271476702] 'process raft request' (duration: 358.652281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:24.590361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.230759Z","time spent":"359.55929ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:42:24.590001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:24.165065Z","time spent":"424.782466ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:42:26.650125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.201349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:42:26.650365Z","caller":"traceutil/trace.go:171","msg":"trace[1810197171] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"400.813981ms","start":"2026-07-14T17:42:26.24953Z","end":"2026-07-14T17:42:26.650344Z","steps":["trace[1810197171] 'process raft request' (duration: 400.736709ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:26.650398Z","caller":"traceutil/trace.go:171","msg":"trace[574611422] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"642.101606ms","start":"2026-07-14T17:42:26.008265Z","end":"2026-07-14T17:42:26.650366Z","steps":["trace[574611422] 'read index received' (duration: 162.409347ms)","trace[574611422] 'applied index is now lower than readState.Index' (duration: 479.689879ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:26.650396Z","caller":"traceutil/trace.go:171","msg":"trace[808101451] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"650.838372ms","start":"2026-07-14T17:42:25.999508Z","end":"2026-07-14T17:42:26.650346Z","steps":["trace[808101451] 'process raft request' (duration: 171.325946ms)","trace[808101451] 'compare' (duration: 479.007665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:42:26.650447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:26.24951Z","time spent":"400.900072ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:42:26.650592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:25.999477Z","time spent":"651.010375ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:42:26.65072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.446593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-07-14T17:42:26.650765Z","caller":"traceutil/trace.go:171","msg":"trace[1447967822] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:819; }","duration":"642.533355ms","start":"2026-07-14T17:42:26.008219Z","end":"2026-07-14T17:42:26.650752Z","steps":["trace[1447967822] 'agreement among raft nodes before linearized reading' (duration: 642.29976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:26.650796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.045214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-14T17:42:26.650809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:26.008193Z","time spent":"642.604247ms","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":74791,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-07-14T17:42:26.650835Z","caller":"traceutil/trace.go:171","msg":"trace[1376996902] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:819; }","duration":"503.120206ms","start":"2026-07-14T17:42:26.147704Z","end":"2026-07-14T17:42:26.650824Z","steps":["trace[1376996902] 'agreement among raft nodes before linearized reading' (duration: 503.034054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:26.650874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:26.14766Z","time spent":"503.204887ms","remote":"127.0.0.1:57054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.192\" "} {"level":"warn","ts":"2026-07-14T17:42:30.508082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.368256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:42:30.508194Z","caller":"traceutil/trace.go:171","msg":"trace[1709153436] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"143.513691ms","start":"2026-07-14T17:42:30.364662Z","end":"2026-07-14T17:42:30.508175Z","steps":["trace[1709153436] 'process raft request' (duration: 11.983882ms)","trace[1709153436] 'compare' (duration: 131.273194ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:42:31.004541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.863279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:42:31.004726Z","caller":"traceutil/trace.go:171","msg":"trace[1274097953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"278.143745ms","start":"2026-07-14T17:42:30.726562Z","end":"2026-07-14T17:42:31.004706Z","steps":["trace[1274097953] 'range keys from in-memory index tree' (duration: 277.717116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:31.00462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.079902ms","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-07-14T17:42:31.005261Z","caller":"traceutil/trace.go:171","msg":"trace[1793122970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:844; }","duration":"326.746015ms","start":"2026-07-14T17:42:30.678484Z","end":"2026-07-14T17:42:31.00523Z","steps":["trace[1793122970] 'range keys from in-memory index tree' (duration: 325.941148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:31.005358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:30.678458Z","time spent":"326.871468ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T17:42:31.106246Z","caller":"traceutil/trace.go:171","msg":"trace[964995169] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"162.120887ms","start":"2026-07-14T17:42:30.943117Z","end":"2026-07-14T17:42:31.105238Z","steps":["trace[964995169] 'process raft request' (duration: 156.359766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:31.687469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259574880087494592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:42:32.148801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040386676s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:42:32.149114Z","caller":"traceutil/trace.go:171","msg":"trace[1232661630] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"984.718801ms","start":"2026-07-14T17:42:31.164339Z","end":"2026-07-14T17:42:32.149058Z","steps":["trace[1232661630] 'process raft request' (duration: 984.568548ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:32.149236Z","caller":"traceutil/trace.go:171","msg":"trace[249798314] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"1.139541182s","start":"2026-07-14T17:42:31.009417Z","end":"2026-07-14T17:42:32.148958Z","steps":["trace[249798314] 'process raft request' (duration: 98.89418ms)","trace[249798314] 'compare' (duration: 1.040234733s)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:42:32.149351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:31.164323Z","time spent":"984.932715ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:42:32.149405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:31.009397Z","time spent":"1.13992258s","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:42:32.160774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.917624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zxtff\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-14T17:42:32.160821Z","caller":"traceutil/trace.go:171","msg":"trace[1032296594] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zxtff; range_end:; response_count:1; response_revision:847; }","duration":"974.001276ms","start":"2026-07-14T17:42:31.186809Z","end":"2026-07-14T17:42:32.16081Z","steps":["trace[1032296594] 'agreement among raft nodes before linearized reading' (duration: 973.844742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:32.160845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:31.186767Z","time spent":"974.072657ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-zxtff\" "} {"level":"info","ts":"2026-07-14T17:42:32.160614Z","caller":"traceutil/trace.go:171","msg":"trace[825358654] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:886; }","duration":"973.692529ms","start":"2026-07-14T17:42:31.18686Z","end":"2026-07-14T17:42:32.160553Z","steps":["trace[825358654] 'read index received' (duration: 603.866173ms)","trace[825358654] 'applied index is now lower than readState.Index' (duration: 369.825446ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:42:32.161076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.249965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T17:42:32.161103Z","caller":"traceutil/trace.go:171","msg":"trace[2874724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:847; }","duration":"658.304767ms","start":"2026-07-14T17:42:31.50279Z","end":"2026-07-14T17:42:32.161095Z","steps":["trace[2874724] 'agreement among raft nodes before linearized reading' (duration: 658.238945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:32.16112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:31.502769Z","time spent":"658.346477ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T17:42:32.161308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.312957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:42:32.161389Z","caller":"traceutil/trace.go:171","msg":"trace[134220937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"434.447319ms","start":"2026-07-14T17:42:31.726918Z","end":"2026-07-14T17:42:32.161365Z","steps":["trace[134220937] 'agreement among raft nodes before linearized reading' (duration: 434.318807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:32.161433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:31.726895Z","time spent":"434.527961ms","remote":"127.0.0.1:57024","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-07-14T17:42:32.161398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.074478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-14T17:42:32.161506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.561905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:42:32.161551Z","caller":"traceutil/trace.go:171","msg":"trace[639370912] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:847; }","duration":"490.640376ms","start":"2026-07-14T17:42:31.670902Z","end":"2026-07-14T17:42:32.161543Z","steps":["trace[639370912] 'agreement among raft nodes before linearized reading' (duration: 490.555324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:32.161578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:31.670891Z","time spent":"490.680746ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:42:32.161599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.069297ms","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-07-14T17:42:32.161636Z","caller":"traceutil/trace.go:171","msg":"trace[1097396248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:847; }","duration":"162.140869ms","start":"2026-07-14T17:42:31.999486Z","end":"2026-07-14T17:42:32.161627Z","steps":["trace[1097396248] 'agreement among raft nodes before linearized reading' (duration: 162.055927ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:32.161515Z","caller":"traceutil/trace.go:171","msg":"trace[1247135211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:847; }","duration":"495.197811ms","start":"2026-07-14T17:42:31.666303Z","end":"2026-07-14T17:42:32.1615Z","steps":["trace[1247135211] 'agreement among raft nodes before linearized reading' (duration: 494.936606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:32.161749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:31.666251Z","time spent":"495.489447ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:42:47.956414Z","caller":"traceutil/trace.go:171","msg":"trace[1751136068] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"363.044214ms","start":"2026-07-14T17:42:47.59334Z","end":"2026-07-14T17:42:47.956384Z","steps":["trace[1751136068] 'read index received' (duration: 362.746788ms)","trace[1751136068] 'applied index is now lower than readState.Index' (duration: 293.786µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:47.956473Z","caller":"traceutil/trace.go:171","msg":"trace[2002446552] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"666.928848ms","start":"2026-07-14T17:42:47.289507Z","end":"2026-07-14T17:42:47.956436Z","steps":["trace[2002446552] 'process raft request' (duration: 666.684193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:47.956634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:47.289492Z","time spent":"667.057061ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:42:47.956706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.3244ms","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-07-14T17:42:47.956733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.22317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:42:47.956772Z","caller":"traceutil/trace.go:171","msg":"trace[540401901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"363.452423ms","start":"2026-07-14T17:42:47.5933Z","end":"2026-07-14T17:42:47.956753Z","steps":["trace[540401901] 'agreement among raft nodes before linearized reading' (duration: 363.252699ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:47.956794Z","caller":"traceutil/trace.go:171","msg":"trace[1502122088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"230.305722ms","start":"2026-07-14T17:42:47.726461Z","end":"2026-07-14T17:42:47.956767Z","steps":["trace[1502122088] 'agreement among raft nodes before linearized reading' (duration: 230.236451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:47.95683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:47.593274Z","time spent":"363.539754ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T17:43:17.183125Z","caller":"traceutil/trace.go:171","msg":"trace[1576849528] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"277.04604ms","start":"2026-07-14T17:43:16.906057Z","end":"2026-07-14T17:43:17.183103Z","steps":["trace[1576849528] 'process raft request' (duration: 276.900917ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:33.658812Z","caller":"traceutil/trace.go:171","msg":"trace[1221578119] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"169.503137ms","start":"2026-07-14T17:43:33.489284Z","end":"2026-07-14T17:43:33.658787Z","steps":["trace[1221578119] 'process raft request' (duration: 169.388935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:05.153471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259574880087497069,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:44:05.323926Z","caller":"traceutil/trace.go:171","msg":"trace[2019992326] linearizableReadLoop","detail":"{readStateIndex:2174; appliedIndex:2172; }","duration":"671.332955ms","start":"2026-07-14T17:44:04.652566Z","end":"2026-07-14T17:44:05.323899Z","steps":["trace[2019992326] 'read index received' (duration: 603.640423ms)","trace[2019992326] 'applied index is now lower than readState.Index' (duration: 67.691542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:44:05.324315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.725032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:44:05.324309Z","caller":"traceutil/trace.go:171","msg":"trace[1296358804] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"691.734074ms","start":"2026-07-14T17:44:04.632477Z","end":"2026-07-14T17:44:05.324211Z","steps":["trace[1296358804] 'process raft request' (duration: 691.318036ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:05.324235Z","caller":"traceutil/trace.go:171","msg":"trace[673789221] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"691.837576ms","start":"2026-07-14T17:44:04.632364Z","end":"2026-07-14T17:44:05.324202Z","steps":["trace[673789221] 'process raft request' (duration: 623.842698ms)","trace[673789221] 'compare' (duration: 67.061881ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:44:05.324303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.721512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a29a8ee2-e09e-46b3-b9d8-975113c86793\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-14T17:44:05.324416Z","caller":"traceutil/trace.go:171","msg":"trace[1494278783] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a29a8ee2-e09e-46b3-b9d8-975113c86793; range_end:; response_count:1; response_revision:2084; }","duration":"671.878295ms","start":"2026-07-14T17:44:04.652523Z","end":"2026-07-14T17:44:05.324401Z","steps":["trace[1494278783] 'agreement among raft nodes before linearized reading' (duration: 671.630091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:05.324464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:04.652501Z","time spent":"671.952436ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a29a8ee2-e09e-46b3-b9d8-975113c86793\" "} {"level":"warn","ts":"2026-07-14T17:44:05.324581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.582395ms","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-07-14T17:44:05.324631Z","caller":"traceutil/trace.go:171","msg":"trace[386270988] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2084; }","duration":"305.680557ms","start":"2026-07-14T17:44:05.018938Z","end":"2026-07-14T17:44:05.324619Z","steps":["trace[386270988] 'agreement among raft nodes before linearized reading' (duration: 305.504054ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:05.324408Z","caller":"traceutil/trace.go:171","msg":"trace[1293435338] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2084; }","duration":"645.841875ms","start":"2026-07-14T17:44:04.678539Z","end":"2026-07-14T17:44:05.324381Z","steps":["trace[1293435338] 'agreement among raft nodes before linearized reading' (duration: 645.691512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:05.324682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:05.018912Z","time spent":"305.755748ms","remote":"127.0.0.1:42926","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-07-14T17:44:05.324677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:04.632448Z","time spent":"691.982728ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:44:05.324776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.653093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:44:05.324813Z","caller":"traceutil/trace.go:171","msg":"trace[1675050240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2084; }","duration":"597.733354ms","start":"2026-07-14T17:44:04.727069Z","end":"2026-07-14T17:44:05.324803Z","steps":["trace[1675050240] 'agreement among raft nodes before linearized reading' (duration: 597.683413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:05.324843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:04.727047Z","time spent":"597.790396ms","remote":"127.0.0.1:57024","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-07-14T17:44:05.324689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:04.678526Z","time spent":"646.14975ms","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":36,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:44:05.324786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.78852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:44:05.32497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.902767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"warn","ts":"2026-07-14T17:44:05.324828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.61676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a29a8ee2-e09e-46b3-b9d8-975113c86793\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-14T17:44:05.325141Z","caller":"traceutil/trace.go:171","msg":"trace[1387725791] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2084; }","duration":"221.11562ms","start":"2026-07-14T17:44:05.10401Z","end":"2026-07-14T17:44:05.325126Z","steps":["trace[1387725791] 'agreement among raft nodes before linearized reading' (duration: 220.839125ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:05.325119Z","caller":"traceutil/trace.go:171","msg":"trace[418048380] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2084; }","duration":"147.239438ms","start":"2026-07-14T17:44:05.177862Z","end":"2026-07-14T17:44:05.325102Z","steps":["trace[418048380] 'agreement among raft nodes before linearized reading' (duration: 146.576666ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:05.325205Z","caller":"traceutil/trace.go:171","msg":"trace[1260227669] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a29a8ee2-e09e-46b3-b9d8-975113c86793; range_end:; response_count:1; response_revision:2084; }","duration":"247.14882ms","start":"2026-07-14T17:44:05.078045Z","end":"2026-07-14T17:44:05.325194Z","steps":["trace[1260227669] 'agreement among raft nodes before linearized reading' (duration: 246.225144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:05.324921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:04.632341Z","time spent":"692.061659ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:44:11.456395Z","caller":"traceutil/trace.go:171","msg":"trace[1585510496] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"102.732582ms","start":"2026-07-14T17:44:11.353634Z","end":"2026-07-14T17:44:11.456367Z","steps":["trace[1585510496] 'process raft request' (duration: 101.975738ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:12.605579Z","caller":"traceutil/trace.go:171","msg":"trace[1049496976] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"130.824813ms","start":"2026-07-14T17:44:12.474731Z","end":"2026-07-14T17:44:12.605556Z","steps":["trace[1049496976] 'process raft request' (duration: 130.68119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:17.590652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.751797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"warn","ts":"2026-07-14T17:44:17.590659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.735256ms","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-07-14T17:44:17.590714Z","caller":"traceutil/trace.go:171","msg":"trace[522171004] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2184; }","duration":"232.858498ms","start":"2026-07-14T17:44:17.357842Z","end":"2026-07-14T17:44:17.5907Z","steps":["trace[522171004] 'range keys from in-memory index tree' (duration: 232.600344ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:17.590765Z","caller":"traceutil/trace.go:171","msg":"trace[220982448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2184; }","duration":"173.866377ms","start":"2026-07-14T17:44:17.416859Z","end":"2026-07-14T17:44:17.590726Z","steps":["trace[220982448] 'range keys from in-memory index tree' (duration: 173.515501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:17.59099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.373051ms","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-07-14T17:44:17.591028Z","caller":"traceutil/trace.go:171","msg":"trace[1480160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2184; }","duration":"108.439562ms","start":"2026-07-14T17:44:17.482581Z","end":"2026-07-14T17:44:17.591021Z","steps":["trace[1480160] 'range keys from in-memory index tree' (duration: 107.79758ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:39.980521Z","caller":"traceutil/trace.go:171","msg":"trace[824384330] transaction","detail":"{read_only:false; response_revision:2321; number_of_response:1; }","duration":"338.816696ms","start":"2026-07-14T17:44:39.641678Z","end":"2026-07-14T17:44:39.980495Z","steps":["trace[824384330] 'process raft request' (duration: 338.679084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:39.980704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:39.641647Z","time spent":"338.945179ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:44:39.985888Z","caller":"traceutil/trace.go:171","msg":"trace[1110577403] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"298.282419ms","start":"2026-07-14T17:44:39.687594Z","end":"2026-07-14T17:44:39.985876Z","steps":["trace[1110577403] 'process raft request' (duration: 298.070635ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:39.98591Z","caller":"traceutil/trace.go:171","msg":"trace[132388424] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"296.365226ms","start":"2026-07-14T17:44:39.689521Z","end":"2026-07-14T17:44:39.985886Z","steps":["trace[132388424] 'process raft request' (duration: 296.258174ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:39.985941Z","caller":"traceutil/trace.go:171","msg":"trace[46378515] linearizableReadLoop","detail":"{readStateIndex:2423; appliedIndex:2419; }","duration":"268.228766ms","start":"2026-07-14T17:44:39.71769Z","end":"2026-07-14T17:44:39.985919Z","steps":["trace[46378515] 'read index received' (duration: 262.685969ms)","trace[46378515] 'applied index is now lower than readState.Index' (duration: 5.542187ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:44:39.986106Z","caller":"traceutil/trace.go:171","msg":"trace[700006643] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"296.177493ms","start":"2026-07-14T17:44:39.689921Z","end":"2026-07-14T17:44:39.986099Z","steps":["trace[700006643] 'process raft request' (duration: 295.913948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:39.986294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.325557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-14T17:44:39.986331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.369157ms","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-07-14T17:44:39.986365Z","caller":"traceutil/trace.go:171","msg":"trace[2073209006] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2324; }","duration":"245.435268ms","start":"2026-07-14T17:44:39.740922Z","end":"2026-07-14T17:44:39.986357Z","steps":["trace[2073209006] 'agreement among raft nodes before linearized reading' (duration: 245.306126ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:39.986365Z","caller":"traceutil/trace.go:171","msg":"trace[380991071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2324; }","duration":"268.412088ms","start":"2026-07-14T17:44:39.717935Z","end":"2026-07-14T17:44:39.986347Z","steps":["trace[380991071] 'agreement among raft nodes before linearized reading' (duration: 268.172914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:39.986526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.831056ms","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-07-14T17:44:39.986567Z","caller":"traceutil/trace.go:171","msg":"trace[98692530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2324; }","duration":"268.874177ms","start":"2026-07-14T17:44:39.717684Z","end":"2026-07-14T17:44:39.986558Z","steps":["trace[98692530] 'agreement among raft nodes before linearized reading' (duration: 268.777615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:39.986595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.90356ms","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-07-14T17:44:39.986622Z","caller":"traceutil/trace.go:171","msg":"trace[41295658] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2324; }","duration":"259.945691ms","start":"2026-07-14T17:44:39.72667Z","end":"2026-07-14T17:44:39.986615Z","steps":["trace[41295658] 'agreement among raft nodes before linearized reading' (duration: 259.88083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:39.986651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.774055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:44:39.986684Z","caller":"traceutil/trace.go:171","msg":"trace[1860132462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2324; }","duration":"260.827717ms","start":"2026-07-14T17:44:39.725847Z","end":"2026-07-14T17:44:39.986675Z","steps":["trace[1860132462] 'agreement among raft nodes before linearized reading' (duration: 260.781196ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:40.113316Z","caller":"traceutil/trace.go:171","msg":"trace[605625888] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"122.198219ms","start":"2026-07-14T17:44:39.991098Z","end":"2026-07-14T17:44:40.113297Z","steps":["trace[605625888] 'process raft request' (duration: 94.546257ms)","trace[605625888] 'compare' (duration: 27.507099ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:44:40.113671Z","caller":"traceutil/trace.go:171","msg":"trace[1064374372] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"121.928134ms","start":"2026-07-14T17:44:39.991712Z","end":"2026-07-14T17:44:40.11364Z","steps":["trace[1064374372] 'process raft request' (duration: 121.556517ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:40.87837Z","caller":"traceutil/trace.go:171","msg":"trace[1928249583] linearizableReadLoop","detail":"{readStateIndex:2427; appliedIndex:2426; }","duration":"333.241004ms","start":"2026-07-14T17:44:40.545106Z","end":"2026-07-14T17:44:40.878347Z","steps":["trace[1928249583] 'read index received' (duration: 333.120932ms)","trace[1928249583] 'applied index is now lower than readState.Index' (duration: 119.062µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:44:40.878453Z","caller":"traceutil/trace.go:171","msg":"trace[217557274] transaction","detail":"{read_only:false; response_revision:2328; number_of_response:1; }","duration":"350.975423ms","start":"2026-07-14T17:44:40.527434Z","end":"2026-07-14T17:44:40.878409Z","steps":["trace[217557274] 'process raft request' (duration: 350.754489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:40.878553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.425767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:44:40.878595Z","caller":"traceutil/trace.go:171","msg":"trace[242777187] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2328; }","duration":"333.501908ms","start":"2026-07-14T17:44:40.545084Z","end":"2026-07-14T17:44:40.878585Z","steps":["trace[242777187] 'agreement among raft nodes before linearized reading' (duration: 333.415826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:40.878604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.392047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:44:40.878644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:40.527417Z","time spent":"351.129485ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:44:40.878694Z","caller":"traceutil/trace.go:171","msg":"trace[1034217249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2328; }","duration":"151.531679ms","start":"2026-07-14T17:44:40.727142Z","end":"2026-07-14T17:44:40.878674Z","steps":["trace[1034217249] 'agreement among raft nodes before linearized reading' (duration: 151.364497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:40.878721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.576301ms","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-07-14T17:44:40.878793Z","caller":"traceutil/trace.go:171","msg":"trace[1681347610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2328; }","duration":"143.709213ms","start":"2026-07-14T17:44:40.735066Z","end":"2026-07-14T17:44:40.878775Z","steps":["trace[1681347610] 'agreement among raft nodes before linearized reading' (duration: 143.465109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:40.87863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:40.545074Z","time spent":"333.54576ms","remote":"127.0.0.1:57116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:44:42.144287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.240261ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-14T17:44:42.144402Z","caller":"traceutil/trace.go:171","msg":"trace[637889766] linearizableReadLoop","detail":"{readStateIndex:2431; appliedIndex:2430; }","duration":"417.005643ms","start":"2026-07-14T17:44:41.727382Z","end":"2026-07-14T17:44:42.144387Z","steps":["trace[637889766] 'read index received' (duration: 54.595519ms)","trace[637889766] 'applied index is now lower than readState.Index' (duration: 362.408654ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:44:42.144492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.111426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:44:42.144525Z","caller":"traceutil/trace.go:171","msg":"trace[1894417598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2331; }","duration":"417.187917ms","start":"2026-07-14T17:44:41.727327Z","end":"2026-07-14T17:44:42.144515Z","steps":["trace[1894417598] 'agreement among raft nodes before linearized reading' (duration: 417.126996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.14456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:41.727303Z","time spent":"417.248968ms","remote":"127.0.0.1:57024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:44:42.468884Z","caller":"traceutil/trace.go:171","msg":"trace[980401062] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"477.487911ms","start":"2026-07-14T17:44:41.991374Z","end":"2026-07-14T17:44:42.468862Z","steps":["trace[980401062] 'process raft request' (duration: 477.308608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.469024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:41.991353Z","time spent":"477.600173ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:44:42.94721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.643945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:44:42.947388Z","caller":"traceutil/trace.go:171","msg":"trace[1809397091] linearizableReadLoop","detail":"{readStateIndex:2434; appliedIndex:2432; }","duration":"802.942869ms","start":"2026-07-14T17:44:42.144427Z","end":"2026-07-14T17:44:42.94737Z","steps":["trace[1809397091] 'read index received' (duration: 324.398299ms)","trace[1809397091] 'applied index is now lower than readState.Index' (duration: 478.54344ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:44:42.947641Z","caller":"traceutil/trace.go:171","msg":"trace[1708492905] transaction","detail":"{read_only:false; response_revision:2334; number_of_response:1; }","duration":"954.675078ms","start":"2026-07-14T17:44:41.992954Z","end":"2026-07-14T17:44:42.947629Z","steps":["trace[1708492905] 'process raft request' (duration: 954.345382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.947643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.816869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:44:42.947708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:41.992945Z","time spent":"954.726698ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:44:42.947726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11116637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:44:42.947732Z","caller":"traceutil/trace.go:171","msg":"trace[1363978545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2334; }","duration":"801.967052ms","start":"2026-07-14T17:44:42.145748Z","end":"2026-07-14T17:44:42.947715Z","steps":["trace[1363978545] 'agreement among raft nodes before linearized reading' (duration: 801.83255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.947778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:42.145735Z","time spent":"802.031793ms","remote":"127.0.0.1:57010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:44:42.94774Z","caller":"traceutil/trace.go:171","msg":"trace[995146132] transaction","detail":"{read_only:false; response_revision:2333; number_of_response:1; }","duration":"956.124532ms","start":"2026-07-14T17:44:41.991588Z","end":"2026-07-14T17:44:42.947713Z","steps":["trace[995146132] 'process raft request' (duration: 741.897868ms)","trace[995146132] 'compare' (duration: 213.507622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:44:42.947812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.857577ms","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-07-14T17:44:42.947861Z","caller":"traceutil/trace.go:171","msg":"trace[1810146733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2334; }","duration":"829.931048ms","start":"2026-07-14T17:44:42.117915Z","end":"2026-07-14T17:44:42.947846Z","steps":["trace[1810146733] 'agreement among raft nodes before linearized reading' (duration: 829.823226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.9479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:42.117894Z","time spent":"829.997469ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:44:42.947917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:41.991572Z","time spent":"956.257224ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:44:42.947938Z","caller":"traceutil/trace.go:171","msg":"trace[1161443436] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2334; }","duration":"1.111306053s","start":"2026-07-14T17:44:41.836505Z","end":"2026-07-14T17:44:42.947811Z","steps":["trace[1161443436] 'agreement among raft nodes before linearized reading' (duration: 1.11117556s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.948052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.952652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-07-14T17:44:42.948041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:41.836488Z","time spent":"1.111538547s","remote":"127.0.0.1:57298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-14T17:44:42.948105Z","caller":"traceutil/trace.go:171","msg":"trace[958875331] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2334; }","duration":"217.076124ms","start":"2026-07-14T17:44:42.731019Z","end":"2026-07-14T17:44:42.948095Z","steps":["trace[958875331] 'agreement among raft nodes before linearized reading' (duration: 216.953942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.947706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.921568ms","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-07-14T17:44:42.94826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.524368ms","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-07-14T17:44:42.948278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.859534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c238fee7133cbc\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-14T17:44:42.948293Z","caller":"traceutil/trace.go:171","msg":"trace[1957823848] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2334; }","duration":"937.57669ms","start":"2026-07-14T17:44:42.010705Z","end":"2026-07-14T17:44:42.948282Z","steps":["trace[1957823848] 'agreement among raft nodes before linearized reading' (duration: 937.490538ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:42.948281Z","caller":"traceutil/trace.go:171","msg":"trace[292760843] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2334; }","duration":"895.519339ms","start":"2026-07-14T17:44:42.052749Z","end":"2026-07-14T17:44:42.948269Z","steps":["trace[292760843] 'agreement among raft nodes before linearized reading' (duration: 894.781066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.948323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:42.010695Z","time spent":"937.622031ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T17:44:42.94833Z","caller":"traceutil/trace.go:171","msg":"trace[1877617437] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c238fee7133cbc; range_end:; response_count:1; response_revision:2334; }","duration":"681.921995ms","start":"2026-07-14T17:44:42.266378Z","end":"2026-07-14T17:44:42.9483Z","steps":["trace[1877617437] 'agreement among raft nodes before linearized reading' (duration: 681.764393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.948368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:42.26635Z","time spent":"682.011656ms","remote":"127.0.0.1:57106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c238fee7133cbc\" "} {"level":"warn","ts":"2026-07-14T17:44:42.948071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.177942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-14T17:44:42.948553Z","caller":"traceutil/trace.go:171","msg":"trace[856051878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2334; }","duration":"830.656571ms","start":"2026-07-14T17:44:42.117857Z","end":"2026-07-14T17:44:42.948514Z","steps":["trace[856051878] 'agreement among raft nodes before linearized reading' (duration: 830.135122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.948332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:42.05274Z","time spent":"895.58043ms","remote":"127.0.0.1:42926","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-07-14T17:44:42.949843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:42.117842Z","time spent":"831.985444ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:44:43.256823Z","caller":"traceutil/trace.go:171","msg":"trace[1937087588] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"302.987624ms","start":"2026-07-14T17:44:42.953813Z","end":"2026-07-14T17:44:43.2568Z","steps":["trace[1937087588] 'process raft request' (duration: 302.264901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:43.256963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:42.953787Z","time spent":"303.102146ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:44:43.257042Z","caller":"traceutil/trace.go:171","msg":"trace[1928934043] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"303.157476ms","start":"2026-07-14T17:44:42.953864Z","end":"2026-07-14T17:44:43.257022Z","steps":["trace[1928934043] 'process raft request' (duration: 302.850811ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:43.257112Z","caller":"traceutil/trace.go:171","msg":"trace[780276505] linearizableReadLoop","detail":"{readStateIndex:2438; appliedIndex:2434; }","duration":"298.931904ms","start":"2026-07-14T17:44:42.958165Z","end":"2026-07-14T17:44:43.257097Z","steps":["trace[780276505] 'read index received' (duration: 85.420155ms)","trace[780276505] 'applied index is now lower than readState.Index' (duration: 213.510149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:44:43.257159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:42.95384Z","time spent":"303.254239ms","remote":"127.0.0.1:57270","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-07-14T17:44:43.257127Z","caller":"traceutil/trace.go:171","msg":"trace[437443910] transaction","detail":"{read_only:false; response_revision:2338; number_of_response:1; }","duration":"303.062075ms","start":"2026-07-14T17:44:42.954032Z","end":"2026-07-14T17:44:43.257094Z","steps":["trace[437443910] 'process raft request' (duration: 302.895292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:43.257228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.068186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:44:43.257239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.72467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:44:43.257261Z","caller":"traceutil/trace.go:171","msg":"trace[186342473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2339; }","duration":"299.132716ms","start":"2026-07-14T17:44:42.958111Z","end":"2026-07-14T17:44:43.257244Z","steps":["trace[186342473] 'agreement among raft nodes before linearized reading' (duration: 299.060185ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:43.257278Z","caller":"traceutil/trace.go:171","msg":"trace[1949040103] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2339; }","duration":"298.788261ms","start":"2026-07-14T17:44:42.958479Z","end":"2026-07-14T17:44:43.257268Z","steps":["trace[1949040103] 'agreement among raft nodes before linearized reading' (duration: 298.72732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:43.257299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.771451ms","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-07-14T17:44:43.257305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:42.954024Z","time spent":"303.194598ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:44:43.257327Z","caller":"traceutil/trace.go:171","msg":"trace[405803047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2339; }","duration":"271.833863ms","start":"2026-07-14T17:44:42.985485Z","end":"2026-07-14T17:44:43.257319Z","steps":["trace[405803047] 'agreement among raft nodes before linearized reading' (duration: 271.743021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:43.257322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.462945ms","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-07-14T17:44:43.257408Z","caller":"traceutil/trace.go:171","msg":"trace[818301765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2339; }","duration":"290.584518ms","start":"2026-07-14T17:44:42.966815Z","end":"2026-07-14T17:44:43.2574Z","steps":["trace[818301765] 'agreement among raft nodes before linearized reading' (duration: 290.430235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:43.257424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.183076ms","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-07-14T17:44:43.257726Z","caller":"traceutil/trace.go:171","msg":"trace[1893803940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2339; }","duration":"208.548742ms","start":"2026-07-14T17:44:43.049156Z","end":"2026-07-14T17:44:43.257705Z","steps":["trace[1893803940] 'agreement among raft nodes before linearized reading' (duration: 208.088674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:45:47.224785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259574880087498680,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:45:47.262983Z","caller":"traceutil/trace.go:171","msg":"trace[2126583451] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"555.264007ms","start":"2026-07-14T17:45:46.707695Z","end":"2026-07-14T17:45:47.262959Z","steps":["trace[2126583451] 'process raft request' (duration: 555.138435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:45:47.263123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:45:46.707669Z","time spent":"555.378949ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:45:47.301413Z","caller":"traceutil/trace.go:171","msg":"trace[1162955327] linearizableReadLoop","detail":"{readStateIndex:2900; appliedIndex:2897; }","duration":"577.412594ms","start":"2026-07-14T17:45:46.723974Z","end":"2026-07-14T17:45:47.301387Z","steps":["trace[1162955327] 'read index received' (duration: 538.829254ms)","trace[1162955327] 'applied index is now lower than readState.Index' (duration: 38.58236ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:45:47.301475Z","caller":"traceutil/trace.go:171","msg":"trace[133726747] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"120.991648ms","start":"2026-07-14T17:45:47.180448Z","end":"2026-07-14T17:45:47.30144Z","steps":["trace[133726747] 'process raft request' (duration: 120.750744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:45:47.301553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.567767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:45:47.301589Z","caller":"traceutil/trace.go:171","msg":"trace[2026782282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2785; }","duration":"577.651848ms","start":"2026-07-14T17:45:46.723924Z","end":"2026-07-14T17:45:47.301576Z","steps":["trace[2026782282] 'agreement among raft nodes before linearized reading' (duration: 577.561206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:45:47.301625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:45:46.723912Z","time spent":"577.704809ms","remote":"127.0.0.1:57024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:45:47.301976Z","caller":"traceutil/trace.go:171","msg":"trace[1997289242] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"116.105867ms","start":"2026-07-14T17:45:47.185828Z","end":"2026-07-14T17:45:47.301934Z","steps":["trace[1997289242] 'process raft request' (duration: 115.513277ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:19.661868Z","caller":"traceutil/trace.go:171","msg":"trace[1324891623] transaction","detail":"{read_only:false; response_revision:2959; number_of_response:1; }","duration":"282.754348ms","start":"2026-07-14T17:46:19.379092Z","end":"2026-07-14T17:46:19.661846Z","steps":["trace[1324891623] 'process raft request' (duration: 282.620105ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:19.736119Z","caller":"traceutil/trace.go:171","msg":"trace[1869382856] transaction","detail":"{read_only:false; response_revision:2960; number_of_response:1; }","duration":"325.828511ms","start":"2026-07-14T17:46:19.410266Z","end":"2026-07-14T17:46:19.736095Z","steps":["trace[1869382856] 'process raft request' (duration: 325.642328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:19.736266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:19.410235Z","time spent":"325.955283ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:46:19.736419Z","caller":"traceutil/trace.go:171","msg":"trace[2097230204] transaction","detail":"{read_only:false; response_revision:2961; number_of_response:1; }","duration":"324.027642ms","start":"2026-07-14T17:46:19.412348Z","end":"2026-07-14T17:46:19.736376Z","steps":["trace[2097230204] 'process raft request' (duration: 323.709557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:19.736661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:19.412295Z","time spent":"324.267485ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:46:20.123572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.951697ms","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-07-14T17:46:20.123785Z","caller":"traceutil/trace.go:171","msg":"trace[1184451040] transaction","detail":"{read_only:false; response_revision:2962; number_of_response:1; }","duration":"368.139571ms","start":"2026-07-14T17:46:19.755614Z","end":"2026-07-14T17:46:20.123754Z","steps":["trace[1184451040] 'process raft request' (duration: 273.543697ms)","trace[1184451040] 'compare' (duration: 94.134286ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:46:20.12396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:19.755598Z","time spent":"368.279423ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:46:20.123747Z","caller":"traceutil/trace.go:171","msg":"trace[1468956516] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2961; }","duration":"183.14293ms","start":"2026-07-14T17:46:19.940579Z","end":"2026-07-14T17:46:20.123722Z","steps":["trace[1468956516] 'range keys from in-memory index tree' (duration: 182.927597ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:20.138766Z","caller":"traceutil/trace.go:171","msg":"trace[92221907] linearizableReadLoop","detail":"{readStateIndex:3086; appliedIndex:3082; }","duration":"350.893669ms","start":"2026-07-14T17:46:19.787857Z","end":"2026-07-14T17:46:20.138751Z","steps":["trace[92221907] 'read index received' (duration: 241.31803ms)","trace[92221907] 'applied index is now lower than readState.Index' (duration: 109.574639ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:46:20.138923Z","caller":"traceutil/trace.go:171","msg":"trace[1035648214] transaction","detail":"{read_only:false; response_revision:2963; number_of_response:1; }","duration":"383.123115ms","start":"2026-07-14T17:46:19.755787Z","end":"2026-07-14T17:46:20.13891Z","steps":["trace[1035648214] 'process raft request' (duration: 382.80077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:20.139487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:19.755777Z","time spent":"383.636704ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:46:20.139773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.867035ms","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-07-14T17:46:20.139833Z","caller":"traceutil/trace.go:171","msg":"trace[967374808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2965; }","duration":"351.945116ms","start":"2026-07-14T17:46:19.787876Z","end":"2026-07-14T17:46:20.139821Z","steps":["trace[967374808] 'agreement among raft nodes before linearized reading' (duration: 351.841184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:20.139921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:19.787865Z","time spent":"352.048368ms","remote":"127.0.0.1:57270","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-07-14T17:46:20.139113Z","caller":"traceutil/trace.go:171","msg":"trace[1159030191] transaction","detail":"{read_only:false; response_revision:2964; number_of_response:1; }","duration":"383.210317ms","start":"2026-07-14T17:46:19.755892Z","end":"2026-07-14T17:46:20.139103Z","steps":["trace[1159030191] 'process raft request' (duration: 382.795611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:20.140175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:19.755885Z","time spent":"384.262254ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:46:20.1404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.908086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c23913dfbc065a\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-14T17:46:20.140501Z","caller":"traceutil/trace.go:171","msg":"trace[248614397] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c23913dfbc065a; range_end:; response_count:1; response_revision:2965; }","duration":"224.058288ms","start":"2026-07-14T17:46:19.916434Z","end":"2026-07-14T17:46:20.140492Z","steps":["trace[248614397] 'agreement among raft nodes before linearized reading' (duration: 223.904756ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:20.139147Z","caller":"traceutil/trace.go:171","msg":"trace[1525092631] transaction","detail":"{read_only:false; response_revision:2965; number_of_response:1; }","duration":"383.247047ms","start":"2026-07-14T17:46:19.755893Z","end":"2026-07-14T17:46:20.13914Z","steps":["trace[1525092631] 'process raft request' (duration: 382.82587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:20.14085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:19.755874Z","time spent":"384.906355ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:46:20.139269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.405758ms","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-07-14T17:46:20.141138Z","caller":"traceutil/trace.go:171","msg":"trace[2133698523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2965; }","duration":"353.306149ms","start":"2026-07-14T17:46:19.787822Z","end":"2026-07-14T17:46:20.141128Z","steps":["trace[2133698523] 'agreement among raft nodes before linearized reading' (duration: 351.343686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:20.141205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:19.787806Z","time spent":"353.39063ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T17:46:20.352355Z","caller":"traceutil/trace.go:171","msg":"trace[381655663] transaction","detail":"{read_only:false; response_revision:2968; number_of_response:1; }","duration":"204.909225ms","start":"2026-07-14T17:46:20.147422Z","end":"2026-07-14T17:46:20.352331Z","steps":["trace[381655663] 'process raft request' (duration: 204.869364ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:20.3526Z","caller":"traceutil/trace.go:171","msg":"trace[2023643703] transaction","detail":"{read_only:false; response_revision:2967; number_of_response:1; }","duration":"205.389952ms","start":"2026-07-14T17:46:20.1472Z","end":"2026-07-14T17:46:20.35259Z","steps":["trace[2023643703] 'process raft request' (duration: 205.026586ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:20.352982Z","caller":"traceutil/trace.go:171","msg":"trace[1797991524] transaction","detail":"{read_only:false; response_revision:2966; number_of_response:1; }","duration":"206.078224ms","start":"2026-07-14T17:46:20.146869Z","end":"2026-07-14T17:46:20.352947Z","steps":["trace[1797991524] 'process raft request' (duration: 200.94506ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:32.716497Z","caller":"traceutil/trace.go:171","msg":"trace[1442449634] linearizableReadLoop","detail":"{readStateIndex:3482; appliedIndex:3480; }","duration":"109.280395ms","start":"2026-07-14T17:47:32.607189Z","end":"2026-07-14T17:47:32.716469Z","steps":["trace[1442449634] 'read index received' (duration: 73.047578ms)","trace[1442449634] 'applied index is now lower than readState.Index' (duration: 36.231357ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:47:32.716627Z","caller":"traceutil/trace.go:171","msg":"trace[2096780572] transaction","detail":"{read_only:false; response_revision:3345; number_of_response:1; }","duration":"121.170628ms","start":"2026-07-14T17:47:32.595442Z","end":"2026-07-14T17:47:32.716612Z","steps":["trace[2096780572] 'process raft request' (duration: 101.317663ms)","trace[2096780572] 'compare' (duration: 19.573425ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:47:32.716769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.570425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-14T17:47:32.716851Z","caller":"traceutil/trace.go:171","msg":"trace[1534966701] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3345; }","duration":"109.659758ms","start":"2026-07-14T17:47:32.607172Z","end":"2026-07-14T17:47:32.716832Z","steps":["trace[1534966701] 'agreement among raft nodes before linearized reading' (duration: 109.457261ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:53.028836Z","caller":"traceutil/trace.go:171","msg":"trace[289532483] transaction","detail":"{read_only:false; response_revision:3449; number_of_response:1; }","duration":"119.23919ms","start":"2026-07-14T17:47:52.909579Z","end":"2026-07-14T17:47:53.028818Z","steps":["trace[289532483] 'process raft request' (duration: 119.136426ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:53.030539Z","caller":"traceutil/trace.go:171","msg":"trace[344427028] transaction","detail":"{read_only:false; response_revision:3450; number_of_response:1; }","duration":"120.523324ms","start":"2026-07-14T17:47:52.909724Z","end":"2026-07-14T17:47:53.030248Z","steps":["trace[344427028] 'process raft request' (duration: 120.284816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:03.596648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.800057ms","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-07-14T17:50:03.596743Z","caller":"traceutil/trace.go:171","msg":"trace[520568989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4326; }","duration":"116.949251ms","start":"2026-07-14T17:50:03.479775Z","end":"2026-07-14T17:50:03.596724Z","steps":["trace[520568989] 'range keys from in-memory index tree' (duration: 116.611212ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:11.514934Z","caller":"traceutil/trace.go:171","msg":"trace[93917105] linearizableReadLoop","detail":"{readStateIndex:4988; appliedIndex:4987; }","duration":"104.196237ms","start":"2026-07-14T17:50:11.41072Z","end":"2026-07-14T17:50:11.514917Z","steps":["trace[93917105] 'read index received' (duration: 77.066603ms)","trace[93917105] 'applied index is now lower than readState.Index' (duration: 27.128924ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:50:11.514986Z","caller":"traceutil/trace.go:171","msg":"trace[89181826] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"149.543629ms","start":"2026-07-14T17:50:11.36543Z","end":"2026-07-14T17:50:11.514974Z","steps":["trace[89181826] 'process raft request' (duration: 122.402265ms)","trace[89181826] 'compare' (duration: 26.659331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:50:11.515632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.903646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-14T17:50:11.51566Z","caller":"traceutil/trace.go:171","msg":"trace[1602002583] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4795; }","duration":"104.970468ms","start":"2026-07-14T17:50:11.410681Z","end":"2026-07-14T17:50:11.515651Z","steps":["trace[1602002583] 'agreement among raft nodes before linearized reading' (duration: 104.33191ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:12.621026Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2498} {"level":"info","ts":"2026-07-14T17:50:12.69564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2498,"took":"73.45984ms","hash":3029817185,"current-db-size-bytes":48799744,"current-db-size":"49 MB","current-db-size-in-use-bytes":26013696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-14T17:50:12.695695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3029817185,"revision":2498,"compact-revision":-1} {"level":"warn","ts":"2026-07-14T17:50:18.151003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.395718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:50:18.151141Z","caller":"traceutil/trace.go:171","msg":"trace[1597444956] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"155.695818ms","start":"2026-07-14T17:50:17.995426Z","end":"2026-07-14T17:50:18.151122Z","steps":["trace[1597444956] 'process raft request' (duration: 38.096814ms)","trace[1597444956] 'compare' (duration: 117.265895ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:50:18.981887Z","caller":"traceutil/trace.go:171","msg":"trace[211100776] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"208.037089ms","start":"2026-07-14T17:50:18.773827Z","end":"2026-07-14T17:50:18.981864Z","steps":["trace[211100776] 'process raft request' (duration: 207.898855ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:19.022869Z","caller":"traceutil/trace.go:171","msg":"trace[1166053989] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"164.29931ms","start":"2026-07-14T17:50:18.858551Z","end":"2026-07-14T17:50:19.02285Z","steps":["trace[1166053989] 'process raft request' (duration: 164.260289ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:19.02304Z","caller":"traceutil/trace.go:171","msg":"trace[1803401301] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"191.615272ms","start":"2026-07-14T17:50:18.831418Z","end":"2026-07-14T17:50:19.023034Z","steps":["trace[1803401301] 'process raft request' (duration: 191.287163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:24.052185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.294261ms","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-07-14T17:50:24.052398Z","caller":"traceutil/trace.go:171","msg":"trace[1196796009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5024; }","duration":"323.531688ms","start":"2026-07-14T17:50:23.728839Z","end":"2026-07-14T17:50:24.052371Z","steps":["trace[1196796009] 'range keys from in-memory index tree' (duration: 323.153977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:24.052867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.729409ms","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":"warn","ts":"2026-07-14T17:50:24.052908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.36456ms","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-07-14T17:50:24.052966Z","caller":"traceutil/trace.go:171","msg":"trace[1266416175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5024; }","duration":"165.874333ms","start":"2026-07-14T17:50:23.887072Z","end":"2026-07-14T17:50:24.052946Z","steps":["trace[1266416175] 'range keys from in-memory index tree' (duration: 165.191485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:24.053025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:50:23.728831Z","time spent":"324.082562ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:50:24.052193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.768645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:50:24.053275Z","caller":"traceutil/trace.go:171","msg":"trace[747785535] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5024; }","duration":"181.866144ms","start":"2026-07-14T17:50:23.871388Z","end":"2026-07-14T17:50:24.053254Z","steps":["trace[747785535] 'count revisions from in-memory index tree' (duration: 180.650582ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:24.052951Z","caller":"traceutil/trace.go:171","msg":"trace[1637127042] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5024; }","duration":"162.836172ms","start":"2026-07-14T17:50:23.890097Z","end":"2026-07-14T17:50:24.052933Z","steps":["trace[1637127042] 'range keys from in-memory index tree' (duration: 162.529304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:24.052552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.938725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:50:24.052294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.786935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b89c3495-e807-4008-b17a-feb9e59558e2\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-14T17:50:24.053681Z","caller":"traceutil/trace.go:171","msg":"trace[443866222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5024; }","duration":"326.082196ms","start":"2026-07-14T17:50:23.727572Z","end":"2026-07-14T17:50:24.053654Z","steps":["trace[443866222] 'range keys from in-memory index tree' (duration: 324.858873ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:24.053679Z","caller":"traceutil/trace.go:171","msg":"trace[847915067] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b89c3495-e807-4008-b17a-feb9e59558e2; range_end:; response_count:1; response_revision:5024; }","duration":"424.222733ms","start":"2026-07-14T17:50:23.62944Z","end":"2026-07-14T17:50:24.053663Z","steps":["trace[847915067] 'range keys from in-memory index tree' (duration: 422.578758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:24.053751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:50:23.727553Z","time spent":"326.180288ms","remote":"127.0.0.1:57024","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-07-14T17:50:24.053853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:50:23.629423Z","time spent":"424.313005ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b89c3495-e807-4008-b17a-feb9e59558e2\" "} {"level":"info","ts":"2026-07-14T17:50:24.234612Z","caller":"traceutil/trace.go:171","msg":"trace[1072020164] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"176.238078ms","start":"2026-07-14T17:50:24.058357Z","end":"2026-07-14T17:50:24.234595Z","steps":["trace[1072020164] 'process raft request' (duration: 176.143105ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:24.249887Z","caller":"traceutil/trace.go:171","msg":"trace[1805716137] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"191.128739ms","start":"2026-07-14T17:50:24.058742Z","end":"2026-07-14T17:50:24.249871Z","steps":["trace[1805716137] 'process raft request' (duration: 190.80161ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:24.249939Z","caller":"traceutil/trace.go:171","msg":"trace[644183430] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"190.852612ms","start":"2026-07-14T17:50:24.059032Z","end":"2026-07-14T17:50:24.249884Z","steps":["trace[644183430] 'process raft request' (duration: 190.727418ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:24.250134Z","caller":"traceutil/trace.go:171","msg":"trace[106710717] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"191.025536ms","start":"2026-07-14T17:50:24.059091Z","end":"2026-07-14T17:50:24.250117Z","steps":["trace[106710717] 'process raft request' (duration: 190.752599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:24.948424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.348152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-14T17:50:24.948578Z","caller":"traceutil/trace.go:171","msg":"trace[656885918] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5032; }","duration":"241.550857ms","start":"2026-07-14T17:50:24.707011Z","end":"2026-07-14T17:50:24.948562Z","steps":["trace[656885918] 'range keys from in-memory index tree' (duration: 241.147537ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:28.036013Z","caller":"traceutil/trace.go:171","msg":"trace[506468169] linearizableReadLoop","detail":"{readStateIndex:5281; appliedIndex:5280; }","duration":"164.650087ms","start":"2026-07-14T17:50:27.871346Z","end":"2026-07-14T17:50:28.035996Z","steps":["trace[506468169] 'read index received' (duration: 164.533894ms)","trace[506468169] 'applied index is now lower than readState.Index' (duration: 115.743µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:50:28.03604Z","caller":"traceutil/trace.go:171","msg":"trace[1044800974] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"177.945214ms","start":"2026-07-14T17:50:27.858076Z","end":"2026-07-14T17:50:28.036022Z","steps":["trace[1044800974] 'process raft request' (duration: 177.81631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:28.036228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.872083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-7tqtw\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-07-14T17:50:28.036249Z","caller":"traceutil/trace.go:171","msg":"trace[422069789] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-7tqtw; range_end:; response_count:1; response_revision:5077; }","duration":"164.948065ms","start":"2026-07-14T17:50:27.871296Z","end":"2026-07-14T17:50:28.036244Z","steps":["trace[422069789] 'agreement among raft nodes before linearized reading' (duration: 164.806381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:28.036263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.494781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:50:28.036348Z","caller":"traceutil/trace.go:171","msg":"trace[109574157] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5077; }","duration":"152.666555ms","start":"2026-07-14T17:50:27.883665Z","end":"2026-07-14T17:50:28.036332Z","steps":["trace[109574157] 'agreement among raft nodes before linearized reading' (duration: 152.48534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:28.036318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.601636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:50:28.036473Z","caller":"traceutil/trace.go:171","msg":"trace[450010041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5077; }","duration":"151.795652ms","start":"2026-07-14T17:50:27.884663Z","end":"2026-07-14T17:50:28.036459Z","steps":["trace[450010041] 'agreement among raft nodes before linearized reading' (duration: 151.568076ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:37.283595Z","caller":"traceutil/trace.go:171","msg":"trace[792464099] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"143.546494ms","start":"2026-07-14T17:50:37.140024Z","end":"2026-07-14T17:50:37.283571Z","steps":["trace[792464099] 'process raft request' (duration: 143.40661ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:37.290778Z","caller":"traceutil/trace.go:171","msg":"trace[1704874043] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"102.477305ms","start":"2026-07-14T17:50:37.188276Z","end":"2026-07-14T17:50:37.290753Z","steps":["trace[1704874043] 'process raft request' (duration: 102.430154ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:37.291159Z","caller":"traceutil/trace.go:171","msg":"trace[108337110] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"150.509279ms","start":"2026-07-14T17:50:37.140636Z","end":"2026-07-14T17:50:37.291146Z","steps":["trace[108337110] 'process raft request' (duration: 149.923133ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:37.291284Z","caller":"traceutil/trace.go:171","msg":"trace[323429555] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"150.229161ms","start":"2026-07-14T17:50:37.141045Z","end":"2026-07-14T17:50:37.291274Z","steps":["trace[323429555] 'process raft request' (duration: 149.624875ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:37.446172Z","caller":"traceutil/trace.go:171","msg":"trace[1923847759] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"143.80524ms","start":"2026-07-14T17:50:37.302323Z","end":"2026-07-14T17:50:37.446128Z","steps":["trace[1923847759] 'process raft request' (duration: 122.130926ms)","trace[1923847759] 'compare' (duration: 21.52459ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:50:37.748591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.645612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18c239510a5a28c3\" ","response":"range_response_count:1 size:746"} {"level":"info","ts":"2026-07-14T17:50:37.7487Z","caller":"traceutil/trace.go:171","msg":"trace[462678736] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18c239510a5a28c3; range_end:; response_count:1; response_revision:5213; }","duration":"110.776805ms","start":"2026-07-14T17:50:37.637892Z","end":"2026-07-14T17:50:37.748669Z","steps":["trace[462678736] 'agreement among raft nodes before linearized reading' (duration: 110.454766ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:37.911145Z","caller":"traceutil/trace.go:171","msg":"trace[999952279] linearizableReadLoop","detail":"{readStateIndex:5422; appliedIndex:5421; }","duration":"159.743272ms","start":"2026-07-14T17:50:37.751367Z","end":"2026-07-14T17:50:37.91111Z","steps":["trace[999952279] 'read index received' (duration: 137.179934ms)","trace[999952279] 'applied index is now lower than readState.Index' (duration: 22.561728ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:50:37.9113Z","caller":"traceutil/trace.go:171","msg":"trace[631394520] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"160.238335ms","start":"2026-07-14T17:50:37.751036Z","end":"2026-07-14T17:50:37.911275Z","steps":["trace[631394520] 'process raft request' (duration: 137.561494ms)","trace[631394520] 'compare' (duration: 22.26519ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:50:37.911641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.754766ms","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-07-14T17:50:37.91261Z","caller":"traceutil/trace.go:171","msg":"trace[1992498852] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5214; }","duration":"159.730122ms","start":"2026-07-14T17:50:37.752817Z","end":"2026-07-14T17:50:37.912547Z","steps":["trace[1992498852] 'agreement among raft nodes before linearized reading' (duration: 158.628743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:37.911837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.470522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b89c3495-e807-4008-b17a-feb9e59558e2\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-14T17:50:37.913201Z","caller":"traceutil/trace.go:171","msg":"trace[1410772063] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b89c3495-e807-4008-b17a-feb9e59558e2; range_end:; response_count:1; response_revision:5214; }","duration":"161.761666ms","start":"2026-07-14T17:50:37.751336Z","end":"2026-07-14T17:50:37.913097Z","steps":["trace[1410772063] 'agreement among raft nodes before linearized reading' (duration: 160.392779ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:38.115433Z","caller":"traceutil/trace.go:171","msg":"trace[1927593034] linearizableReadLoop","detail":"{readStateIndex:5426; appliedIndex:5424; }","duration":"124.434553ms","start":"2026-07-14T17:50:37.990973Z","end":"2026-07-14T17:50:38.115408Z","steps":["trace[1927593034] 'read index received' (duration: 22.68625ms)","trace[1927593034] 'applied index is now lower than readState.Index' (duration: 101.746933ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:50:38.115581Z","caller":"traceutil/trace.go:171","msg":"trace[979240752] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"119.849941ms","start":"2026-07-14T17:50:37.995683Z","end":"2026-07-14T17:50:38.115533Z","steps":["trace[979240752] 'process raft request' (duration: 119.618175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:38.115645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.665699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18c239510bc5dd23\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-07-14T17:50:38.115678Z","caller":"traceutil/trace.go:171","msg":"trace[1665567504] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18c239510bc5dd23; range_end:; response_count:1; response_revision:5219; }","duration":"124.763592ms","start":"2026-07-14T17:50:37.990905Z","end":"2026-07-14T17:50:38.115668Z","steps":["trace[1665567504] 'agreement among raft nodes before linearized reading' (duration: 124.617218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:38.115739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.887748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-14T17:50:38.11578Z","caller":"traceutil/trace.go:171","msg":"trace[1323088623] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5219; }","duration":"123.956311ms","start":"2026-07-14T17:50:37.991812Z","end":"2026-07-14T17:50:38.115768Z","steps":["trace[1323088623] 'agreement among raft nodes before linearized reading' (duration: 123.830927ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:50:38.115487Z","caller":"traceutil/trace.go:171","msg":"trace[1188439499] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"124.561737ms","start":"2026-07-14T17:50:37.990907Z","end":"2026-07-14T17:50:38.115468Z","steps":["trace[1188439499] 'process raft request' (duration: 124.262879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:50:38.716631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.481431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17235"} {"level":"info","ts":"2026-07-14T17:50:38.716714Z","caller":"traceutil/trace.go:171","msg":"trace[11039660] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5226; }","duration":"108.605174ms","start":"2026-07-14T17:50:38.608087Z","end":"2026-07-14T17:50:38.716692Z","steps":["trace[11039660] 'range keys from in-memory index tree' (duration: 108.294846ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:54:26.450161Z","caller":"traceutil/trace.go:171","msg":"trace[104242303] transaction","detail":"{read_only:false; response_revision:6910; number_of_response:1; }","duration":"102.025628ms","start":"2026-07-14T17:54:26.348116Z","end":"2026-07-14T17:54:26.450141Z","steps":["trace[104242303] 'process raft request' (duration: 101.923006ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:54:46.494138Z","caller":"traceutil/trace.go:171","msg":"trace[1449322017] linearizableReadLoop","detail":"{readStateIndex:7281; appliedIndex:7278; }","duration":"100.096871ms","start":"2026-07-14T17:54:46.394024Z","end":"2026-07-14T17:54:46.494121Z","steps":["trace[1449322017] 'read index received' (duration: 65.284694ms)","trace[1449322017] 'applied index is now lower than readState.Index' (duration: 34.811517ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:54:46.494288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.207733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:54:46.494317Z","caller":"traceutil/trace.go:171","msg":"trace[1955210897] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:7012; }","duration":"100.307665ms","start":"2026-07-14T17:54:46.394003Z","end":"2026-07-14T17:54:46.49431Z","steps":["trace[1955210897] 'agreement among raft nodes before linearized reading' (duration: 100.187292ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:54:46.49455Z","caller":"traceutil/trace.go:171","msg":"trace[873501963] transaction","detail":"{read_only:false; response_revision:7011; number_of_response:1; }","duration":"113.383412ms","start":"2026-07-14T17:54:46.381158Z","end":"2026-07-14T17:54:46.494542Z","steps":["trace[873501963] 'process raft request' (duration: 98.915805ms)","trace[873501963] 'compare' (duration: 13.918935ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:54:46.494722Z","caller":"traceutil/trace.go:171","msg":"trace[638418969] transaction","detail":"{read_only:false; response_revision:7012; number_of_response:1; }","duration":"108.326305ms","start":"2026-07-14T17:54:46.386366Z","end":"2026-07-14T17:54:46.494693Z","steps":["trace[638418969] 'process raft request' (duration: 107.721532ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:55:12.631817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4809} {"level":"info","ts":"2026-07-14T17:55:12.716334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4809,"took":"82.90025ms","hash":42835921,"current-db-size-bytes":48799744,"current-db-size":"49 MB","current-db-size-in-use-bytes":21635072,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-14T17:55:12.716403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":42835921,"revision":4809,"compact-revision":2498}