{"level":"warn","ts":"2026-02-25T19:29:53.846811Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T19:29:53.846905Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.187: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.187:2380","--initial-cluster=instance=https://199.19.213.187:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.187:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.187: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-02-25T19:29:53.846967Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T19:29:53.846977Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.187:2380"]} {"level":"info","ts":"2026-02-25T19:29:53.846996Z","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-02-25T19:29:53.847595Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.187:2379"]} {"level":"info","ts":"2026-02-25T19:29:53.84767Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.187:2380"],"listen-peer-urls":["https://199.19.213.187:2380"],"advertise-client-urls":["https://199.19.213.187:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.187:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.187: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-02-25T19:29:53.856449Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.580912ms"} {"level":"info","ts":"2026-02-25T19:29:53.871408Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bf16572acb5c7f39","cluster-id":"acf20a96895e2b74"} {"level":"info","ts":"2026-02-25T19:29:53.871505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:29:53.871525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became follower at term 0"} {"level":"info","ts":"2026-02-25T19:29:53.871539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bf16572acb5c7f39 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:29:53.871549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became follower at term 1"} {"level":"info","ts":"2026-02-25T19:29:53.871594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 switched to configuration voters=(13769288752043949881)"} {"level":"warn","ts":"2026-02-25T19:29:53.884413Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:29:53.887381Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:29:53.891388Z","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-02-25T19:29:53.897488Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bf16572acb5c7f39","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:29:53.897954Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bf16572acb5c7f39","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:29:53.898169Z","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-02-25T19:29:53.898592Z","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-02-25T19:29:53.89864Z","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-02-25T19:29:53.901661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 switched to configuration voters=(13769288752043949881)"} {"level":"info","ts":"2026-02-25T19:29:53.90192Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"acf20a96895e2b74","local-member-id":"bf16572acb5c7f39","added-peer-id":"bf16572acb5c7f39","added-peer-peer-urls":["https://199.19.213.187:2380"]} {"level":"info","ts":"2026-02-25T19:29:53.907441Z","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-02-25T19:29:53.907613Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.187:2380"} {"level":"info","ts":"2026-02-25T19:29:53.907661Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.187:2380"} {"level":"info","ts":"2026-02-25T19:29:53.909633Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bf16572acb5c7f39","initial-advertise-peer-urls":["https://199.19.213.187:2380"],"listen-peer-urls":["https://199.19.213.187:2380"],"advertise-client-urls":["https://199.19.213.187:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.187:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:29:53.90968Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:29:54.272718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:29:54.272786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:29:54.272812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 received MsgPreVoteResp from bf16572acb5c7f39 at term 1"} {"level":"info","ts":"2026-02-25T19:29:54.272831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:29:54.272842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 received MsgVoteResp from bf16572acb5c7f39 at term 2"} {"level":"info","ts":"2026-02-25T19:29:54.272885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became leader at term 2"} {"level":"info","ts":"2026-02-25T19:29:54.272902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bf16572acb5c7f39 elected leader bf16572acb5c7f39 at term 2"} {"level":"info","ts":"2026-02-25T19:29:54.274486Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bf16572acb5c7f39","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.187:2379]}","request-path":"/0/members/bf16572acb5c7f39/attributes","cluster-id":"acf20a96895e2b74","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:29:54.274533Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:29:54.274636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:29:54.274809Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:29:54.274854Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:29:54.27802Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:29:54.280441Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.187:2379"} {"level":"info","ts":"2026-02-25T19:29:54.280575Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:54.282235Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"acf20a96895e2b74","local-member-id":"bf16572acb5c7f39","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:54.28234Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:54.282385Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:30:03.697186Z","caller":"traceutil/trace.go:171","msg":"trace[1347620558] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"108.092083ms","start":"2026-02-25T19:30:03.589051Z","end":"2026-02-25T19:30:03.697143Z","steps":["trace[1347620558] 'process raft request' (duration: 107.822715ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.221844Z","caller":"traceutil/trace.go:171","msg":"trace[915036473] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:358; }","duration":"190.964126ms","start":"2026-02-25T19:30:04.030856Z","end":"2026-02-25T19:30:04.22182Z","steps":["trace[915036473] 'read index received' (duration: 102.802395ms)","trace[915036473] 'applied index is now lower than readState.Index' (duration: 88.160741ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:04.222203Z","caller":"traceutil/trace.go:171","msg":"trace[1861783823] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"217.764723ms","start":"2026-02-25T19:30:04.004407Z","end":"2026-02-25T19:30:04.222172Z","steps":["trace[1861783823] 'process raft request' (duration: 129.265362ms)","trace[1861783823] 'compare' (duration: 87.924025ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:04.222391Z","caller":"traceutil/trace.go:171","msg":"trace[407865267] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"212.874984ms","start":"2026-02-25T19:30:04.009493Z","end":"2026-02-25T19:30:04.222368Z","steps":["trace[407865267] 'process raft request' (duration: 212.275037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.222855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.720276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4201"} {"level":"info","ts":"2026-02-25T19:30:04.222943Z","caller":"traceutil/trace.go:171","msg":"trace[1965990484] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:351; }","duration":"186.86951ms","start":"2026-02-25T19:30:04.036051Z","end":"2026-02-25T19:30:04.22292Z","steps":["trace[1965990484] 'agreement among raft nodes before linearized reading' (duration: 186.705586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.222948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.551745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-02-25T19:30:04.222999Z","caller":"traceutil/trace.go:171","msg":"trace[2016148743] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:351; }","duration":"184.618556ms","start":"2026-02-25T19:30:04.038365Z","end":"2026-02-25T19:30:04.222984Z","steps":["trace[2016148743] 'agreement among raft nodes before linearized reading' (duration: 184.497023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.223277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.063679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-02-25T19:30:04.223339Z","caller":"traceutil/trace.go:171","msg":"trace[709031260] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:351; }","duration":"185.151461ms","start":"2026-02-25T19:30:04.038172Z","end":"2026-02-25T19:30:04.223323Z","steps":["trace[709031260] 'agreement among raft nodes before linearized reading' (duration: 185.041698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.223617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.461229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-02-25T19:30:04.223602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.133287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T19:30:04.223675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.347804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-02-25T19:30:04.223678Z","caller":"traceutil/trace.go:171","msg":"trace[1255928666] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:351; }","duration":"185.523381ms","start":"2026-02-25T19:30:04.038139Z","end":"2026-02-25T19:30:04.223663Z","steps":["trace[1255928666] 'agreement among raft nodes before linearized reading' (duration: 185.413418ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.22372Z","caller":"traceutil/trace.go:171","msg":"trace[1934379149] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:351; }","duration":"186.409816ms","start":"2026-02-25T19:30:04.037295Z","end":"2026-02-25T19:30:04.223705Z","steps":["trace[1934379149] 'agreement among raft nodes before linearized reading' (duration: 186.320324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.223753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.213015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T19:30:04.223853Z","caller":"traceutil/trace.go:171","msg":"trace[73854224] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:351; }","duration":"144.346958ms","start":"2026-02-25T19:30:04.079484Z","end":"2026-02-25T19:30:04.223831Z","steps":["trace[73854224] 'agreement among raft nodes before linearized reading' (duration: 144.104461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.223867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.929503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-25T19:30:04.223894Z","caller":"traceutil/trace.go:171","msg":"trace[1684645601] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:351; }","duration":"185.984314ms","start":"2026-02-25T19:30:04.037903Z","end":"2026-02-25T19:30:04.223888Z","steps":["trace[1684645601] 'agreement among raft nodes before linearized reading' (duration: 185.938193ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.223735Z","caller":"traceutil/trace.go:171","msg":"trace[1229098204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:351; }","duration":"147.25985ms","start":"2026-02-25T19:30:04.076422Z","end":"2026-02-25T19:30:04.223682Z","steps":["trace[1229098204] 'agreement among raft nodes before linearized reading' (duration: 147.057034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.223782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.670686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-02-25T19:30:04.225124Z","caller":"traceutil/trace.go:171","msg":"trace[1138519980] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:351; }","duration":"187.024364ms","start":"2026-02-25T19:30:04.038083Z","end":"2026-02-25T19:30:04.225107Z","steps":["trace[1138519980] 'agreement among raft nodes before linearized reading' (duration: 185.626374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.226009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.155873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T19:30:04.226134Z","caller":"traceutil/trace.go:171","msg":"trace[1279231644] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:351; }","duration":"195.243857ms","start":"2026-02-25T19:30:04.030801Z","end":"2026-02-25T19:30:04.226045Z","steps":["trace[1279231644] 'agreement among raft nodes before linearized reading' (duration: 191.46193ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.357085Z","caller":"traceutil/trace.go:171","msg":"trace[1487912148] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"128.351526ms","start":"2026-02-25T19:30:04.228707Z","end":"2026-02-25T19:30:04.357058Z","steps":["trace[1487912148] 'process raft request' (duration: 128.203892ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.357158Z","caller":"traceutil/trace.go:171","msg":"trace[128844273] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"128.546952ms","start":"2026-02-25T19:30:04.228587Z","end":"2026-02-25T19:30:04.357134Z","steps":["trace[128844273] 'process raft request' (duration: 128.197192ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.357221Z","caller":"traceutil/trace.go:171","msg":"trace[790952244] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"127.235034ms","start":"2026-02-25T19:30:04.229944Z","end":"2026-02-25T19:30:04.357179Z","steps":["trace[790952244] 'process raft request' (duration: 127.021149ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.357265Z","caller":"traceutil/trace.go:171","msg":"trace[605605205] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"125.825484ms","start":"2026-02-25T19:30:04.231424Z","end":"2026-02-25T19:30:04.357249Z","steps":["trace[605605205] 'process raft request' (duration: 125.587698ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.357499Z","caller":"traceutil/trace.go:171","msg":"trace[1421046685] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"124.709973ms","start":"2026-02-25T19:30:04.232778Z","end":"2026-02-25T19:30:04.357488Z","steps":["trace[1421046685] 'process raft request' (duration: 124.416375ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.357631Z","caller":"traceutil/trace.go:171","msg":"trace[1103034784] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"122.716287ms","start":"2026-02-25T19:30:04.234896Z","end":"2026-02-25T19:30:04.357613Z","steps":["trace[1103034784] 'process raft request' (duration: 122.583533ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.357715Z","caller":"traceutil/trace.go:171","msg":"trace[1451327366] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"124.804116ms","start":"2026-02-25T19:30:04.232883Z","end":"2026-02-25T19:30:04.357687Z","steps":["trace[1451327366] 'process raft request' (duration: 124.499127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.360041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.511327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4373"} {"level":"warn","ts":"2026-02-25T19:30:04.360081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.578959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4373"} {"level":"info","ts":"2026-02-25T19:30:04.360104Z","caller":"traceutil/trace.go:171","msg":"trace[2060931678] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:360; }","duration":"130.60002ms","start":"2026-02-25T19:30:04.229488Z","end":"2026-02-25T19:30:04.360088Z","steps":["trace[2060931678] 'agreement among raft nodes before linearized reading' (duration: 130.471806ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.360128Z","caller":"traceutil/trace.go:171","msg":"trace[557416291] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:360; }","duration":"130.648151ms","start":"2026-02-25T19:30:04.229466Z","end":"2026-02-25T19:30:04.360114Z","steps":["trace[557416291] 'agreement among raft nodes before linearized reading' (duration: 130.533408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.360103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.859858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4373"} {"level":"info","ts":"2026-02-25T19:30:04.360285Z","caller":"traceutil/trace.go:171","msg":"trace[20966421] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:360; }","duration":"130.993011ms","start":"2026-02-25T19:30:04.22921Z","end":"2026-02-25T19:30:04.360203Z","steps":["trace[20966421] 'agreement among raft nodes before linearized reading' (duration: 130.829326ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.360308Z","caller":"traceutil/trace.go:171","msg":"trace[691431060] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"125.223828ms","start":"2026-02-25T19:30:04.235066Z","end":"2026-02-25T19:30:04.36029Z","steps":["trace[691431060] 'process raft request' (duration: 122.45997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.36058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.97234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4373"} {"level":"info","ts":"2026-02-25T19:30:04.360645Z","caller":"traceutil/trace.go:171","msg":"trace[1986746861] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:360; }","duration":"131.049363ms","start":"2026-02-25T19:30:04.229579Z","end":"2026-02-25T19:30:04.360629Z","steps":["trace[1986746861] 'agreement among raft nodes before linearized reading' (duration: 130.94946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.360779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.477209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T19:30:04.360842Z","caller":"traceutil/trace.go:171","msg":"trace[85745608] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:360; }","duration":"128.552982ms","start":"2026-02-25T19:30:04.232273Z","end":"2026-02-25T19:30:04.360826Z","steps":["trace[85745608] 'agreement among raft nodes before linearized reading' (duration: 128.454509ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.607409Z","caller":"traceutil/trace.go:171","msg":"trace[927455847] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"239.365042ms","start":"2026-02-25T19:30:04.368006Z","end":"2026-02-25T19:30:04.607371Z","steps":["trace[927455847] 'process raft request' (duration: 166.699049ms)","trace[927455847] 'compare' (duration: 72.391786ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:04.607625Z","caller":"traceutil/trace.go:171","msg":"trace[644747646] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"238.721154ms","start":"2026-02-25T19:30:04.368878Z","end":"2026-02-25T19:30:04.607599Z","steps":["trace[644747646] 'process raft request' (duration: 238.463627ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.607633Z","caller":"traceutil/trace.go:171","msg":"trace[2138241266] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"237.603962ms","start":"2026-02-25T19:30:04.370009Z","end":"2026-02-25T19:30:04.607613Z","steps":["trace[2138241266] 'process raft request' (duration: 237.414607ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.607709Z","caller":"traceutil/trace.go:171","msg":"trace[453278870] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"239.353302ms","start":"2026-02-25T19:30:04.368328Z","end":"2026-02-25T19:30:04.607681Z","steps":["trace[453278870] 'process raft request' (duration: 238.954221ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.607941Z","caller":"traceutil/trace.go:171","msg":"trace[31493795] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"236.77404ms","start":"2026-02-25T19:30:04.371158Z","end":"2026-02-25T19:30:04.607932Z","steps":["trace[31493795] 'process raft request' (duration: 236.316746ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.607896Z","caller":"traceutil/trace.go:171","msg":"trace[975944220] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:370; }","duration":"182.570247ms","start":"2026-02-25T19:30:04.425305Z","end":"2026-02-25T19:30:04.607875Z","steps":["trace[975944220] 'read index received' (duration: 109.39011ms)","trace[975944220] 'applied index is now lower than readState.Index' (duration: 73.166547ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:04.608489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.167707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-25T19:30:04.608545Z","caller":"traceutil/trace.go:171","msg":"trace[488593512] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:366; }","duration":"228.478015ms","start":"2026-02-25T19:30:04.380054Z","end":"2026-02-25T19:30:04.608532Z","steps":["trace[488593512] 'agreement among raft nodes before linearized reading' (duration: 228.143765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.694298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.168306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-02-25T19:30:04.694345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.53407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T19:30:04.694386Z","caller":"traceutil/trace.go:171","msg":"trace[1710520189] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:368; }","duration":"263.272319ms","start":"2026-02-25T19:30:04.431096Z","end":"2026-02-25T19:30:04.694369Z","steps":["trace[1710520189] 'agreement among raft nodes before linearized reading' (duration: 263.114824ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.69442Z","caller":"traceutil/trace.go:171","msg":"trace[1795086781] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:368; }","duration":"163.641423ms","start":"2026-02-25T19:30:04.530759Z","end":"2026-02-25T19:30:04.6944Z","steps":["trace[1795086781] 'agreement among raft nodes before linearized reading' (duration: 163.479729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.694432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.20271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-proxy-7n4ns.18979416c47bdf74\" ","response":"range_response_count:1 size:769"} {"level":"info","ts":"2026-02-25T19:30:04.6945Z","caller":"traceutil/trace.go:171","msg":"trace[2109228516] range","detail":"{range_begin:/registry/events/kube-system/kube-proxy-7n4ns.18979416c47bdf74; range_end:; response_count:1; response_revision:368; }","duration":"192.304643ms","start":"2026-02-25T19:30:04.502173Z","end":"2026-02-25T19:30:04.694478Z","steps":["trace[2109228516] 'agreement among raft nodes before linearized reading' (duration: 192.183869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.694546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.16325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T19:30:04.694584Z","caller":"traceutil/trace.go:171","msg":"trace[408372352] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:368; }","duration":"214.234482ms","start":"2026-02-25T19:30:04.480341Z","end":"2026-02-25T19:30:04.694576Z","steps":["trace[408372352] 'agreement among raft nodes before linearized reading' (duration: 214.180011ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.694537Z","caller":"traceutil/trace.go:171","msg":"trace[1297924659] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"260.553702ms","start":"2026-02-25T19:30:04.433958Z","end":"2026-02-25T19:30:04.694512Z","steps":["trace[1297924659] 'process raft request' (duration: 260.190401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:04.694694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.911894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-02-25T19:30:04.695306Z","caller":"traceutil/trace.go:171","msg":"trace[669652449] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:368; }","duration":"179.549973ms","start":"2026-02-25T19:30:04.515705Z","end":"2026-02-25T19:30:04.695255Z","steps":["trace[669652449] 'agreement among raft nodes before linearized reading' (duration: 178.865363ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.694802Z","caller":"traceutil/trace.go:171","msg":"trace[1719616321] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"264.390079ms","start":"2026-02-25T19:30:04.430031Z","end":"2026-02-25T19:30:04.694421Z","steps":["trace[1719616321] 'process raft request' (duration: 264.000658ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.753033Z","caller":"traceutil/trace.go:171","msg":"trace[2087497529] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"137.588008ms","start":"2026-02-25T19:30:04.61543Z","end":"2026-02-25T19:30:04.753018Z","steps":["trace[2087497529] 'process raft request' (duration: 137.476174ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.753027Z","caller":"traceutil/trace.go:171","msg":"trace[223780827] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"140.613883ms","start":"2026-02-25T19:30:04.612388Z","end":"2026-02-25T19:30:04.753002Z","steps":["trace[223780827] 'process raft request' (duration: 110.336428ms)","trace[223780827] 'compare' (duration: 30.038078ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:04.753185Z","caller":"traceutil/trace.go:171","msg":"trace[1226561609] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"135.363625ms","start":"2026-02-25T19:30:04.617798Z","end":"2026-02-25T19:30:04.753162Z","steps":["trace[1226561609] 'process raft request' (duration: 135.163429ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:04.753524Z","caller":"traceutil/trace.go:171","msg":"trace[457181224] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"135.59781ms","start":"2026-02-25T19:30:04.617915Z","end":"2026-02-25T19:30:04.753513Z","steps":["trace[457181224] 'process raft request' (duration: 135.168608ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:14.522678Z","caller":"traceutil/trace.go:171","msg":"trace[453213231] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"127.04617ms","start":"2026-02-25T19:30:14.395606Z","end":"2026-02-25T19:30:14.522652Z","steps":["trace[453213231] 'read index received' (duration: 97.012062ms)","trace[453213231] 'applied index is now lower than readState.Index' (duration: 30.033168ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:14.522794Z","caller":"traceutil/trace.go:171","msg":"trace[833595087] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"169.454698ms","start":"2026-02-25T19:30:14.353322Z","end":"2026-02-25T19:30:14.522777Z","steps":["trace[833595087] 'process raft request' (duration: 139.249024ms)","trace[833595087] 'compare' (duration: 29.988358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:14.522814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.181454ms","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-02-25T19:30:14.523163Z","caller":"traceutil/trace.go:171","msg":"trace[1554714850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"127.342209ms","start":"2026-02-25T19:30:14.39557Z","end":"2026-02-25T19:30:14.522912Z","steps":["trace[1554714850] 'agreement among raft nodes before linearized reading' (duration: 127.182014ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:14.657452Z","caller":"traceutil/trace.go:171","msg":"trace[285967942] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"130.046925ms","start":"2026-02-25T19:30:14.527385Z","end":"2026-02-25T19:30:14.657432Z","steps":["trace[285967942] 'process raft request' (duration: 129.1469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:31.911195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.641415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:31.911309Z","caller":"traceutil/trace.go:171","msg":"trace[52418791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"225.7967ms","start":"2026-02-25T19:30:31.685481Z","end":"2026-02-25T19:30:31.911278Z","steps":["trace[52418791] 'range keys from in-memory index tree' (duration: 225.503702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:31.911355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.858396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pbxb2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-25T19:30:31.911608Z","caller":"traceutil/trace.go:171","msg":"trace[1418384944] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pbxb2; range_end:; response_count:1; response_revision:555; }","duration":"315.174895ms","start":"2026-02-25T19:30:31.596407Z","end":"2026-02-25T19:30:31.911582Z","steps":["trace[1418384944] 'range keys from in-memory index tree' (duration: 314.66432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:31.911668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:31.596382Z","time spent":"315.273117ms","remote":"127.0.0.1:59566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-pbxb2\" "} {"level":"info","ts":"2026-02-25T19:30:32.171529Z","caller":"traceutil/trace.go:171","msg":"trace[499859486] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"244.141477ms","start":"2026-02-25T19:30:31.927365Z","end":"2026-02-25T19:30:32.171507Z","steps":["trace[499859486] 'process raft request' (duration: 243.985332ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:33.858754Z","caller":"traceutil/trace.go:171","msg":"trace[428049267] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"129.265481ms","start":"2026-02-25T19:30:33.729459Z","end":"2026-02-25T19:30:33.858725Z","steps":["trace[428049267] 'process raft request' (duration: 118.041964ms)","trace[428049267] 'compare' (duration: 10.526627ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:36.950431Z","caller":"traceutil/trace.go:171","msg":"trace[1056292154] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"118.047695ms","start":"2026-02-25T19:30:36.832357Z","end":"2026-02-25T19:30:36.950404Z","steps":["trace[1056292154] 'read index received' (duration: 112.280812ms)","trace[1056292154] 'applied index is now lower than readState.Index' (duration: 5.765723ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:36.950527Z","caller":"traceutil/trace.go:171","msg":"trace[1770051438] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"162.130167ms","start":"2026-02-25T19:30:36.78836Z","end":"2026-02-25T19:30:36.95049Z","steps":["trace[1770051438] 'process raft request' (duration: 156.381925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:36.950854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.437425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-25T19:30:36.950937Z","caller":"traceutil/trace.go:171","msg":"trace[356922678] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:747; }","duration":"118.59864ms","start":"2026-02-25T19:30:36.832319Z","end":"2026-02-25T19:30:36.950917Z","steps":["trace[356922678] 'agreement among raft nodes before linearized reading' (duration: 118.259351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:51.144878Z","caller":"traceutil/trace.go:171","msg":"trace[611100293] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"290.591255ms","start":"2026-02-25T19:30:50.854251Z","end":"2026-02-25T19:30:51.144843Z","steps":["trace[611100293] 'process raft request' (duration: 290.260875ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:13.432811Z","caller":"traceutil/trace.go:171","msg":"trace[1631493736] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"106.759447ms","start":"2026-02-25T19:31:13.326008Z","end":"2026-02-25T19:31:13.432767Z","steps":["trace[1631493736] 'process raft request' (duration: 53.753824ms)","trace[1631493736] 'compare' (duration: 52.791647ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:13.43357Z","caller":"traceutil/trace.go:171","msg":"trace[1096290085] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"100.394158ms","start":"2026-02-25T19:31:13.333132Z","end":"2026-02-25T19:31:13.433526Z","steps":["trace[1096290085] 'process raft request' (duration: 100.057369ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:39.356678Z","caller":"traceutil/trace.go:171","msg":"trace[1172349909] linearizableReadLoop","detail":"{readStateIndex:1648; appliedIndex:1647; }","duration":"284.435975ms","start":"2026-02-25T19:31:39.072198Z","end":"2026-02-25T19:31:39.356634Z","steps":["trace[1172349909] 'read index received' (duration: 206.929896ms)","trace[1172349909] 'applied index is now lower than readState.Index' (duration: 77.504079ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:39.357036Z","caller":"traceutil/trace.go:171","msg":"trace[1775943332] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"371.270315ms","start":"2026-02-25T19:31:38.985699Z","end":"2026-02-25T19:31:39.35697Z","steps":["trace[1775943332] 'process raft request' (duration: 293.455568ms)","trace[1775943332] 'compare' (duration: 76.676605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:39.357162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.955389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-02-25T19:31:39.357247Z","caller":"traceutil/trace.go:171","msg":"trace[594441186] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1588; }","duration":"285.099212ms","start":"2026-02-25T19:31:39.072123Z","end":"2026-02-25T19:31:39.357223Z","steps":["trace[594441186] 'agreement among raft nodes before linearized reading' (duration: 284.828645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:39.357277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.884803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T19:31:39.357351Z","caller":"traceutil/trace.go:171","msg":"trace[426917338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1588; }","duration":"201.038757ms","start":"2026-02-25T19:31:39.156291Z","end":"2026-02-25T19:31:39.35733Z","steps":["trace[426917338] 'agreement among raft nodes before linearized reading' (duration: 200.694998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:39.357857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:38.985668Z","time spent":"371.490171ms","remote":"127.0.0.1:59566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:25.346582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.066137ms","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-02-25T19:32:25.346683Z","caller":"traceutil/trace.go:171","msg":"trace[130397704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1868; }","duration":"273.222381ms","start":"2026-02-25T19:32:25.07344Z","end":"2026-02-25T19:32:25.346662Z","steps":["trace[130397704] 'range keys from in-memory index tree' (duration: 272.843572ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:48.441306Z","caller":"traceutil/trace.go:171","msg":"trace[1293361828] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"207.84583ms","start":"2026-02-25T19:33:48.233406Z","end":"2026-02-25T19:33:48.441252Z","steps":["trace[1293361828] 'process raft request' (duration: 177.382039ms)","trace[1293361828] 'compare' (duration: 29.916626ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:48.809358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:48.809479Z","caller":"traceutil/trace.go:171","msg":"trace[463295409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2328; }","duration":"123.615186ms","start":"2026-02-25T19:33:48.685832Z","end":"2026-02-25T19:33:48.809447Z","steps":["trace[463295409] 'range keys from in-memory index tree' (duration: 123.326767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:48.809613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.452486ms","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-02-25T19:33:48.809775Z","caller":"traceutil/trace.go:171","msg":"trace[20492640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2328; }","duration":"116.640991ms","start":"2026-02-25T19:33:48.693081Z","end":"2026-02-25T19:33:48.809722Z","steps":["trace[20492640] 'range keys from in-memory index tree' (duration: 116.263441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:48.809835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.213017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-25T19:33:48.809966Z","caller":"traceutil/trace.go:171","msg":"trace[271500109] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2328; }","duration":"107.389172ms","start":"2026-02-25T19:33:48.702551Z","end":"2026-02-25T19:33:48.809941Z","steps":["trace[271500109] 'range keys from in-memory index tree' (duration: 106.943539ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:48.962512Z","caller":"traceutil/trace.go:171","msg":"trace[272777993] linearizableReadLoop","detail":"{readStateIndex:2418; appliedIndex:2416; }","duration":"138.684286ms","start":"2026-02-25T19:33:48.823793Z","end":"2026-02-25T19:33:48.962478Z","steps":["trace[272777993] 'read index received' (duration: 56.685004ms)","trace[272777993] 'applied index is now lower than readState.Index' (duration: 81.998302ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:48.962654Z","caller":"traceutil/trace.go:171","msg":"trace[1001716440] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"146.611829ms","start":"2026-02-25T19:33:48.815993Z","end":"2026-02-25T19:33:48.962605Z","steps":["trace[1001716440] 'process raft request' (duration: 105.417197ms)","trace[1001716440] 'compare' (duration: 40.918273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:48.962967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.133179ms","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-02-25T19:33:48.96311Z","caller":"traceutil/trace.go:171","msg":"trace[1254048209] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2330; }","duration":"139.314814ms","start":"2026-02-25T19:33:48.823747Z","end":"2026-02-25T19:33:48.963062Z","steps":["trace[1254048209] 'agreement among raft nodes before linearized reading' (duration: 138.914143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:36:35.656406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.045629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:36:35.656772Z","caller":"traceutil/trace.go:171","msg":"trace[823644661] transaction","detail":"{read_only:false; response_revision:3310; number_of_response:1; }","duration":"128.008014ms","start":"2026-02-25T19:36:35.528719Z","end":"2026-02-25T19:36:35.656727Z","steps":["trace[823644661] 'process raft request' (duration: 20.371618ms)","trace[823644661] 'compare' (duration: 106.601596ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:37:45.062127Z","caller":"traceutil/trace.go:171","msg":"trace[1785345462] transaction","detail":"{read_only:false; response_revision:4107; number_of_response:1; }","duration":"113.328337ms","start":"2026-02-25T19:37:44.948769Z","end":"2026-02-25T19:37:45.062098Z","steps":["trace[1785345462] 'process raft request' (duration: 111.060772ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:37:45.062229Z","caller":"traceutil/trace.go:171","msg":"trace[1724764760] transaction","detail":"{read_only:false; response_revision:4108; number_of_response:1; }","duration":"112.949826ms","start":"2026-02-25T19:37:44.949254Z","end":"2026-02-25T19:37:45.062204Z","steps":["trace[1724764760] 'process raft request' (duration: 112.76099ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:37:45.062577Z","caller":"traceutil/trace.go:171","msg":"trace[1803626995] transaction","detail":"{read_only:false; response_revision:4109; number_of_response:1; }","duration":"108.670764ms","start":"2026-02-25T19:37:44.953876Z","end":"2026-02-25T19:37:45.062547Z","steps":["trace[1803626995] 'process raft request' (duration: 108.214222ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:37:45.871415Z","caller":"traceutil/trace.go:171","msg":"trace[102638246] transaction","detail":"{read_only:false; response_revision:4150; number_of_response:1; }","duration":"105.379301ms","start":"2026-02-25T19:37:45.766013Z","end":"2026-02-25T19:37:45.871392Z","steps":["trace[102638246] 'process raft request' (duration: 90.515588ms)","trace[102638246] 'compare' (duration: 14.694098ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:37:52.488499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.510412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:37:52.488698Z","caller":"traceutil/trace.go:171","msg":"trace[1830855024] transaction","detail":"{read_only:false; response_revision:4285; number_of_response:1; }","duration":"173.119709ms","start":"2026-02-25T19:37:52.315564Z","end":"2026-02-25T19:37:52.488684Z","steps":["trace[1830855024] 'process raft request' (duration: 173.039937ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:37:52.488733Z","caller":"traceutil/trace.go:171","msg":"trace[422742920] transaction","detail":"{read_only:false; response_revision:4284; number_of_response:1; }","duration":"178.491912ms","start":"2026-02-25T19:37:52.310196Z","end":"2026-02-25T19:37:52.488688Z","steps":["trace[422742920] 'process raft request' (duration: 18.710563ms)","trace[422742920] 'compare' (duration: 159.377948ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:37:52.488735Z","caller":"traceutil/trace.go:171","msg":"trace[577465621] linearizableReadLoop","detail":"{readStateIndex:4452; appliedIndex:4451; }","duration":"175.984161ms","start":"2026-02-25T19:37:52.312687Z","end":"2026-02-25T19:37:52.488672Z","steps":["trace[577465621] 'read index received' (duration: 16.217292ms)","trace[577465621] 'applied index is now lower than readState.Index' (duration: 159.760389ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:37:52.488906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.192277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/loki-alerting-rules\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:37:52.48906Z","caller":"traceutil/trace.go:171","msg":"trace[857434446] range","detail":"{range_begin:/registry/configmaps/monitoring/loki-alerting-rules; range_end:; response_count:0; response_revision:4285; }","duration":"176.354721ms","start":"2026-02-25T19:37:52.312651Z","end":"2026-02-25T19:37:52.489005Z","steps":["trace[857434446] 'agreement among raft nodes before linearized reading' (duration: 176.125005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:12.962146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.220225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:38:12.96223Z","caller":"traceutil/trace.go:171","msg":"trace[1069137193] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:4742; }","duration":"248.338539ms","start":"2026-02-25T19:38:12.713874Z","end":"2026-02-25T19:38:12.962212Z","steps":["trace[1069137193] 'count revisions from in-memory index tree' (duration: 248.104942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.485246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.651669ms","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":"warn","ts":"2026-02-25T19:38:31.485297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.817166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-02-25T19:38:31.485388Z","caller":"traceutil/trace.go:171","msg":"trace[1924874839] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4965; }","duration":"321.947219ms","start":"2026-02-25T19:38:31.163417Z","end":"2026-02-25T19:38:31.485364Z","steps":["trace[1924874839] 'range keys from in-memory index tree' (duration: 321.653232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.485429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:31.163403Z","time spent":"322.016621ms","remote":"127.0.0.1:55982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3268,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-02-25T19:38:31.485332Z","caller":"traceutil/trace.go:171","msg":"trace[839066865] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4965; }","duration":"281.804924ms","start":"2026-02-25T19:38:31.203509Z","end":"2026-02-25T19:38:31.485314Z","steps":["trace[839066865] 'range keys from in-memory index tree' (duration: 281.447684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.485616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.192103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-02-25T19:38:31.485672Z","caller":"traceutil/trace.go:171","msg":"trace[294030151] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4965; }","duration":"353.296897ms","start":"2026-02-25T19:38:31.13236Z","end":"2026-02-25T19:38:31.485656Z","steps":["trace[294030151] 'range keys from in-memory index tree' (duration: 353.018128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.48573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.189483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T19:38:31.485811Z","caller":"traceutil/trace.go:171","msg":"trace[258031336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4965; }","duration":"147.319917ms","start":"2026-02-25T19:38:31.33847Z","end":"2026-02-25T19:38:31.48579Z","steps":["trace[258031336] 'range keys from in-memory index tree' (duration: 146.951386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.485759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:31.132331Z","time spent":"353.413709ms","remote":"127.0.0.1:59566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10416,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-02-25T19:38:31.790677Z","caller":"traceutil/trace.go:171","msg":"trace[2122071156] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"300.245612ms","start":"2026-02-25T19:38:31.490399Z","end":"2026-02-25T19:38:31.790645Z","steps":["trace[2122071156] 'process raft request' (duration: 300.061746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.790846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:31.490377Z","time spent":"300.381455ms","remote":"127.0.0.1:59674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:38:31.96132Z","caller":"traceutil/trace.go:171","msg":"trace[445091487] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5150; }","duration":"302.244018ms","start":"2026-02-25T19:38:31.659052Z","end":"2026-02-25T19:38:31.961296Z","steps":["trace[445091487] 'read index received' (duration: 131.484794ms)","trace[445091487] 'applied index is now lower than readState.Index' (duration: 170.758184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:38:31.96163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.979568ms","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-02-25T19:38:31.961707Z","caller":"traceutil/trace.go:171","msg":"trace[208873024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4967; }","duration":"111.108372ms","start":"2026-02-25T19:38:31.850585Z","end":"2026-02-25T19:38:31.961693Z","steps":["trace[208873024] 'agreement among raft nodes before linearized reading' (duration: 110.860814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.961665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.588758ms","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-02-25T19:38:31.961865Z","caller":"traceutil/trace.go:171","msg":"trace[2113767847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4967; }","duration":"302.823586ms","start":"2026-02-25T19:38:31.658981Z","end":"2026-02-25T19:38:31.961805Z","steps":["trace[2113767847] 'agreement among raft nodes before linearized reading' (duration: 302.401963ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:31.961367Z","caller":"traceutil/trace.go:171","msg":"trace[734876961] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"466.098276ms","start":"2026-02-25T19:38:31.495255Z","end":"2026-02-25T19:38:31.961354Z","steps":["trace[734876961] 'process raft request' (duration: 465.89858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.961953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:31.658946Z","time spent":"302.98655ms","remote":"127.0.0.1:59674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:38:31.962245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:31.495228Z","time spent":"466.824847ms","remote":"127.0.0.1:59566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:38:32.100885Z","caller":"traceutil/trace.go:171","msg":"trace[730165636] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5152; }","duration":"133.632856ms","start":"2026-02-25T19:38:31.967223Z","end":"2026-02-25T19:38:32.100856Z","steps":["trace[730165636] 'read index received' (duration: 121.242302ms)","trace[730165636] 'applied index is now lower than readState.Index' (duration: 12.389153ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:38:32.101037Z","caller":"traceutil/trace.go:171","msg":"trace[906321985] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"133.903253ms","start":"2026-02-25T19:38:31.967066Z","end":"2026-02-25T19:38:32.100969Z","steps":["trace[906321985] 'process raft request' (duration: 121.382396ms)","trace[906321985] 'compare' (duration: 12.28299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:38:32.101324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.079618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:65013"} {"level":"info","ts":"2026-02-25T19:38:32.101362Z","caller":"traceutil/trace.go:171","msg":"trace[208916431] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:4968; }","duration":"134.17526ms","start":"2026-02-25T19:38:31.967177Z","end":"2026-02-25T19:38:32.101352Z","steps":["trace[208916431] 'agreement among raft nodes before linearized reading' (duration: 133.948884ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:32.177412Z","caller":"traceutil/trace.go:171","msg":"trace[322810813] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"210.028046ms","start":"2026-02-25T19:38:31.967358Z","end":"2026-02-25T19:38:32.177386Z","steps":["trace[322810813] 'process raft request' (duration: 209.926822ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:32.177624Z","caller":"traceutil/trace.go:171","msg":"trace[502354390] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"208.696608ms","start":"2026-02-25T19:38:31.96892Z","end":"2026-02-25T19:38:32.177617Z","steps":["trace[502354390] 'process raft request' (duration: 208.404359ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:32.177425Z","caller":"traceutil/trace.go:171","msg":"trace[1623810091] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"210.14585ms","start":"2026-02-25T19:38:31.967245Z","end":"2026-02-25T19:38:32.177391Z","steps":["trace[1623810091] 'process raft request' (duration: 209.872551ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:32.177825Z","caller":"traceutil/trace.go:171","msg":"trace[1247739778] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"208.697088ms","start":"2026-02-25T19:38:31.969119Z","end":"2026-02-25T19:38:32.177816Z","steps":["trace[1247739778] 'process raft request' (duration: 208.41026ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:32.177972Z","caller":"traceutil/trace.go:171","msg":"trace[236022535] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"208.287657ms","start":"2026-02-25T19:38:31.969677Z","end":"2026-02-25T19:38:32.177965Z","steps":["trace[236022535] 'process raft request' (duration: 208.089582ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:32.178178Z","caller":"traceutil/trace.go:171","msg":"trace[655598928] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"207.839513ms","start":"2026-02-25T19:38:31.970316Z","end":"2026-02-25T19:38:32.178156Z","steps":["trace[655598928] 'process raft request' (duration: 207.614387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:32.17823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.922198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-25T19:38:32.178305Z","caller":"traceutil/trace.go:171","msg":"trace[1962626606] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4974; }","duration":"124.03232ms","start":"2026-02-25T19:38:32.054252Z","end":"2026-02-25T19:38:32.178284Z","steps":["trace[1962626606] 'agreement among raft nodes before linearized reading' (duration: 123.833394ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:32.315297Z","caller":"traceutil/trace.go:171","msg":"trace[238029683] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"182.15731ms","start":"2026-02-25T19:38:32.133094Z","end":"2026-02-25T19:38:32.315252Z","steps":["trace[238029683] 'process raft request' (duration: 120.031277ms)","trace[238029683] 'compare' (duration: 61.503305ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:38:32.315427Z","caller":"traceutil/trace.go:171","msg":"trace[1117318988] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5159; }","duration":"133.901123ms","start":"2026-02-25T19:38:32.181505Z","end":"2026-02-25T19:38:32.315406Z","steps":["trace[1117318988] 'read index received' (duration: 71.601924ms)","trace[1117318988] 'applied index is now lower than readState.Index' (duration: 62.298239ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:38:32.315555Z","caller":"traceutil/trace.go:171","msg":"trace[454740120] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"167.479771ms","start":"2026-02-25T19:38:32.148026Z","end":"2026-02-25T19:38:32.315505Z","steps":["trace[454740120] 'process raft request' (duration: 167.298096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:32.315721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.198782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-25T19:38:32.315781Z","caller":"traceutil/trace.go:171","msg":"trace[1865378982] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4976; }","duration":"134.313475ms","start":"2026-02-25T19:38:32.181451Z","end":"2026-02-25T19:38:32.315764Z","steps":["trace[1865378982] 'agreement among raft nodes before linearized reading' (duration: 134.122349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:32.31629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.8564ms","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-02-25T19:38:32.316346Z","caller":"traceutil/trace.go:171","msg":"trace[709568591] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4976; }","duration":"113.944853ms","start":"2026-02-25T19:38:32.202385Z","end":"2026-02-25T19:38:32.31633Z","steps":["trace[709568591] 'agreement among raft nodes before linearized reading' (duration: 113.790028ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:32.692142Z","caller":"traceutil/trace.go:171","msg":"trace[72271158] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"149.630491ms","start":"2026-02-25T19:38:32.542483Z","end":"2026-02-25T19:38:32.692114Z","steps":["trace[72271158] 'process raft request' (duration: 113.545861ms)","trace[72271158] 'compare' (duration: 35.768451ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:39:54.444315Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2641} {"level":"info","ts":"2026-02-25T19:39:54.497239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2641,"took":"51.646706ms","hash":2625262194,"current-db-size-bytes":41316352,"current-db-size":"41 MB","current-db-size-in-use-bytes":30347264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-25T19:39:54.497321Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2625262194,"revision":2641,"compact-revision":-1} {"level":"info","ts":"2026-02-25T19:44:12.763385Z","caller":"traceutil/trace.go:171","msg":"trace[1733766565] linearizableReadLoop","detail":"{readStateIndex:7669; appliedIndex:7668; }","duration":"214.527159ms","start":"2026-02-25T19:44:12.548817Z","end":"2026-02-25T19:44:12.763344Z","steps":["trace[1733766565] 'read index received' (duration: 214.179879ms)","trace[1733766565] 'applied index is now lower than readState.Index' (duration: 345.63µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:44:12.763535Z","caller":"traceutil/trace.go:171","msg":"trace[1510297984] transaction","detail":"{read_only:false; response_revision:7404; number_of_response:1; }","duration":"321.363987ms","start":"2026-02-25T19:44:12.442124Z","end":"2026-02-25T19:44:12.763488Z","steps":["trace[1510297984] 'process raft request' (duration: 320.999407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:44:12.763777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.934261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-25T19:44:12.763891Z","caller":"traceutil/trace.go:171","msg":"trace[1926318466] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7404; }","duration":"215.090355ms","start":"2026-02-25T19:44:12.548774Z","end":"2026-02-25T19:44:12.763864Z","steps":["trace[1926318466] 'agreement among raft nodes before linearized reading' (duration: 214.782917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:44:12.763797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:44:12.442106Z","time spent":"321.569562ms","remote":"127.0.0.1:59674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:44:12.8346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.397686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:44:12.834702Z","caller":"traceutil/trace.go:171","msg":"trace[289650712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7405; }","duration":"148.551903ms","start":"2026-02-25T19:44:12.686127Z","end":"2026-02-25T19:44:12.834679Z","steps":["trace[289650712] 'agreement among raft nodes before linearized reading' (duration: 148.376307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:44:12.834875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.817648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-25T19:44:12.835016Z","caller":"traceutil/trace.go:171","msg":"trace[231589312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7405; }","duration":"285.051274ms","start":"2026-02-25T19:44:12.549934Z","end":"2026-02-25T19:44:12.834986Z","steps":["trace[231589312] 'agreement among raft nodes before linearized reading' (duration: 284.611381ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:44:24.121359Z","caller":"traceutil/trace.go:171","msg":"trace[985440482] transaction","detail":"{read_only:false; response_revision:7459; number_of_response:1; }","duration":"200.621547ms","start":"2026-02-25T19:44:23.920687Z","end":"2026-02-25T19:44:24.121308Z","steps":["trace[985440482] 'process raft request' (duration: 200.461062ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:44:24.121497Z","caller":"traceutil/trace.go:171","msg":"trace[1427165792] linearizableReadLoop","detail":"{readStateIndex:7728; appliedIndex:7728; }","duration":"101.261291ms","start":"2026-02-25T19:44:24.020209Z","end":"2026-02-25T19:44:24.12147Z","steps":["trace[1427165792] 'read index received' (duration: 101.249731ms)","trace[1427165792] 'applied index is now lower than readState.Index' (duration: 9.4µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:44:24.121723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.491958ms","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-02-25T19:44:24.121827Z","caller":"traceutil/trace.go:171","msg":"trace[1630452580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7459; }","duration":"101.640172ms","start":"2026-02-25T19:44:24.020161Z","end":"2026-02-25T19:44:24.121802Z","steps":["trace[1630452580] 'agreement among raft nodes before linearized reading' (duration: 101.403686ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:44:25.091244Z","caller":"traceutil/trace.go:171","msg":"trace[146182843] linearizableReadLoop","detail":"{readStateIndex:7732; appliedIndex:7731; }","duration":"199.904268ms","start":"2026-02-25T19:44:24.891322Z","end":"2026-02-25T19:44:25.091226Z","steps":["trace[146182843] 'read index received' (duration: 199.737343ms)","trace[146182843] 'applied index is now lower than readState.Index' (duration: 166.345µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:44:25.09133Z","caller":"traceutil/trace.go:171","msg":"trace[209825701] transaction","detail":"{read_only:false; response_revision:7463; number_of_response:1; }","duration":"266.842473ms","start":"2026-02-25T19:44:24.824481Z","end":"2026-02-25T19:44:25.091323Z","steps":["trace[209825701] 'process raft request' (duration: 266.645967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:44:25.091535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.210706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-25T19:44:25.091621Z","caller":"traceutil/trace.go:171","msg":"trace[165565700] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7463; }","duration":"200.34246ms","start":"2026-02-25T19:44:24.891269Z","end":"2026-02-25T19:44:25.091611Z","steps":["trace[165565700] 'agreement among raft nodes before linearized reading' (duration: 200.187945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:44:25.091883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.503333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-25T19:44:25.091993Z","caller":"traceutil/trace.go:171","msg":"trace[1705073662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7463; }","duration":"195.658197ms","start":"2026-02-25T19:44:24.896311Z","end":"2026-02-25T19:44:25.091969Z","steps":["trace[1705073662] 'agreement among raft nodes before linearized reading' (duration: 195.37693ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:44:25.21927Z","caller":"traceutil/trace.go:171","msg":"trace[1751736641] transaction","detail":"{read_only:false; response_revision:7464; number_of_response:1; }","duration":"123.441965ms","start":"2026-02-25T19:44:25.095792Z","end":"2026-02-25T19:44:25.219234Z","steps":["trace[1751736641] 'process raft request' (duration: 72.12703ms)","trace[1751736641] 'compare' (duration: 51.099119ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:44:25.219463Z","caller":"traceutil/trace.go:171","msg":"trace[640007302] transaction","detail":"{read_only:false; response_revision:7465; number_of_response:1; }","duration":"123.107695ms","start":"2026-02-25T19:44:25.096317Z","end":"2026-02-25T19:44:25.219424Z","steps":["trace[640007302] 'process raft request' (duration: 122.859978ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:44:54.450645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5604} {"level":"info","ts":"2026-02-25T19:44:54.53273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5604,"took":"79.319332ms","hash":3337731070,"current-db-size-bytes":42602496,"current-db-size":"43 MB","current-db-size-in-use-bytes":22294528,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-02-25T19:44:54.53278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3337731070,"revision":5604,"compact-revision":2641} {"level":"info","ts":"2026-02-25T19:45:05.540495Z","caller":"traceutil/trace.go:171","msg":"trace[1129286795] transaction","detail":"{read_only:false; response_revision:7751; number_of_response:1; }","duration":"102.961449ms","start":"2026-02-25T19:45:05.437492Z","end":"2026-02-25T19:45:05.540453Z","steps":["trace[1129286795] 'process raft request' (duration: 61.643245ms)","trace[1129286795] 'compare' (duration: 41.157009ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:45:06.217235Z","caller":"traceutil/trace.go:171","msg":"trace[149088503] linearizableReadLoop","detail":"{readStateIndex:8038; appliedIndex:8037; }","duration":"127.288983ms","start":"2026-02-25T19:45:06.089921Z","end":"2026-02-25T19:45:06.21721Z","steps":["trace[149088503] 'read index received' (duration: 127.18416ms)","trace[149088503] 'applied index is now lower than readState.Index' (duration: 103.693µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:45:06.2173Z","caller":"traceutil/trace.go:171","msg":"trace[1243861907] transaction","detail":"{read_only:false; response_revision:7757; number_of_response:1; }","duration":"191.47221ms","start":"2026-02-25T19:45:06.025781Z","end":"2026-02-25T19:45:06.217254Z","steps":["trace[1243861907] 'process raft request' (duration: 191.231574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:45:06.217433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.493018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:45:06.217561Z","caller":"traceutil/trace.go:171","msg":"trace[963565373] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:7757; }","duration":"127.682644ms","start":"2026-02-25T19:45:06.089784Z","end":"2026-02-25T19:45:06.217467Z","steps":["trace[963565373] 'agreement among raft nodes before linearized reading' (duration: 127.52761ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:06.477896Z","caller":"traceutil/trace.go:171","msg":"trace[512384338] transaction","detail":"{read_only:false; response_revision:7759; number_of_response:1; }","duration":"214.351173ms","start":"2026-02-25T19:45:06.263513Z","end":"2026-02-25T19:45:06.477864Z","steps":["trace[512384338] 'process raft request' (duration: 214.173958ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:06.492616Z","caller":"traceutil/trace.go:171","msg":"trace[1706997350] transaction","detail":"{read_only:false; response_revision:7760; number_of_response:1; }","duration":"222.672078ms","start":"2026-02-25T19:45:06.269918Z","end":"2026-02-25T19:45:06.492591Z","steps":["trace[1706997350] 'process raft request' (duration: 222.467942ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:06.492838Z","caller":"traceutil/trace.go:171","msg":"trace[1706751731] linearizableReadLoop","detail":"{readStateIndex:8042; appliedIndex:8039; }","duration":"103.347189ms","start":"2026-02-25T19:45:06.389459Z","end":"2026-02-25T19:45:06.492806Z","steps":["trace[1706751731] 'read index received' (duration: 88.267065ms)","trace[1706751731] 'applied index is now lower than readState.Index' (duration: 15.075034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:45:06.493149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.284227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T19:45:06.493173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.680648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-02-25T19:45:06.493218Z","caller":"traceutil/trace.go:171","msg":"trace[1590521108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7761; }","duration":"103.40195ms","start":"2026-02-25T19:45:06.389798Z","end":"2026-02-25T19:45:06.4932Z","steps":["trace[1590521108] 'agreement among raft nodes before linearized reading' (duration: 103.194615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:45:06.493151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.108523ms","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-02-25T19:45:06.493251Z","caller":"traceutil/trace.go:171","msg":"trace[1428605974] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7761; }","duration":"103.828852ms","start":"2026-02-25T19:45:06.389401Z","end":"2026-02-25T19:45:06.49323Z","steps":["trace[1428605974] 'agreement among raft nodes before linearized reading' (duration: 103.529104ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:06.49329Z","caller":"traceutil/trace.go:171","msg":"trace[539956050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7761; }","duration":"103.256586ms","start":"2026-02-25T19:45:06.390011Z","end":"2026-02-25T19:45:06.493268Z","steps":["trace[539956050] 'agreement among raft nodes before linearized reading' (duration: 103.03253ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:06.492854Z","caller":"traceutil/trace.go:171","msg":"trace[2014888250] transaction","detail":"{read_only:false; response_revision:7761; number_of_response:1; }","duration":"175.766587ms","start":"2026-02-25T19:45:06.317077Z","end":"2026-02-25T19:45:06.492843Z","steps":["trace[2014888250] 'process raft request' (duration: 175.453589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:45:07.538052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.382606ms","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-02-25T19:45:07.538179Z","caller":"traceutil/trace.go:171","msg":"trace[826244534] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7765; }","duration":"203.53312ms","start":"2026-02-25T19:45:07.334627Z","end":"2026-02-25T19:45:07.53816Z","steps":["trace[826244534] 'range keys from in-memory index tree' (duration: 203.363336ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:07.538234Z","caller":"traceutil/trace.go:171","msg":"trace[2112324349] linearizableReadLoop","detail":"{readStateIndex:8047; appliedIndex:8046; }","duration":"166.56088ms","start":"2026-02-25T19:45:07.371609Z","end":"2026-02-25T19:45:07.53817Z","steps":["trace[2112324349] 'read index received' (duration: 107.789405ms)","trace[2112324349] 'applied index is now lower than readState.Index' (duration: 58.769955ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:45:07.538296Z","caller":"traceutil/trace.go:171","msg":"trace[1334196263] transaction","detail":"{read_only:false; response_revision:7766; number_of_response:1; }","duration":"232.364871ms","start":"2026-02-25T19:45:07.305901Z","end":"2026-02-25T19:45:07.538265Z","steps":["trace[1334196263] 'process raft request' (duration: 173.539005ms)","trace[1334196263] 'compare' (duration: 58.562949ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:45:07.538519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.901019ms","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-02-25T19:45:07.538567Z","caller":"traceutil/trace.go:171","msg":"trace[1958854201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7766; }","duration":"166.996071ms","start":"2026-02-25T19:45:07.371558Z","end":"2026-02-25T19:45:07.538554Z","steps":["trace[1958854201] 'agreement among raft nodes before linearized reading' (duration: 166.745514ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:08.078475Z","caller":"traceutil/trace.go:171","msg":"trace[1274137682] transaction","detail":"{read_only:false; response_revision:7773; number_of_response:1; }","duration":"140.364461ms","start":"2026-02-25T19:45:07.93809Z","end":"2026-02-25T19:45:08.078454Z","steps":["trace[1274137682] 'process raft request' (duration: 140.242498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:46:09.140355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.586704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:46:09.140702Z","caller":"traceutil/trace.go:171","msg":"trace[596573935] linearizableReadLoop","detail":"{readStateIndex:8643; appliedIndex:8641; }","duration":"207.177256ms","start":"2026-02-25T19:46:08.933502Z","end":"2026-02-25T19:46:09.140679Z","steps":["trace[596573935] 'read index received' (duration: 66.009656ms)","trace[596573935] 'applied index is now lower than readState.Index' (duration: 141.16604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:46:09.140985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.475214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dt726\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-02-25T19:46:09.14105Z","caller":"traceutil/trace.go:171","msg":"trace[182208912] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dt726; range_end:; response_count:1; response_revision:8344; }","duration":"207.581536ms","start":"2026-02-25T19:46:08.93345Z","end":"2026-02-25T19:46:09.141032Z","steps":["trace[182208912] 'agreement among raft nodes before linearized reading' (duration: 207.299889ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:46:09.141086Z","caller":"traceutil/trace.go:171","msg":"trace[1857192904] transaction","detail":"{read_only:false; response_revision:8343; number_of_response:1; }","duration":"270.085094ms","start":"2026-02-25T19:46:08.870956Z","end":"2026-02-25T19:46:09.141041Z","steps":["trace[1857192904] 'process raft request' (duration: 128.635576ms)","trace[1857192904] 'compare' (duration: 140.177011ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:46:09.14104Z","caller":"traceutil/trace.go:171","msg":"trace[1978398893] transaction","detail":"{read_only:false; response_revision:8344; number_of_response:1; }","duration":"267.356958ms","start":"2026-02-25T19:46:08.873619Z","end":"2026-02-25T19:46:09.140976Z","steps":["trace[1978398893] 'process raft request' (duration: 266.911836ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:48:52.477221Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bf16572acb5c7f39","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-25T19:48:52.483314Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-25T19:48:52.483443Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-25T19:49:54.457816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7661} {"level":"info","ts":"2026-02-25T19:49:54.522467Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7661,"took":"61.872324ms","hash":2311968073,"current-db-size-bytes":42602496,"current-db-size":"43 MB","current-db-size-in-use-bytes":27525120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-25T19:49:54.522531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2311968073,"revision":7661,"compact-revision":5604} {"level":"info","ts":"2026-02-25T19:54:54.466523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10211} {"level":"info","ts":"2026-02-25T19:54:54.548893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10211,"took":"80.08049ms","hash":2810657618,"current-db-size-bytes":42602496,"current-db-size":"43 MB","current-db-size-in-use-bytes":23941120,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-25T19:54:54.548948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2810657618,"revision":10211,"compact-revision":7661} {"level":"info","ts":"2026-02-25T19:55:52.445523Z","caller":"traceutil/trace.go:171","msg":"trace[1133219768] linearizableReadLoop","detail":"{readStateIndex:12877; appliedIndex:12876; }","duration":"160.972793ms","start":"2026-02-25T19:55:52.284532Z","end":"2026-02-25T19:55:52.445505Z","steps":["trace[1133219768] 'read index received' (duration: 160.808739ms)","trace[1133219768] 'applied index is now lower than readState.Index' (duration: 163.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:55:52.44557Z","caller":"traceutil/trace.go:171","msg":"trace[894199033] transaction","detail":"{read_only:false; response_revision:12439; number_of_response:1; }","duration":"234.883929ms","start":"2026-02-25T19:55:52.210657Z","end":"2026-02-25T19:55:52.445541Z","steps":["trace[894199033] 'process raft request' (duration: 234.683824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:55:52.445669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.123307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-02-25T19:55:52.445693Z","caller":"traceutil/trace.go:171","msg":"trace[2021065143] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:12439; }","duration":"161.202729ms","start":"2026-02-25T19:55:52.284483Z","end":"2026-02-25T19:55:52.445686Z","steps":["trace[2021065143] 'agreement among raft nodes before linearized reading' (duration: 161.094456ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:56:06.790657Z","caller":"traceutil/trace.go:171","msg":"trace[869465004] linearizableReadLoop","detail":"{readStateIndex:12960; appliedIndex:12959; }","duration":"245.687861ms","start":"2026-02-25T19:56:06.544947Z","end":"2026-02-25T19:56:06.790635Z","steps":["trace[869465004] 'read index received' (duration: 245.583568ms)","trace[869465004] 'applied index is now lower than readState.Index' (duration: 103.223µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:56:06.790685Z","caller":"traceutil/trace.go:171","msg":"trace[1560498913] transaction","detail":"{read_only:false; response_revision:12518; number_of_response:1; }","duration":"250.498007ms","start":"2026-02-25T19:56:06.540167Z","end":"2026-02-25T19:56:06.790665Z","steps":["trace[1560498913] 'process raft request' (duration: 250.326512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:56:06.79089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.934889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-02-25T19:56:06.79091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.645428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-x6fpl\" ","response":"range_response_count:1 size:12364"} {"level":"warn","ts":"2026-02-25T19:56:06.791002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.373641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:56:06.79103Z","caller":"traceutil/trace.go:171","msg":"trace[148478789] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:12518; }","duration":"131.447943ms","start":"2026-02-25T19:56:06.659574Z","end":"2026-02-25T19:56:06.791022Z","steps":["trace[148478789] 'agreement among raft nodes before linearized reading' (duration: 131.394362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:56:06.791015Z","caller":"traceutil/trace.go:171","msg":"trace[1820257468] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-x6fpl; range_end:; response_count:1; response_revision:12518; }","duration":"215.734921ms","start":"2026-02-25T19:56:06.57524Z","end":"2026-02-25T19:56:06.790975Z","steps":["trace[1820257468] 'agreement among raft nodes before linearized reading' (duration: 215.540415ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:56:06.790933Z","caller":"traceutil/trace.go:171","msg":"trace[125664036] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:12518; }","duration":"246.026211ms","start":"2026-02-25T19:56:06.544893Z","end":"2026-02-25T19:56:06.790919Z","steps":["trace[125664036] 'agreement among raft nodes before linearized reading' (duration: 245.864507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:56:06.790912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.07136ms","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-02-25T19:56:06.791328Z","caller":"traceutil/trace.go:171","msg":"trace[273264557] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:12518; }","duration":"101.523663ms","start":"2026-02-25T19:56:06.68978Z","end":"2026-02-25T19:56:06.791303Z","steps":["trace[273264557] 'agreement among raft nodes before linearized reading' (duration: 101.034839ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:56:17.185888Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000032fb.wal"} {"level":"info","ts":"2026-02-25T19:56:27.624572Z","caller":"traceutil/trace.go:171","msg":"trace[1209051456] linearizableReadLoop","detail":"{readStateIndex:13270; appliedIndex:13269; }","duration":"165.184096ms","start":"2026-02-25T19:56:27.459364Z","end":"2026-02-25T19:56:27.624548Z","steps":["trace[1209051456] 'read index received' (duration: 164.9639ms)","trace[1209051456] 'applied index is now lower than readState.Index' (duration: 219.086µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:56:27.624679Z","caller":"traceutil/trace.go:171","msg":"trace[1861543785] transaction","detail":"{read_only:false; response_revision:12814; number_of_response:1; }","duration":"369.329145ms","start":"2026-02-25T19:56:27.255325Z","end":"2026-02-25T19:56:27.624654Z","steps":["trace[1861543785] 'process raft request' (duration: 369.025337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:56:27.62481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.380758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-02-25T19:56:27.624812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:56:27.255309Z","time spent":"369.421218ms","remote":"127.0.0.1:59674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:56:27.624853Z","caller":"traceutil/trace.go:171","msg":"trace[1332070137] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12814; }","duration":"153.46577ms","start":"2026-02-25T19:56:27.471374Z","end":"2026-02-25T19:56:27.62484Z","steps":["trace[1332070137] 'agreement among raft nodes before linearized reading' (duration: 153.310766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:56:27.624891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.681243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T19:56:27.624969Z","caller":"traceutil/trace.go:171","msg":"trace[1284841096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12814; }","duration":"118.795746ms","start":"2026-02-25T19:56:27.506153Z","end":"2026-02-25T19:56:27.624949Z","steps":["trace[1284841096] 'agreement among raft nodes before linearized reading' (duration: 118.644912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:56:27.625744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.66507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc233ce4-cce8-4350-bfd9-55b4f926f10c\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-02-25T19:56:27.625868Z","caller":"traceutil/trace.go:171","msg":"trace[1196405432] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fc233ce4-cce8-4350-bfd9-55b4f926f10c; range_end:; response_count:1; response_revision:12814; }","duration":"166.532564ms","start":"2026-02-25T19:56:27.459313Z","end":"2026-02-25T19:56:27.625846Z","steps":["trace[1196405432] 'agreement among raft nodes before linearized reading' (duration: 165.366352ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:56:27.73175Z","caller":"traceutil/trace.go:171","msg":"trace[603698532] transaction","detail":"{read_only:false; response_revision:12816; number_of_response:1; }","duration":"101.573547ms","start":"2026-02-25T19:56:27.630139Z","end":"2026-02-25T19:56:27.731712Z","steps":["trace[603698532] 'process raft request' (duration: 70.227685ms)","trace[603698532] 'compare' (duration: 30.899049ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:57:47.768716Z","caller":"traceutil/trace.go:171","msg":"trace[82906273] linearizableReadLoop","detail":"{readStateIndex:14029; appliedIndex:14028; }","duration":"107.10991ms","start":"2026-02-25T19:57:47.661574Z","end":"2026-02-25T19:57:47.768684Z","steps":["trace[82906273] 'read index received' (duration: 106.904914ms)","trace[82906273] 'applied index is now lower than readState.Index' (duration: 203.886µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:57:47.768963Z","caller":"traceutil/trace.go:171","msg":"trace[1612583315] transaction","detail":"{read_only:false; response_revision:13551; number_of_response:1; }","duration":"139.861132ms","start":"2026-02-25T19:57:47.629029Z","end":"2026-02-25T19:57:47.76889Z","steps":["trace[1612583315] 'process raft request' (duration: 139.475181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:57:47.769232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.645274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.187\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-02-25T19:57:47.769312Z","caller":"traceutil/trace.go:171","msg":"trace[197296189] range","detail":"{range_begin:/registry/masterleases/199.19.213.187; range_end:; response_count:1; response_revision:13551; }","duration":"107.752507ms","start":"2026-02-25T19:57:47.661519Z","end":"2026-02-25T19:57:47.769271Z","steps":["trace[197296189] 'agreement among raft nodes before linearized reading' (duration: 107.430088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:58:59.00956Z","caller":"traceutil/trace.go:171","msg":"trace[2109057893] transaction","detail":"{read_only:false; response_revision:14004; number_of_response:1; }","duration":"210.560449ms","start":"2026-02-25T19:58:58.798976Z","end":"2026-02-25T19:58:59.009537Z","steps":["trace[2109057893] 'process raft request' (duration: 210.393544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:58:59.013644Z","caller":"traceutil/trace.go:171","msg":"trace[839466525] transaction","detail":"{read_only:false; response_revision:14005; number_of_response:1; }","duration":"134.467921ms","start":"2026-02-25T19:58:58.879158Z","end":"2026-02-25T19:58:59.013626Z","steps":["trace[839466525] 'process raft request' (duration: 134.246225ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:59:00.650726Z","caller":"traceutil/trace.go:171","msg":"trace[1017167609] transaction","detail":"{read_only:false; response_revision:14012; number_of_response:1; }","duration":"100.883057ms","start":"2026-02-25T19:59:00.549818Z","end":"2026-02-25T19:59:00.650701Z","steps":["trace[1017167609] 'process raft request' (duration: 100.466724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:59:00.651035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.494096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-25T19:59:00.651081Z","caller":"traceutil/trace.go:171","msg":"trace[1457369184] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14012; }","duration":"100.579109ms","start":"2026-02-25T19:59:00.550486Z","end":"2026-02-25T19:59:00.651066Z","steps":["trace[1457369184] 'agreement among raft nodes before linearized reading' (duration: 100.448835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:59:54.473175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12089} {"level":"info","ts":"2026-02-25T19:59:54.546624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12089,"took":"71.055768ms","hash":370374297,"current-db-size-bytes":42602496,"current-db-size":"43 MB","current-db-size-in-use-bytes":28028928,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-25T19:59:54.546687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":370374297,"revision":12089,"compact-revision":10211} {"level":"info","ts":"2026-02-25T20:00:08.628162Z","caller":"traceutil/trace.go:171","msg":"trace[1592168511] transaction","detail":"{read_only:false; response_revision:14571; number_of_response:1; }","duration":"103.118009ms","start":"2026-02-25T20:00:08.525015Z","end":"2026-02-25T20:00:08.628133Z","steps":["trace[1592168511] 'process raft request' (duration: 102.956305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:00:08.878261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.136044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T20:00:08.878392Z","caller":"traceutil/trace.go:171","msg":"trace[1335003540] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14571; }","duration":"151.281259ms","start":"2026-02-25T20:00:08.727086Z","end":"2026-02-25T20:00:08.878367Z","steps":["trace[1335003540] 'count revisions from in-memory index tree' (duration: 151.079863ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:01.329689Z","caller":"traceutil/trace.go:171","msg":"trace[1798959776] transaction","detail":"{read_only:false; response_revision:15847; number_of_response:1; }","duration":"116.384331ms","start":"2026-02-25T20:03:01.213274Z","end":"2026-02-25T20:03:01.329659Z","steps":["trace[1798959776] 'process raft request' (duration: 115.551327ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:01.736996Z","caller":"traceutil/trace.go:171","msg":"trace[1562857459] linearizableReadLoop","detail":"{readStateIndex:16406; appliedIndex:16405; }","duration":"149.345035ms","start":"2026-02-25T20:03:01.587625Z","end":"2026-02-25T20:03:01.736971Z","steps":["trace[1562857459] 'read index received' (duration: 149.142059ms)","trace[1562857459] 'applied index is now lower than readState.Index' (duration: 202.036µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T20:03:01.737181Z","caller":"traceutil/trace.go:171","msg":"trace[1683945175] transaction","detail":"{read_only:false; response_revision:15849; number_of_response:1; }","duration":"150.904709ms","start":"2026-02-25T20:03:01.586219Z","end":"2026-02-25T20:03:01.737123Z","steps":["trace[1683945175] 'process raft request' (duration: 150.609221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:03:01.738009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.360003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:944744"} {"level":"info","ts":"2026-02-25T20:03:01.738064Z","caller":"traceutil/trace.go:171","msg":"trace[111151720] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:15849; }","duration":"150.433305ms","start":"2026-02-25T20:03:01.587618Z","end":"2026-02-25T20:03:01.738051Z","steps":["trace[111151720] 'agreement among raft nodes before linearized reading' (duration: 149.511189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:03:02.150906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.991871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-25T20:03:02.151007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.638673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-02-25T20:03:02.151007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.060813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-25T20:03:02.151065Z","caller":"traceutil/trace.go:171","msg":"trace[347193263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15849; }","duration":"262.244348ms","start":"2026-02-25T20:03:01.888785Z","end":"2026-02-25T20:03:02.151029Z","steps":["trace[347193263] 'range keys from in-memory index tree' (duration: 261.651651ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:02.151095Z","caller":"traceutil/trace.go:171","msg":"trace[276218218] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15849; }","duration":"262.216757ms","start":"2026-02-25T20:03:01.888861Z","end":"2026-02-25T20:03:02.151078Z","steps":["trace[276218218] 'range keys from in-memory index tree' (duration: 261.871288ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:02.151092Z","caller":"traceutil/trace.go:171","msg":"trace[628589650] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15849; }","duration":"265.783287ms","start":"2026-02-25T20:03:01.885289Z","end":"2026-02-25T20:03:02.151073Z","steps":["trace[628589650] 'range keys from in-memory index tree' (duration: 265.427707ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.719232Z","caller":"traceutil/trace.go:171","msg":"trace[1760389907] transaction","detail":"{read_only:false; response_revision:15993; number_of_response:1; }","duration":"106.203436ms","start":"2026-02-25T20:03:09.612999Z","end":"2026-02-25T20:03:09.719203Z","steps":["trace[1760389907] 'process raft request' (duration: 105.884336ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.719226Z","caller":"traceutil/trace.go:171","msg":"trace[1573248186] transaction","detail":"{read_only:false; response_revision:15992; number_of_response:1; }","duration":"106.274208ms","start":"2026-02-25T20:03:09.612884Z","end":"2026-02-25T20:03:09.719159Z","steps":["trace[1573248186] 'process raft request' (duration: 105.961398ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.719508Z","caller":"traceutil/trace.go:171","msg":"trace[1626955575] transaction","detail":"{read_only:false; response_revision:15994; number_of_response:1; }","duration":"106.438702ms","start":"2026-02-25T20:03:09.61306Z","end":"2026-02-25T20:03:09.719499Z","steps":["trace[1626955575] 'process raft request' (duration: 105.848545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.719505Z","caller":"traceutil/trace.go:171","msg":"trace[1707596527] transaction","detail":"{read_only:false; response_revision:15990; number_of_response:1; }","duration":"106.630727ms","start":"2026-02-25T20:03:09.612839Z","end":"2026-02-25T20:03:09.719469Z","steps":["trace[1707596527] 'process raft request' (duration: 105.925137ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.719613Z","caller":"traceutil/trace.go:171","msg":"trace[1071934569] transaction","detail":"{read_only:false; number_of_response:1; response_revision:15994; }","duration":"105.697611ms","start":"2026-02-25T20:03:09.613907Z","end":"2026-02-25T20:03:09.719605Z","steps":["trace[1071934569] 'process raft request' (duration: 105.030952ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.719715Z","caller":"traceutil/trace.go:171","msg":"trace[692993410] transaction","detail":"{read_only:false; response_revision:15989; number_of_response:1; }","duration":"106.976987ms","start":"2026-02-25T20:03:09.612717Z","end":"2026-02-25T20:03:09.719694Z","steps":["trace[692993410] 'process raft request' (duration: 105.905136ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.719787Z","caller":"traceutil/trace.go:171","msg":"trace[1471837496] transaction","detail":"{read_only:false; response_revision:15991; number_of_response:1; }","duration":"106.874385ms","start":"2026-02-25T20:03:09.612891Z","end":"2026-02-25T20:03:09.719766Z","steps":["trace[1471837496] 'process raft request' (duration: 105.920747ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.71982Z","caller":"traceutil/trace.go:171","msg":"trace[824603056] transaction","detail":"{read_only:false; response_revision:15996; number_of_response:1; }","duration":"102.252545ms","start":"2026-02-25T20:03:09.617559Z","end":"2026-02-25T20:03:09.719811Z","steps":["trace[824603056] 'process raft request' (duration: 101.541175ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.720192Z","caller":"traceutil/trace.go:171","msg":"trace[2016678457] transaction","detail":"{read_only:false; response_revision:15995; number_of_response:1; }","duration":"106.070302ms","start":"2026-02-25T20:03:09.614055Z","end":"2026-02-25T20:03:09.720125Z","steps":["trace[2016678457] 'process raft request' (duration: 104.931499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:03:09.734406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.314073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2364562"} {"level":"info","ts":"2026-02-25T20:03:09.734484Z","caller":"traceutil/trace.go:171","msg":"trace[318048830] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:15996; }","duration":"119.391145ms","start":"2026-02-25T20:03:09.615067Z","end":"2026-02-25T20:03:09.734458Z","steps":["trace[318048830] 'agreement among raft nodes before linearized reading' (duration: 104.888679ms)","trace[318048830] 'range keys from bolt db' (duration: 14.348852ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T20:03:09.888669Z","caller":"traceutil/trace.go:171","msg":"trace[1223602984] linearizableReadLoop","detail":"{readStateIndex:16559; appliedIndex:16558; }","duration":"169.472178ms","start":"2026-02-25T20:03:09.71917Z","end":"2026-02-25T20:03:09.888643Z","steps":["trace[1223602984] 'read index received' (duration: 131.207916ms)","trace[1223602984] 'applied index is now lower than readState.Index' (duration: 38.263212ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T20:03:09.889111Z","caller":"traceutil/trace.go:171","msg":"trace[1705139881] transaction","detail":"{read_only:false; response_revision:15997; number_of_response:1; }","duration":"204.628204ms","start":"2026-02-25T20:03:09.684464Z","end":"2026-02-25T20:03:09.889092Z","steps":["trace[1705139881] 'process raft request' (duration: 165.901898ms)","trace[1705139881] 'compare' (duration: 38.15233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T20:03:09.889657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.033211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-25T20:03:09.889712Z","caller":"traceutil/trace.go:171","msg":"trace[459679007] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:15997; }","duration":"202.110563ms","start":"2026-02-25T20:03:09.687585Z","end":"2026-02-25T20:03:09.889695Z","steps":["trace[459679007] 'agreement among raft nodes before linearized reading' (duration: 201.841545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:03:09.890213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.374335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-td2qh-vnc-w964q\" ","response":"range_response_count:1 size:2530"} {"level":"info","ts":"2026-02-25T20:03:09.890368Z","caller":"traceutil/trace.go:171","msg":"trace[969864615] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-td2qh-vnc-w964q; range_end:; response_count:1; response_revision:15997; }","duration":"205.519319ms","start":"2026-02-25T20:03:09.684776Z","end":"2026-02-25T20:03:09.890295Z","steps":["trace[969864615] 'agreement among raft nodes before linearized reading' (duration: 205.132438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:03:09.89066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.676607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-td2qh-vnc\" ","response":"range_response_count:1 size:4286"} {"level":"info","ts":"2026-02-25T20:03:09.890748Z","caller":"traceutil/trace.go:171","msg":"trace[282340498] transaction","detail":"{read_only:false; response_revision:15998; number_of_response:1; }","duration":"160.970581ms","start":"2026-02-25T20:03:09.729749Z","end":"2026-02-25T20:03:09.89072Z","steps":["trace[282340498] 'process raft request' (duration: 160.540499ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.89098Z","caller":"traceutil/trace.go:171","msg":"trace[1615937888] transaction","detail":"{read_only:false; response_revision:15999; number_of_response:1; }","duration":"155.837057ms","start":"2026-02-25T20:03:09.735132Z","end":"2026-02-25T20:03:09.890969Z","steps":["trace[1615937888] 'process raft request' (duration: 155.285822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:03:09.891001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.638492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-02-25T20:03:09.891125Z","caller":"traceutil/trace.go:171","msg":"trace[2104813058] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:16001; }","duration":"129.682944ms","start":"2026-02-25T20:03:09.761347Z","end":"2026-02-25T20:03:09.89103Z","steps":["trace[2104813058] 'agreement among raft nodes before linearized reading' (duration: 129.56281ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.891205Z","caller":"traceutil/trace.go:171","msg":"trace[1688780911] transaction","detail":"{read_only:false; response_revision:16000; number_of_response:1; }","duration":"150.438575ms","start":"2026-02-25T20:03:09.740757Z","end":"2026-02-25T20:03:09.891195Z","steps":["trace[1688780911] 'process raft request' (duration: 149.712055ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.890712Z","caller":"traceutil/trace.go:171","msg":"trace[1703403382] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-td2qh-vnc; range_end:; response_count:1; response_revision:16000; }","duration":"203.77826ms","start":"2026-02-25T20:03:09.686921Z","end":"2026-02-25T20:03:09.890699Z","steps":["trace[1703403382] 'agreement among raft nodes before linearized reading' (duration: 203.628396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:03:09.891571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.85856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T20:03:09.891618Z","caller":"traceutil/trace.go:171","msg":"trace[706954658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16001; }","duration":"205.012364ms","start":"2026-02-25T20:03:09.686594Z","end":"2026-02-25T20:03:09.891606Z","steps":["trace[706954658] 'agreement among raft nodes before linearized reading' (duration: 204.100269ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:03:09.89194Z","caller":"traceutil/trace.go:171","msg":"trace[2057837798] transaction","detail":"{read_only:false; response_revision:16001; number_of_response:1; }","duration":"148.998344ms","start":"2026-02-25T20:03:09.742928Z","end":"2026-02-25T20:03:09.891927Z","steps":["trace[2057837798] 'process raft request' (duration: 147.602096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:03:09.892175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.059846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-25T20:03:09.892233Z","caller":"traceutil/trace.go:171","msg":"trace[58405468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16001; }","duration":"124.146478ms","start":"2026-02-25T20:03:09.768072Z","end":"2026-02-25T20:03:09.892219Z","steps":["trace[58405468] 'agreement among raft nodes before linearized reading' (duration: 123.996334ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:04:54.479181Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14465} {"level":"info","ts":"2026-02-25T20:04:54.577477Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14465,"took":"95.431284ms","hash":527789703,"current-db-size-bytes":43024384,"current-db-size":"43 MB","current-db-size-in-use-bytes":29323264,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-25T20:04:54.577545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":527789703,"revision":14465,"compact-revision":12089} {"level":"info","ts":"2026-02-25T20:09:54.485862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16862} {"level":"info","ts":"2026-02-25T20:09:54.587311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16862,"took":"96.685029ms","hash":3068414047,"current-db-size-bytes":43024384,"current-db-size":"43 MB","current-db-size-in-use-bytes":24436736,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-25T20:09:54.587395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3068414047,"revision":16862,"compact-revision":14465}