{"level":"warn","ts":"2026-02-26T21:01:37.374361Z","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-26T21:01:37.374463Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41: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.204.45.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41: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-26T21:01:37.374549Z","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-26T21:01:37.374562Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-26T21:01:37.374592Z","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-26T21:01:37.375352Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-26T21:01:37.375454Z","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.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41: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-26T21:01:37.379831Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.095147ms"} {"level":"info","ts":"2026-02-26T21:01:37.384912Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-26T21:01:37.385008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T21:01:37.385037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-26T21:01:37.385058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T21:01:37.385079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-26T21:01:37.385135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-26T21:01:37.390885Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T21:01:37.393004Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T21:01:37.394385Z","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-26T21:01:37.397276Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T21:01:37.397545Z","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-26T21:01:37.3977Z","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-26T21:01:37.397654Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T21:01:37.397724Z","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-26T21:01:37.399356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-26T21:01:37.399672Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-26T21:01:37.401893Z","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-26T21:01:37.401967Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-26T21:01:37.402004Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-26T21:01:37.402366Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T21:01:37.40245Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T21:01:38.186126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T21:01:38.186206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T21:01:38.186248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-26T21:01:38.186274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-26T21:01:38.186285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-26T21:01:38.186303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-26T21:01:38.186324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-26T21:01:38.187512Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:38.188369Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T21:01:38.18837Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T21:01:38.188404Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T21:01:38.188739Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T21:01:38.188838Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:38.188886Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T21:01:38.189011Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:38.18905Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:38.192266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T21:01:38.192369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-26T21:01:56.302612Z","caller":"traceutil/trace.go:171","msg":"trace[1697834621] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"368.187698ms","start":"2026-02-26T21:01:55.934375Z","end":"2026-02-26T21:01:56.302563Z","steps":["trace[1697834621] 'read index received' (duration: 368.022784ms)","trace[1697834621] 'applied index is now lower than readState.Index' (duration: 163.924µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:56.302702Z","caller":"traceutil/trace.go:171","msg":"trace[1475573299] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"411.064468ms","start":"2026-02-26T21:01:55.891597Z","end":"2026-02-26T21:01:56.302661Z","steps":["trace[1475573299] 'process raft request' (duration: 410.825752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:56.302968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.822871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T21:01:56.303086Z","caller":"traceutil/trace.go:171","msg":"trace[1364513298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:446; }","duration":"250.019206ms","start":"2026-02-26T21:01:56.053045Z","end":"2026-02-26T21:01:56.303064Z","steps":["trace[1364513298] 'agreement among raft nodes before linearized reading' (duration: 249.816441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:56.303165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.731052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T21:01:56.303376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:55.891551Z","time spent":"411.251103ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:56.303447Z","caller":"traceutil/trace.go:171","msg":"trace[1935844890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:446; }","duration":"369.00606ms","start":"2026-02-26T21:01:55.934335Z","end":"2026-02-26T21:01:56.303341Z","steps":["trace[1935844890] 'agreement among raft nodes before linearized reading' (duration: 368.663931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:56.303624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:55.934311Z","time spent":"369.293918ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:01:56.917495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.088178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:01:56.917641Z","caller":"traceutil/trace.go:171","msg":"trace[598038432] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"611.034845ms","start":"2026-02-26T21:01:56.306577Z","end":"2026-02-26T21:01:56.917612Z","steps":["trace[598038432] 'process raft request' (duration: 453.588138ms)","trace[598038432] 'compare' (duration: 156.930073ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:56.917875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:56.306558Z","time spent":"611.144577ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:56.920773Z","caller":"traceutil/trace.go:171","msg":"trace[1299890636] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"612.839712ms","start":"2026-02-26T21:01:56.307768Z","end":"2026-02-26T21:01:56.920608Z","steps":["trace[1299890636] 'process raft request' (duration: 612.494963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:56.921175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:56.30776Z","time spent":"613.213082ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:56.922465Z","caller":"traceutil/trace.go:171","msg":"trace[912409184] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:460; }","duration":"508.888914ms","start":"2026-02-26T21:01:56.413501Z","end":"2026-02-26T21:01:56.92239Z","steps":["trace[912409184] 'read index received' (duration: 346.806584ms)","trace[912409184] 'applied index is now lower than readState.Index' (duration: 162.08146ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:56.922558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.059889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:56.922595Z","caller":"traceutil/trace.go:171","msg":"trace[1025215496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"509.171552ms","start":"2026-02-26T21:01:56.413411Z","end":"2026-02-26T21:01:56.922583Z","steps":["trace[1025215496] 'agreement among raft nodes before linearized reading' (duration: 509.11145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:56.922632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:56.413387Z","time spent":"509.238163ms","remote":"127.0.0.1:36512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T21:02:08.562901Z","caller":"traceutil/trace.go:171","msg":"trace[392546414] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"176.881665ms","start":"2026-02-26T21:02:08.385993Z","end":"2026-02-26T21:02:08.562875Z","steps":["trace[392546414] 'read index received' (duration: 176.675829ms)","trace[392546414] 'applied index is now lower than readState.Index' (duration: 204.956µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:08.563083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.06874ms","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-26T21:02:08.563124Z","caller":"traceutil/trace.go:171","msg":"trace[939807700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"177.177692ms","start":"2026-02-26T21:02:08.385936Z","end":"2026-02-26T21:02:08.563114Z","steps":["trace[939807700] 'agreement among raft nodes before linearized reading' (duration: 177.056429ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:08.56311Z","caller":"traceutil/trace.go:171","msg":"trace[477632892] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"264.717037ms","start":"2026-02-26T21:02:08.298325Z","end":"2026-02-26T21:02:08.563042Z","steps":["trace[477632892] 'process raft request' (duration: 264.419068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:14.860804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.510879ms","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-26T21:02:14.860907Z","caller":"traceutil/trace.go:171","msg":"trace[1512838475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"262.668344ms","start":"2026-02-26T21:02:14.598215Z","end":"2026-02-26T21:02:14.860884Z","steps":["trace[1512838475] 'range keys from in-memory index tree' (duration: 262.306732ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:15.233626Z","caller":"traceutil/trace.go:171","msg":"trace[1480011895] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"215.60326ms","start":"2026-02-26T21:02:15.017988Z","end":"2026-02-26T21:02:15.233591Z","steps":["trace[1480011895] 'process raft request' (duration: 198.218684ms)","trace[1480011895] 'compare' (duration: 17.14797ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:15.233795Z","caller":"traceutil/trace.go:171","msg":"trace[865251952] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"214.524221ms","start":"2026-02-26T21:02:15.019234Z","end":"2026-02-26T21:02:15.233758Z","steps":["trace[865251952] 'process raft request' (duration: 214.276095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:16.100458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.166485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:16.100699Z","caller":"traceutil/trace.go:171","msg":"trace[1726515456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"121.477184ms","start":"2026-02-26T21:02:15.979196Z","end":"2026-02-26T21:02:16.100673Z","steps":["trace[1726515456] 'range keys from in-memory index tree' (duration: 121.068122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:16.100466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:15.585314Z","time spent":"515.140879ms","remote":"127.0.0.1:60436","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-26T21:02:17.768899Z","caller":"traceutil/trace.go:171","msg":"trace[1243378142] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"194.179008ms","start":"2026-02-26T21:02:17.574684Z","end":"2026-02-26T21:02:17.768863Z","steps":["trace[1243378142] 'process raft request' (duration: 173.753813ms)","trace[1243378142] 'compare' (duration: 20.22749ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:18.085517Z","caller":"traceutil/trace.go:171","msg":"trace[1724578755] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"117.52939ms","start":"2026-02-26T21:02:17.967962Z","end":"2026-02-26T21:02:18.085492Z","steps":["trace[1724578755] 'process raft request' (duration: 58.013901ms)","trace[1724578755] 'compare' (duration: 59.212431ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:18.085644Z","caller":"traceutil/trace.go:171","msg":"trace[1728445483] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"116.543234ms","start":"2026-02-26T21:02:17.969067Z","end":"2026-02-26T21:02:18.085611Z","steps":["trace[1728445483] 'process raft request' (duration: 116.304627ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:18.103957Z","caller":"traceutil/trace.go:171","msg":"trace[1670211473] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"124.917113ms","start":"2026-02-26T21:02:17.97902Z","end":"2026-02-26T21:02:18.103937Z","steps":["trace[1670211473] 'process raft request' (duration: 106.44362ms)","trace[1670211473] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/apiextensions.k8s.io/customresourcedefinitions/challenges.acme.cert-manager.io; req_size:59677; } (duration: 18.2969ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:18.104Z","caller":"traceutil/trace.go:171","msg":"trace[344228748] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"124.967765ms","start":"2026-02-26T21:02:17.979015Z","end":"2026-02-26T21:02:18.103983Z","steps":["trace[344228748] 'read index received' (duration: 47.048764ms)","trace[344228748] 'applied index is now lower than readState.Index' (duration: 77.918491ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:18.104027Z","caller":"traceutil/trace.go:171","msg":"trace[1089815726] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"121.973316ms","start":"2026-02-26T21:02:17.98204Z","end":"2026-02-26T21:02:18.104013Z","steps":["trace[1089815726] 'process raft request' (duration: 121.837483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:18.104081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.125699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:18.104108Z","caller":"traceutil/trace.go:171","msg":"trace[1504425731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"125.209931ms","start":"2026-02-26T21:02:17.97889Z","end":"2026-02-26T21:02:18.1041Z","steps":["trace[1504425731] 'agreement among raft nodes before linearized reading' (duration: 125.14586ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:18.42654Z","caller":"traceutil/trace.go:171","msg":"trace[655060579] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"337.522634ms","start":"2026-02-26T21:02:18.088993Z","end":"2026-02-26T21:02:18.426516Z","steps":["trace[655060579] 'process raft request' (duration: 337.259467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:18.426647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:18.088982Z","time spent":"337.615706ms","remote":"127.0.0.1:36646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:02:18.427823Z","caller":"traceutil/trace.go:171","msg":"trace[1161098124] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"340.561343ms","start":"2026-02-26T21:02:18.087212Z","end":"2026-02-26T21:02:18.427773Z","steps":["trace[1161098124] 'process raft request' (duration: 253.285137ms)","trace[1161098124] 'compare' (duration: 85.596553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:18.428053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:18.087191Z","time spent":"340.718919ms","remote":"127.0.0.1:36678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1372,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:02:18.428321Z","caller":"traceutil/trace.go:171","msg":"trace[1054977793] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"338.994032ms","start":"2026-02-26T21:02:18.089063Z","end":"2026-02-26T21:02:18.428057Z","steps":["trace[1054977793] 'process raft request' (duration: 337.233496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:18.428524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:18.089046Z","time spent":"339.404393ms","remote":"127.0.0.1:36774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1366,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:02:18.429274Z","caller":"traceutil/trace.go:171","msg":"trace[2105596878] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"285.493922ms","start":"2026-02-26T21:02:18.143744Z","end":"2026-02-26T21:02:18.429238Z","steps":["trace[2105596878] 'process raft request' (duration: 282.761ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:18.429358Z","caller":"traceutil/trace.go:171","msg":"trace[752008881] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"287.974127ms","start":"2026-02-26T21:02:18.141364Z","end":"2026-02-26T21:02:18.429338Z","steps":["trace[752008881] 'process raft request' (duration: 284.980478ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:18.430103Z","caller":"traceutil/trace.go:171","msg":"trace[1795384650] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"281.940439ms","start":"2026-02-26T21:02:18.148104Z","end":"2026-02-26T21:02:18.430044Z","steps":["trace[1795384650] 'process raft request' (duration: 280.382898ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:19.197781Z","caller":"traceutil/trace.go:171","msg":"trace[1019221959] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"124.530713ms","start":"2026-02-26T21:02:19.073226Z","end":"2026-02-26T21:02:19.197757Z","steps":["trace[1019221959] 'process raft request' (duration: 124.266986ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:37.632264Z","caller":"traceutil/trace.go:171","msg":"trace[2055353044] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"283.91263ms","start":"2026-02-26T21:02:37.34833Z","end":"2026-02-26T21:02:37.632243Z","steps":["trace[2055353044] 'process raft request' (duration: 283.791797ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:37.691751Z","caller":"traceutil/trace.go:171","msg":"trace[795781582] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:955; }","duration":"174.669997ms","start":"2026-02-26T21:02:37.51705Z","end":"2026-02-26T21:02:37.69172Z","steps":["trace[795781582] 'read index received' (duration: 115.135227ms)","trace[795781582] 'applied index is now lower than readState.Index' (duration: 59.53325ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:37.692013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.942604ms","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-26T21:02:37.692053Z","caller":"traceutil/trace.go:171","msg":"trace[317806706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"175.061547ms","start":"2026-02-26T21:02:37.516979Z","end":"2026-02-26T21:02:37.692041Z","steps":["trace[317806706] 'agreement among raft nodes before linearized reading' (duration: 174.866712ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:37.692068Z","caller":"traceutil/trace.go:171","msg":"trace[925925335] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"343.639805ms","start":"2026-02-26T21:02:37.348377Z","end":"2026-02-26T21:02:37.692017Z","steps":["trace[925925335] 'process raft request' (duration: 341.015495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:37.692246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:37.348362Z","time spent":"343.796359ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:42.216305Z","caller":"traceutil/trace.go:171","msg":"trace[509590451] transaction","detail":"{read_only:false; response_revision:1696; number_of_response:1; }","duration":"116.050974ms","start":"2026-02-26T21:03:42.100218Z","end":"2026-02-26T21:03:42.216269Z","steps":["trace[509590451] 'process raft request' (duration: 115.729136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:42.349683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.599509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:42.349805Z","caller":"traceutil/trace.go:171","msg":"trace[1918306845] transaction","detail":"{read_only:false; response_revision:1697; number_of_response:1; }","duration":"128.535543ms","start":"2026-02-26T21:03:42.221252Z","end":"2026-02-26T21:03:42.349787Z","steps":["trace[1918306845] 'process raft request' (duration: 20.739779ms)","trace[1918306845] 'compare' (duration: 107.456665ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:42.352023Z","caller":"traceutil/trace.go:171","msg":"trace[451284506] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"121.914434ms","start":"2026-02-26T21:03:42.230074Z","end":"2026-02-26T21:03:42.351989Z","steps":["trace[451284506] 'process raft request' (duration: 121.680858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.147808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.756549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-02-26T21:03:46.147948Z","caller":"traceutil/trace.go:171","msg":"trace[247996503] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1719; }","duration":"570.937493ms","start":"2026-02-26T21:03:45.576982Z","end":"2026-02-26T21:03:46.14792Z","steps":["trace[247996503] 'range keys from in-memory index tree' (duration: 570.575634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.148022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.576964Z","time spent":"571.042075ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T21:03:46.148012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.269195ms","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-26T21:03:46.148113Z","caller":"traceutil/trace.go:171","msg":"trace[614880450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1719; }","duration":"579.427368ms","start":"2026-02-26T21:03:45.568661Z","end":"2026-02-26T21:03:46.148089Z","steps":["trace[614880450] 'range keys from in-memory index tree' (duration: 579.022939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.148167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.568638Z","time spent":"579.520801ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:03:46.14816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.597229ms","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":"warn","ts":"2026-02-26T21:03:46.148531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.995559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-02-26T21:03:46.148597Z","caller":"traceutil/trace.go:171","msg":"trace[260966008] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1721; }","duration":"491.09808ms","start":"2026-02-26T21:03:45.657481Z","end":"2026-02-26T21:03:46.148579Z","steps":["trace[260966008] 'agreement among raft nodes before linearized reading' (duration: 490.912896ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:46.148612Z","caller":"traceutil/trace.go:171","msg":"trace[1446203194] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1719; }","duration":"612.322937ms","start":"2026-02-26T21:03:45.536168Z","end":"2026-02-26T21:03:46.148491Z","steps":["trace[1446203194] 'range keys from in-memory index tree' (duration: 611.362633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.148648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.657453Z","time spent":"491.182252ms","remote":"127.0.0.1:38472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-26T21:03:46.14869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.536099Z","time spent":"612.571993ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-02-26T21:03:46.148296Z","caller":"traceutil/trace.go:171","msg":"trace[853454791] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"329.713103ms","start":"2026-02-26T21:03:45.818556Z","end":"2026-02-26T21:03:46.14827Z","steps":["trace[853454791] 'process raft request' (duration: 329.651611ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:46.148287Z","caller":"traceutil/trace.go:171","msg":"trace[346399393] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"520.543071ms","start":"2026-02-26T21:03:45.627692Z","end":"2026-02-26T21:03:46.148235Z","steps":["trace[346399393] 'process raft request' (duration: 482.506062ms)","trace[346399393] 'compare' (duration: 37.445864ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:46.148936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.81853Z","time spent":"330.319478ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:46.148325Z","caller":"traceutil/trace.go:171","msg":"trace[900359859] linearizableReadLoop","detail":"{readStateIndex:1783; appliedIndex:1782; }","duration":"490.747372ms","start":"2026-02-26T21:03:45.657532Z","end":"2026-02-26T21:03:46.148279Z","steps":["trace[900359859] 'read index received' (duration: 452.682122ms)","trace[900359859] 'applied index is now lower than readState.Index' (duration: 38.0611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:46.149289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.627666Z","time spent":"521.362722ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7967,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:46.149402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.370866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:46.149461Z","caller":"traceutil/trace.go:171","msg":"trace[1732432223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1721; }","duration":"170.455419ms","start":"2026-02-26T21:03:45.978979Z","end":"2026-02-26T21:03:46.149435Z","steps":["trace[1732432223] 'agreement among raft nodes before linearized reading' (duration: 170.352496ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:01.880478Z","caller":"traceutil/trace.go:171","msg":"trace[785305208] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2222; }","duration":"126.787161ms","start":"2026-02-26T21:05:01.753652Z","end":"2026-02-26T21:05:01.880439Z","steps":["trace[785305208] 'process raft request' (duration: 126.631247ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:01.921251Z","caller":"traceutil/trace.go:171","msg":"trace[481977185] linearizableReadLoop","detail":"{readStateIndex:2311; appliedIndex:2309; }","duration":"140.115933ms","start":"2026-02-26T21:05:01.781101Z","end":"2026-02-26T21:05:01.921217Z","steps":["trace[481977185] 'read index received' (duration: 99.206402ms)","trace[481977185] 'applied index is now lower than readState.Index' (duration: 40.908291ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:05:01.921372Z","caller":"traceutil/trace.go:171","msg":"trace[2052115628] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"140.738119ms","start":"2026-02-26T21:05:01.780603Z","end":"2026-02-26T21:05:01.921341Z","steps":["trace[2052115628] 'process raft request' (duration: 140.336739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:05:01.921583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.449102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/valkey-server-pvqqx\" ","response":"range_response_count:1 size:2450"} {"level":"info","ts":"2026-02-26T21:05:01.921666Z","caller":"traceutil/trace.go:171","msg":"trace[141178642] range","detail":"{range_begin:/registry/secrets/openstack/valkey-server-pvqqx; range_end:; response_count:1; response_revision:2224; }","duration":"140.593885ms","start":"2026-02-26T21:05:01.781054Z","end":"2026-02-26T21:05:01.921647Z","steps":["trace[141178642] 'agreement among raft nodes before linearized reading' (duration: 140.330048ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:02.013858Z","caller":"traceutil/trace.go:171","msg":"trace[181028661] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"113.57099ms","start":"2026-02-26T21:05:01.900233Z","end":"2026-02-26T21:05:02.013804Z","steps":["trace[181028661] 'process raft request' (duration: 108.761707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:05:02.014176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.294813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/valkey-server-certs\" ","response":"range_response_count:1 size:5410"} {"level":"info","ts":"2026-02-26T21:05:02.014281Z","caller":"traceutil/trace.go:171","msg":"trace[1284944240] range","detail":"{range_begin:/registry/secrets/openstack/valkey-server-certs; range_end:; response_count:1; response_revision:2226; }","duration":"230.476857ms","start":"2026-02-26T21:05:01.783778Z","end":"2026-02-26T21:05:02.014254Z","steps":["trace[1284944240] 'agreement among raft nodes before linearized reading' (duration: 230.104267ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:42.542145Z","caller":"traceutil/trace.go:171","msg":"trace[1261321052] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"236.555893ms","start":"2026-02-26T21:05:42.305566Z","end":"2026-02-26T21:05:42.542121Z","steps":["trace[1261321052] 'process raft request' (duration: 235.892206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:05:42.904537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.338049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.1897e7e12a17b215\" ","response":"range_response_count:1 size:768"} {"level":"info","ts":"2026-02-26T21:05:42.904641Z","caller":"traceutil/trace.go:171","msg":"trace[1501786920] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.1897e7e12a17b215; range_end:; response_count:1; response_revision:2490; }","duration":"247.504614ms","start":"2026-02-26T21:05:42.657116Z","end":"2026-02-26T21:05:42.904621Z","steps":["trace[1501786920] 'range keys from in-memory index tree' (duration: 247.172245ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:42.934713Z","caller":"traceutil/trace.go:171","msg":"trace[622866617] linearizableReadLoop","detail":"{readStateIndex:2587; appliedIndex:2586; }","duration":"157.641181ms","start":"2026-02-26T21:05:42.777044Z","end":"2026-02-26T21:05:42.934686Z","steps":["trace[622866617] 'read index received' (duration: 157.438426ms)","trace[622866617] 'applied index is now lower than readState.Index' (duration: 201.605µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:05:42.934869Z","caller":"traceutil/trace.go:171","msg":"trace[1417893519] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"159.283532ms","start":"2026-02-26T21:05:42.775508Z","end":"2026-02-26T21:05:42.934792Z","steps":["trace[1417893519] 'process raft request' (duration: 159.012066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:05:42.935103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.04635ms","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-26T21:05:42.935155Z","caller":"traceutil/trace.go:171","msg":"trace[1672625972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2491; }","duration":"158.165554ms","start":"2026-02-26T21:05:42.776975Z","end":"2026-02-26T21:05:42.935141Z","steps":["trace[1672625972] 'agreement among raft nodes before linearized reading' (duration: 157.838465ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:43.051444Z","caller":"traceutil/trace.go:171","msg":"trace[2095294966] linearizableReadLoop","detail":"{readStateIndex:2589; appliedIndex:2587; }","duration":"116.632309ms","start":"2026-02-26T21:05:42.934788Z","end":"2026-02-26T21:05:43.05142Z","steps":["trace[2095294966] 'read index received' (duration: 116.461364ms)","trace[2095294966] 'applied index is now lower than readState.Index' (duration: 169.855µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:05:43.051485Z","caller":"traceutil/trace.go:171","msg":"trace[111311798] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"144.606476ms","start":"2026-02-26T21:05:42.906844Z","end":"2026-02-26T21:05:43.051451Z","steps":["trace[111311798] 'process raft request' (duration: 144.510484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:05:43.051669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.353948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-26T21:05:43.05169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.689309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:05:43.051726Z","caller":"traceutil/trace.go:171","msg":"trace[1568526739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2493; }","duration":"256.750951ms","start":"2026-02-26T21:05:42.794965Z","end":"2026-02-26T21:05:43.051716Z","steps":["trace[1568526739] 'agreement among raft nodes before linearized reading' (duration: 256.566556ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:43.051728Z","caller":"traceutil/trace.go:171","msg":"trace[481011564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2493; }","duration":"254.447461ms","start":"2026-02-26T21:05:42.79725Z","end":"2026-02-26T21:05:43.051698Z","steps":["trace[481011564] 'agreement among raft nodes before linearized reading' (duration: 254.210165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:05:43.051897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.340138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-02-26T21:05:43.051919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.03597ms","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-26T21:05:43.051884Z","caller":"traceutil/trace.go:171","msg":"trace[803251967] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"274.259819ms","start":"2026-02-26T21:05:42.777559Z","end":"2026-02-26T21:05:43.051819Z","steps":["trace[803251967] 'process raft request' (duration: 273.659654ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:43.051964Z","caller":"traceutil/trace.go:171","msg":"trace[147552870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2493; }","duration":"263.107432ms","start":"2026-02-26T21:05:42.788844Z","end":"2026-02-26T21:05:43.051951Z","steps":["trace[147552870] 'agreement among raft nodes before linearized reading' (duration: 262.990359ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:43.051974Z","caller":"traceutil/trace.go:171","msg":"trace[161113808] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2493; }","duration":"254.463371ms","start":"2026-02-26T21:05:42.797485Z","end":"2026-02-26T21:05:43.051949Z","steps":["trace[161113808] 'agreement among raft nodes before linearized reading' (duration: 254.250536ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:43.052601Z","caller":"traceutil/trace.go:171","msg":"trace[2106697357] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"112.79846ms","start":"2026-02-26T21:05:42.939777Z","end":"2026-02-26T21:05:43.052576Z","steps":["trace[2106697357] 'process raft request' (duration: 112.634936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:05:43.052645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.930473ms","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-26T21:05:43.052698Z","caller":"traceutil/trace.go:171","msg":"trace[553200388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2494; }","duration":"104.005475ms","start":"2026-02-26T21:05:42.948683Z","end":"2026-02-26T21:05:43.052688Z","steps":["trace[553200388] 'agreement among raft nodes before linearized reading' (duration: 103.853912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:23.240373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.864201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:09:23.240515Z","caller":"traceutil/trace.go:171","msg":"trace[1180516122] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:3859; }","duration":"130.077846ms","start":"2026-02-26T21:09:23.110402Z","end":"2026-02-26T21:09:23.24048Z","steps":["trace[1180516122] 'count revisions from in-memory index tree' (duration: 129.717547ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:27.821044Z","caller":"traceutil/trace.go:171","msg":"trace[457225564] transaction","detail":"{read_only:false; response_revision:4141; number_of_response:1; }","duration":"101.658969ms","start":"2026-02-26T21:09:27.719352Z","end":"2026-02-26T21:09:27.821011Z","steps":["trace[457225564] 'process raft request' (duration: 101.542176ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:27.821123Z","caller":"traceutil/trace.go:171","msg":"trace[1075319215] linearizableReadLoop","detail":"{readStateIndex:4306; appliedIndex:4300; }","duration":"102.501641ms","start":"2026-02-26T21:09:27.718598Z","end":"2026-02-26T21:09:27.8211Z","steps":["trace[1075319215] 'read index received' (duration: 25.873012ms)","trace[1075319215] 'applied index is now lower than readState.Index' (duration: 76.627639ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:27.821167Z","caller":"traceutil/trace.go:171","msg":"trace[366520409] transaction","detail":"{read_only:false; response_revision:4140; number_of_response:1; }","duration":"102.629515ms","start":"2026-02-26T21:09:27.718497Z","end":"2026-02-26T21:09:27.821127Z","steps":["trace[366520409] 'process raft request' (duration: 34.234835ms)","trace[366520409] 'compare' (duration: 67.943668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:09:27.821285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.663245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:09:27.821323Z","caller":"traceutil/trace.go:171","msg":"trace[44039259] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-tls; range_end:; response_count:0; response_revision:4145; }","duration":"102.739976ms","start":"2026-02-26T21:09:27.718572Z","end":"2026-02-26T21:09:27.821312Z","steps":["trace[44039259] 'agreement among raft nodes before linearized reading' (duration: 102.642284ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:30.075991Z","caller":"traceutil/trace.go:171","msg":"trace[429936481] transaction","detail":"{read_only:false; response_revision:4224; number_of_response:1; }","duration":"220.13186ms","start":"2026-02-26T21:09:29.855829Z","end":"2026-02-26T21:09:30.075961Z","steps":["trace[429936481] 'process raft request' (duration: 218.913168ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:36.565996Z","caller":"traceutil/trace.go:171","msg":"trace[1933257036] linearizableReadLoop","detail":"{readStateIndex:4587; appliedIndex:4586; }","duration":"143.061187ms","start":"2026-02-26T21:09:36.422917Z","end":"2026-02-26T21:09:36.565978Z","steps":["trace[1933257036] 'read index received' (duration: 85.064324ms)","trace[1933257036] 'applied index is now lower than readState.Index' (duration: 57.996213ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:36.566126Z","caller":"traceutil/trace.go:171","msg":"trace[287824916] transaction","detail":"{read_only:false; response_revision:4418; number_of_response:1; }","duration":"145.348826ms","start":"2026-02-26T21:09:36.420731Z","end":"2026-02-26T21:09:36.56608Z","steps":["trace[287824916] 'process raft request' (duration: 87.182009ms)","trace[287824916] 'compare' (duration: 57.943321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:09:36.566205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.266693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-drm6g-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:09:36.566277Z","caller":"traceutil/trace.go:171","msg":"trace[307896786] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-drm6g-tls; range_end:; response_count:0; response_revision:4418; }","duration":"143.437817ms","start":"2026-02-26T21:09:36.422823Z","end":"2026-02-26T21:09:36.566261Z","steps":["trace[307896786] 'agreement among raft nodes before linearized reading' (duration: 143.264753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:36.632116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.867497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2194593"} {"level":"info","ts":"2026-02-26T21:09:36.632227Z","caller":"traceutil/trace.go:171","msg":"trace[873961205] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4419; }","duration":"133.018361ms","start":"2026-02-26T21:09:36.499182Z","end":"2026-02-26T21:09:36.632201Z","steps":["trace[873961205] 'agreement among raft nodes before linearized reading' (duration: 131.832771ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:36.815803Z","caller":"traceutil/trace.go:171","msg":"trace[1383108456] linearizableReadLoop","detail":"{readStateIndex:4590; appliedIndex:4589; }","duration":"172.012509ms","start":"2026-02-26T21:09:36.643766Z","end":"2026-02-26T21:09:36.815779Z","steps":["trace[1383108456] 'read index received' (duration: 104.623576ms)","trace[1383108456] 'applied index is now lower than readState.Index' (duration: 67.388163ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:36.815869Z","caller":"traceutil/trace.go:171","msg":"trace[1445063267] transaction","detail":"{read_only:false; response_revision:4420; number_of_response:1; }","duration":"172.531682ms","start":"2026-02-26T21:09:36.643291Z","end":"2026-02-26T21:09:36.815823Z","steps":["trace[1445063267] 'process raft request' (duration: 105.072857ms)","trace[1445063267] 'compare' (duration: 67.289661ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:09:36.816045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.248135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-02-26T21:09:36.816136Z","caller":"traceutil/trace.go:171","msg":"trace[1408116745] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4420; }","duration":"172.335537ms","start":"2026-02-26T21:09:36.643741Z","end":"2026-02-26T21:09:36.816077Z","steps":["trace[1408116745] 'agreement among raft nodes before linearized reading' (duration: 172.165042ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:36.825531Z","caller":"traceutil/trace.go:171","msg":"trace[1216128890] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4420; }","duration":"180.873595ms","start":"2026-02-26T21:09:36.644642Z","end":"2026-02-26T21:09:36.825515Z","steps":["trace[1216128890] 'process raft request' (duration: 180.788253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:36.825797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.972318ms","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-26T21:09:36.825841Z","caller":"traceutil/trace.go:171","msg":"trace[2104213365] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4420; }","duration":"163.050309ms","start":"2026-02-26T21:09:36.662781Z","end":"2026-02-26T21:09:36.825832Z","steps":["trace[2104213365] 'agreement among raft nodes before linearized reading' (duration: 162.917366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:38.1473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.076389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:09:38.14745Z","caller":"traceutil/trace.go:171","msg":"trace[558603184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4467; }","duration":"170.252784ms","start":"2026-02-26T21:09:37.97715Z","end":"2026-02-26T21:09:38.147403Z","steps":["trace[558603184] 'agreement among raft nodes before linearized reading' (duration: 73.824698ms)","trace[558603184] 'range keys from in-memory index tree' (duration: 96.244892ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:38.147473Z","caller":"traceutil/trace.go:171","msg":"trace[819324860] transaction","detail":"{read_only:false; response_revision:4468; number_of_response:1; }","duration":"132.024937ms","start":"2026-02-26T21:09:38.015413Z","end":"2026-02-26T21:09:38.147438Z","steps":["trace[819324860] 'process raft request' (duration: 108.67203ms)","trace[819324860] 'compare' (duration: 23.039949ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:09:38.147684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.470284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:09:38.147723Z","caller":"traceutil/trace.go:171","msg":"trace[935001597] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4468; }","duration":"162.531886ms","start":"2026-02-26T21:09:37.985178Z","end":"2026-02-26T21:09:38.14771Z","steps":["trace[935001597] 'agreement among raft nodes before linearized reading' (duration: 162.369122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:39.920666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.468587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:718777"} {"level":"info","ts":"2026-02-26T21:09:39.920751Z","caller":"traceutil/trace.go:171","msg":"trace[1440976425] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4545; }","duration":"175.60263ms","start":"2026-02-26T21:09:39.745132Z","end":"2026-02-26T21:09:39.920734Z","steps":["trace[1440976425] 'range keys from in-memory index tree' (duration: 174.888132ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:40.093987Z","caller":"traceutil/trace.go:171","msg":"trace[1824865356] linearizableReadLoop","detail":"{readStateIndex:4721; appliedIndex:4720; }","duration":"169.471473ms","start":"2026-02-26T21:09:39.924497Z","end":"2026-02-26T21:09:40.093969Z","steps":["trace[1824865356] 'read index received' (duration: 168.328355ms)","trace[1824865356] 'applied index is now lower than readState.Index' (duration: 1.142158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:09:40.094159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.653089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T21:09:40.094145Z","caller":"traceutil/trace.go:171","msg":"trace[1127158766] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"171.180978ms","start":"2026-02-26T21:09:39.922926Z","end":"2026-02-26T21:09:40.094107Z","steps":["trace[1127158766] 'process raft request' (duration: 169.904125ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:40.094197Z","caller":"traceutil/trace.go:171","msg":"trace[1539502796] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4547; }","duration":"169.731211ms","start":"2026-02-26T21:09:39.924457Z","end":"2026-02-26T21:09:40.094188Z","steps":["trace[1539502796] 'agreement among raft nodes before linearized reading' (duration: 169.616088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:40.094159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.565004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:09:40.094495Z","caller":"traceutil/trace.go:171","msg":"trace[679385186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4547; }","duration":"115.915594ms","start":"2026-02-26T21:09:39.978528Z","end":"2026-02-26T21:09:40.094443Z","steps":["trace[679385186] 'agreement among raft nodes before linearized reading' (duration: 115.586936ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:41.411222Z","caller":"traceutil/trace.go:171","msg":"trace[544622334] linearizableReadLoop","detail":"{readStateIndex:4751; appliedIndex:4750; }","duration":"137.176017ms","start":"2026-02-26T21:09:41.274017Z","end":"2026-02-26T21:09:41.411193Z","steps":["trace[544622334] 'read index received' (duration: 120.353177ms)","trace[544622334] 'applied index is now lower than readState.Index' (duration: 16.82107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:09:41.411475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.450005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92912b92-2d72-4a39-9f9b-1d237c52da81\" ","response":"range_response_count:1 size:3890"} {"level":"info","ts":"2026-02-26T21:09:41.411513Z","caller":"traceutil/trace.go:171","msg":"trace[21473709] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-92912b92-2d72-4a39-9f9b-1d237c52da81; range_end:; response_count:1; response_revision:4575; }","duration":"137.540767ms","start":"2026-02-26T21:09:41.27396Z","end":"2026-02-26T21:09:41.411501Z","steps":["trace[21473709] 'agreement among raft nodes before linearized reading' (duration: 137.381993ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:43.4695Z","caller":"traceutil/trace.go:171","msg":"trace[1473328168] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"126.364481ms","start":"2026-02-26T21:09:43.343108Z","end":"2026-02-26T21:09:43.469473Z","steps":["trace[1473328168] 'process raft request' (duration: 123.684302ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:43.469525Z","caller":"traceutil/trace.go:171","msg":"trace[371136396] linearizableReadLoop","detail":"{readStateIndex:4803; appliedIndex:4801; }","duration":"117.246688ms","start":"2026-02-26T21:09:43.352248Z","end":"2026-02-26T21:09:43.469495Z","steps":["trace[371136396] 'read index received' (duration: 3.387947ms)","trace[371136396] 'applied index is now lower than readState.Index' (duration: 113.856131ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:09:43.470092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.812943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413082"} {"level":"info","ts":"2026-02-26T21:09:43.470171Z","caller":"traceutil/trace.go:171","msg":"trace[195751654] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:4626; }","duration":"117.929465ms","start":"2026-02-26T21:09:43.352224Z","end":"2026-02-26T21:09:43.470153Z","steps":["trace[195751654] 'agreement among raft nodes before linearized reading' (duration: 117.651697ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:46.062098Z","caller":"traceutil/trace.go:171","msg":"trace[1564036507] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"104.327587ms","start":"2026-02-26T21:09:45.957724Z","end":"2026-02-26T21:09:46.062052Z","steps":["trace[1564036507] 'process raft request' (duration: 39.805658ms)","trace[1564036507] 'compare' (duration: 64.282283ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:47.808449Z","caller":"traceutil/trace.go:171","msg":"trace[1155673434] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"129.339417ms","start":"2026-02-26T21:09:47.679083Z","end":"2026-02-26T21:09:47.808422Z","steps":["trace[1155673434] 'process raft request' (duration: 129.148351ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:58.532727Z","caller":"traceutil/trace.go:171","msg":"trace[469551302] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5061; }","duration":"249.740905ms","start":"2026-02-26T21:09:58.28296Z","end":"2026-02-26T21:09:58.532701Z","steps":["trace[469551302] 'read index received' (duration: 249.455567ms)","trace[469551302] 'applied index is now lower than readState.Index' (duration: 284.248µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:58.532822Z","caller":"traceutil/trace.go:171","msg":"trace[813052252] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"250.571696ms","start":"2026-02-26T21:09:58.28221Z","end":"2026-02-26T21:09:58.532782Z","steps":["trace[813052252] 'process raft request' (duration: 250.200216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:58.532965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.9936ms","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-26T21:09:58.533005Z","caller":"traceutil/trace.go:171","msg":"trace[1446968100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4879; }","duration":"250.063893ms","start":"2026-02-26T21:09:58.282929Z","end":"2026-02-26T21:09:58.532993Z","steps":["trace[1446968100] 'agreement among raft nodes before linearized reading' (duration: 249.911799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:58.533033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.42325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-26T21:09:58.533061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.332439ms","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-26T21:09:58.533101Z","caller":"traceutil/trace.go:171","msg":"trace[967723675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4879; }","duration":"151.37294ms","start":"2026-02-26T21:09:58.381714Z","end":"2026-02-26T21:09:58.533087Z","steps":["trace[967723675] 'agreement among raft nodes before linearized reading' (duration: 151.263427ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:58.5331Z","caller":"traceutil/trace.go:171","msg":"trace[1573209633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4879; }","duration":"158.550033ms","start":"2026-02-26T21:09:58.374534Z","end":"2026-02-26T21:09:58.533084Z","steps":["trace[1573209633] 'agreement among raft nodes before linearized reading' (duration: 158.331028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:58.533171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.31339ms","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-26T21:09:58.533235Z","caller":"traceutil/trace.go:171","msg":"trace[1490229098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4879; }","duration":"151.410921ms","start":"2026-02-26T21:09:58.381809Z","end":"2026-02-26T21:09:58.53322Z","steps":["trace[1490229098] 'agreement among raft nodes before linearized reading' (duration: 151.270347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:58.533243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.358875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T21:09:58.533276Z","caller":"traceutil/trace.go:171","msg":"trace[296813253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4879; }","duration":"152.419677ms","start":"2026-02-26T21:09:58.380847Z","end":"2026-02-26T21:09:58.533267Z","steps":["trace[296813253] 'agreement among raft nodes before linearized reading' (duration: 152.335565ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:58.704611Z","caller":"traceutil/trace.go:171","msg":"trace[648288898] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"168.022805ms","start":"2026-02-26T21:09:58.536554Z","end":"2026-02-26T21:09:58.704577Z","steps":["trace[648288898] 'process raft request' (duration: 97.656167ms)","trace[648288898] 'compare' (duration: 69.942308ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:58.704642Z","caller":"traceutil/trace.go:171","msg":"trace[1695953764] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"167.43388ms","start":"2026-02-26T21:09:58.537181Z","end":"2026-02-26T21:09:58.704615Z","steps":["trace[1695953764] 'process raft request' (duration: 167.371509ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:58.704667Z","caller":"traceutil/trace.go:171","msg":"trace[591693131] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"168.010675ms","start":"2026-02-26T21:09:58.536621Z","end":"2026-02-26T21:09:58.704631Z","steps":["trace[591693131] 'process raft request' (duration: 167.77958ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:58.704785Z","caller":"traceutil/trace.go:171","msg":"trace[172292378] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"168.114738ms","start":"2026-02-26T21:09:58.536655Z","end":"2026-02-26T21:09:58.70477Z","steps":["trace[172292378] 'process raft request' (duration: 167.84362ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:00.213382Z","caller":"traceutil/trace.go:171","msg":"trace[1626234093] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"190.608703ms","start":"2026-02-26T21:10:00.022728Z","end":"2026-02-26T21:10:00.213336Z","steps":["trace[1626234093] 'process raft request' (duration: 190.209503ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.614185Z","caller":"traceutil/trace.go:171","msg":"trace[1281477066] linearizableReadLoop","detail":"{readStateIndex:5102; appliedIndex:5101; }","duration":"215.875899ms","start":"2026-02-26T21:10:02.398278Z","end":"2026-02-26T21:10:02.614154Z","steps":["trace[1281477066] 'read index received' (duration: 215.648373ms)","trace[1281477066] 'applied index is now lower than readState.Index' (duration: 226.126µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:10:02.614229Z","caller":"traceutil/trace.go:171","msg":"trace[1213198107] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"216.525955ms","start":"2026-02-26T21:10:02.397668Z","end":"2026-02-26T21:10:02.614194Z","steps":["trace[1213198107] 'process raft request' (duration: 216.289828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:02.614516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.968644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-02-26T21:10:02.614587Z","caller":"traceutil/trace.go:171","msg":"trace[403582073] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4918; }","duration":"134.097477ms","start":"2026-02-26T21:10:02.480474Z","end":"2026-02-26T21:10:02.614571Z","steps":["trace[403582073] 'agreement among raft nodes before linearized reading' (duration: 133.874762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:02.614624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.3015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-02-26T21:10:02.614747Z","caller":"traceutil/trace.go:171","msg":"trace[722754470] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4918; }","duration":"216.482085ms","start":"2026-02-26T21:10:02.398238Z","end":"2026-02-26T21:10:02.61472Z","steps":["trace[722754470] 'agreement among raft nodes before linearized reading' (duration: 216.081104ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.72809Z","caller":"traceutil/trace.go:171","msg":"trace[1276946455] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"108.533524ms","start":"2026-02-26T21:10:02.619529Z","end":"2026-02-26T21:10:02.728062Z","steps":["trace[1276946455] 'process raft request' (duration: 99.254408ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.741537Z","caller":"traceutil/trace.go:171","msg":"trace[1872963292] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"121.758133ms","start":"2026-02-26T21:10:02.619744Z","end":"2026-02-26T21:10:02.741502Z","steps":["trace[1872963292] 'process raft request' (duration: 121.485865ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.74185Z","caller":"traceutil/trace.go:171","msg":"trace[910094603] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"121.904807ms","start":"2026-02-26T21:10:02.619927Z","end":"2026-02-26T21:10:02.741832Z","steps":["trace[910094603] 'process raft request' (duration: 121.495887ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.742064Z","caller":"traceutil/trace.go:171","msg":"trace[1398935296] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"120.879719ms","start":"2026-02-26T21:10:02.621171Z","end":"2026-02-26T21:10:02.742051Z","steps":["trace[1398935296] 'process raft request' (duration: 120.572902ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.742229Z","caller":"traceutil/trace.go:171","msg":"trace[2146402625] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"121.031704ms","start":"2026-02-26T21:10:02.621189Z","end":"2026-02-26T21:10:02.742221Z","steps":["trace[2146402625] 'process raft request' (duration: 120.764507ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.742312Z","caller":"traceutil/trace.go:171","msg":"trace[1070997170] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"120.289946ms","start":"2026-02-26T21:10:02.621987Z","end":"2026-02-26T21:10:02.742277Z","steps":["trace[1070997170] 'process raft request' (duration: 120.144782ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.742457Z","caller":"traceutil/trace.go:171","msg":"trace[1988661778] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5102; }","duration":"103.371482ms","start":"2026-02-26T21:10:02.639064Z","end":"2026-02-26T21:10:02.742436Z","steps":["trace[1988661778] 'read index received' (duration: 79.734468ms)","trace[1988661778] 'applied index is now lower than readState.Index' (duration: 23.635644ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:10:02.742802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.707631ms","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-26T21:10:02.742916Z","caller":"traceutil/trace.go:171","msg":"trace[323695191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4925; }","duration":"103.874656ms","start":"2026-02-26T21:10:02.639013Z","end":"2026-02-26T21:10:02.742887Z","steps":["trace[323695191] 'agreement among raft nodes before linearized reading' (duration: 103.543697ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.743011Z","caller":"traceutil/trace.go:171","msg":"trace[1980372705] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"117.459173ms","start":"2026-02-26T21:10:02.625515Z","end":"2026-02-26T21:10:02.742974Z","steps":["trace[1980372705] 'process raft request' (duration: 116.691254ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:15.122149Z","caller":"traceutil/trace.go:171","msg":"trace[642779258] linearizableReadLoop","detail":"{readStateIndex:5275; appliedIndex:5274; }","duration":"279.113856ms","start":"2026-02-26T21:10:14.843014Z","end":"2026-02-26T21:10:15.122128Z","steps":["trace[642779258] 'read index received' (duration: 278.924511ms)","trace[642779258] 'applied index is now lower than readState.Index' (duration: 188.385µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:10:15.122207Z","caller":"traceutil/trace.go:171","msg":"trace[1638576851] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"314.371577ms","start":"2026-02-26T21:10:14.807802Z","end":"2026-02-26T21:10:15.122173Z","steps":["trace[1638576851] 'process raft request' (duration: 314.194022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:15.122496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.476334ms","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-26T21:10:15.122586Z","caller":"traceutil/trace.go:171","msg":"trace[1545015498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5085; }","duration":"279.643488ms","start":"2026-02-26T21:10:14.842923Z","end":"2026-02-26T21:10:15.122566Z","steps":["trace[1545015498] 'agreement among raft nodes before linearized reading' (duration: 279.372032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:15.122556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.305687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:10:15.122533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.230662ms","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-26T21:10:15.122627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.868984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T21:10:15.122661Z","caller":"traceutil/trace.go:171","msg":"trace[2003432793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5085; }","duration":"278.362866ms","start":"2026-02-26T21:10:14.844289Z","end":"2026-02-26T21:10:15.122652Z","steps":["trace[2003432793] 'agreement among raft nodes before linearized reading' (duration: 278.16415ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:15.122645Z","caller":"traceutil/trace.go:171","msg":"trace[1130957748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5085; }","duration":"142.452082ms","start":"2026-02-26T21:10:14.980183Z","end":"2026-02-26T21:10:15.122636Z","steps":["trace[1130957748] 'agreement among raft nodes before linearized reading' (duration: 142.306537ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:15.12269Z","caller":"traceutil/trace.go:171","msg":"trace[1731000838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5085; }","duration":"277.978377ms","start":"2026-02-26T21:10:14.844697Z","end":"2026-02-26T21:10:15.122676Z","steps":["trace[1731000838] 'agreement among raft nodes before linearized reading' (duration: 277.813022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:15.122799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.1476ms","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-26T21:10:15.1229Z","caller":"traceutil/trace.go:171","msg":"trace[1836654062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5085; }","duration":"278.277355ms","start":"2026-02-26T21:10:14.844608Z","end":"2026-02-26T21:10:15.122885Z","steps":["trace[1836654062] 'agreement among raft nodes before linearized reading' (duration: 277.929805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:15.123204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:10:14.807774Z","time spent":"314.665784ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:10:15.123312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.865205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T21:10:15.123366Z","caller":"traceutil/trace.go:171","msg":"trace[392007650] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5085; }","duration":"189.955507ms","start":"2026-02-26T21:10:14.933398Z","end":"2026-02-26T21:10:15.123353Z","steps":["trace[392007650] 'agreement among raft nodes before linearized reading' (duration: 189.079604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:15.48598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.370053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:10:15.486069Z","caller":"traceutil/trace.go:171","msg":"trace[1929767441] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"359.237363ms","start":"2026-02-26T21:10:15.126816Z","end":"2026-02-26T21:10:15.486053Z","steps":["trace[1929767441] 'process raft request' (duration: 48.717135ms)","trace[1929767441] 'compare' (duration: 310.275021ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:10:15.486119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:10:15.126797Z","time spent":"359.298055ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:10:15.858821Z","caller":"traceutil/trace.go:171","msg":"trace[107936887] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"731.88514ms","start":"2026-02-26T21:10:15.126916Z","end":"2026-02-26T21:10:15.858801Z","steps":["trace[107936887] 'process raft request' (duration: 731.654054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:15.858971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:10:15.126887Z","time spent":"732.008442ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:10:15.858821Z","caller":"traceutil/trace.go:171","msg":"trace[1697728137] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"731.584453ms","start":"2026-02-26T21:10:15.12721Z","end":"2026-02-26T21:10:15.858795Z","steps":["trace[1697728137] 'process raft request' (duration: 731.500341ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:15.859147Z","caller":"traceutil/trace.go:171","msg":"trace[851736656] linearizableReadLoop","detail":"{readStateIndex:5280; appliedIndex:5276; }","duration":"356.736251ms","start":"2026-02-26T21:10:15.502385Z","end":"2026-02-26T21:10:15.859122Z","steps":["trace[851736656] 'read index received' (duration: 356.087744ms)","trace[851736656] 'applied index is now lower than readState.Index' (duration: 647.007µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:10:15.859239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:10:15.127188Z","time spent":"731.946572ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:10:15.859269Z","caller":"traceutil/trace.go:171","msg":"trace[1428330073] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"731.4594ms","start":"2026-02-26T21:10:15.127791Z","end":"2026-02-26T21:10:15.85925Z","steps":["trace[1428330073] 'process raft request' (duration: 731.215313ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:15.859256Z","caller":"traceutil/trace.go:171","msg":"trace[912627139] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"731.999563ms","start":"2026-02-26T21:10:15.127217Z","end":"2026-02-26T21:10:15.859217Z","steps":["trace[912627139] 'process raft request' (duration: 731.534281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:15.859372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.986266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"warn","ts":"2026-02-26T21:10:15.859416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:10:15.127772Z","time spent":"731.543461ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:10:15.859429Z","caller":"traceutil/trace.go:171","msg":"trace[1086912057] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5090; }","duration":"357.083969ms","start":"2026-02-26T21:10:15.502334Z","end":"2026-02-26T21:10:15.859418Z","steps":["trace[1086912057] 'agreement among raft nodes before linearized reading' (duration: 356.926305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:15.859469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:10:15.502313Z","time spent":"357.14824ms","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10413,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-02-26T21:10:15.859459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:10:15.127202Z","time spent":"732.156507ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:10:15.859512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.254996ms","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-26T21:10:15.859622Z","caller":"traceutil/trace.go:171","msg":"trace[1003279480] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5090; }","duration":"266.374409ms","start":"2026-02-26T21:10:15.593195Z","end":"2026-02-26T21:10:15.85957Z","steps":["trace[1003279480] 'agreement among raft nodes before linearized reading' (duration: 266.151974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:15.859662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.444269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jcvcv\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T21:10:15.859712Z","caller":"traceutil/trace.go:171","msg":"trace[233918264] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jcvcv; range_end:; response_count:1; response_revision:5090; }","duration":"183.520871ms","start":"2026-02-26T21:10:15.676174Z","end":"2026-02-26T21:10:15.859694Z","steps":["trace[233918264] 'agreement among raft nodes before linearized reading' (duration: 183.356717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:50.879809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.837416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:10:50.879936Z","caller":"traceutil/trace.go:171","msg":"trace[1592209516] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"370.121901ms","start":"2026-02-26T21:10:50.509783Z","end":"2026-02-26T21:10:50.879905Z","steps":["trace[1592209516] 'process raft request' (duration: 139.10184ms)","trace[1592209516] 'compare' (duration: 230.60546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:10:50.880001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:10:50.509758Z","time spent":"370.211273ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:11:11.040336Z","caller":"traceutil/trace.go:171","msg":"trace[1086468996] transaction","detail":"{read_only:false; response_revision:5547; number_of_response:1; }","duration":"123.88344ms","start":"2026-02-26T21:11:10.916419Z","end":"2026-02-26T21:11:11.040302Z","steps":["trace[1086468996] 'process raft request' (duration: 123.268664ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:11:38.223696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2767} {"level":"info","ts":"2026-02-26T21:11:38.282819Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2767,"took":"57.695917ms","hash":4258446847,"current-db-size-bytes":40968192,"current-db-size":"41 MB","current-db-size-in-use-bytes":30896128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-02-26T21:11:38.282877Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4258446847,"revision":2767,"compact-revision":-1} {"level":"warn","ts":"2026-02-26T21:15:10.559842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.474168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:15:10.559912Z","caller":"traceutil/trace.go:171","msg":"trace[1573791016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7129; }","duration":"575.57477ms","start":"2026-02-26T21:15:09.984323Z","end":"2026-02-26T21:15:10.559898Z","steps":["trace[1573791016] 'range keys from in-memory index tree' (duration: 575.387566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:10.559938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:09.9843Z","time spent":"575.632402ms","remote":"127.0.0.1:36512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:15:10.560276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.887955ms","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-26T21:15:10.560308Z","caller":"traceutil/trace.go:171","msg":"trace[1708439597] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:7129; }","duration":"146.999648ms","start":"2026-02-26T21:15:10.413301Z","end":"2026-02-26T21:15:10.5603Z","steps":["trace[1708439597] 'count revisions from in-memory index tree' (duration: 146.825703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:10.560633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.96459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:214283"} {"level":"info","ts":"2026-02-26T21:15:10.560662Z","caller":"traceutil/trace.go:171","msg":"trace[1813482494] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7129; }","duration":"574.008081ms","start":"2026-02-26T21:15:09.986648Z","end":"2026-02-26T21:15:10.560656Z","steps":["trace[1813482494] 'range keys from in-memory index tree' (duration: 573.743075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:10.560681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:09.986641Z","time spent":"574.034341ms","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":24,"response size":214305,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-02-26T21:15:10.562495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.345181ms","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-26T21:15:10.562552Z","caller":"traceutil/trace.go:171","msg":"trace[1866536436] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7129; }","duration":"560.411242ms","start":"2026-02-26T21:15:10.002132Z","end":"2026-02-26T21:15:10.562544Z","steps":["trace[1866536436] 'range keys from in-memory index tree' (duration: 560.336931ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:15:11.112163Z","caller":"traceutil/trace.go:171","msg":"trace[1567856325] transaction","detail":"{read_only:false; response_revision:7130; number_of_response:1; }","duration":"471.641927ms","start":"2026-02-26T21:15:10.640492Z","end":"2026-02-26T21:15:11.112134Z","steps":["trace[1567856325] 'process raft request' (duration: 471.469493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:11.112338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.640464Z","time spent":"471.782171ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:15:13.252885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.140937478s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:15:13.253297Z","caller":"traceutil/trace.go:171","msg":"trace[2021644270] linearizableReadLoop","detail":"{readStateIndex:7390; appliedIndex:7388; }","duration":"2.335689249s","start":"2026-02-26T21:15:10.917583Z","end":"2026-02-26T21:15:13.253272Z","steps":["trace[2021644270] 'read index received' (duration: 194.319891ms)","trace[2021644270] 'applied index is now lower than readState.Index' (duration: 2.141367998s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:15:13.25356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.335951877s","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-26T21:15:13.253624Z","caller":"traceutil/trace.go:171","msg":"trace[137528900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7131; }","duration":"2.33607674s","start":"2026-02-26T21:15:10.917533Z","end":"2026-02-26T21:15:13.253609Z","steps":["trace[137528900] 'agreement among raft nodes before linearized reading' (duration: 2.335828253s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.253692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.917511Z","time spent":"2.336161562s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T21:15:13.254249Z","caller":"traceutil/trace.go:171","msg":"trace[1833378480] transaction","detail":"{read_only:false; response_revision:7131; number_of_response:1; }","duration":"2.45548912s","start":"2026-02-26T21:15:10.798727Z","end":"2026-02-26T21:15:13.254216Z","steps":["trace[1833378480] 'process raft request' (duration: 2.454404342s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.254408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.798707Z","time spent":"2.455616294s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:15:13.255574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.276204295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:15:13.255697Z","caller":"traceutil/trace.go:171","msg":"trace[1583152373] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:7133; }","duration":"2.276331368s","start":"2026-02-26T21:15:10.979337Z","end":"2026-02-26T21:15:13.255668Z","steps":["trace[1583152373] 'agreement among raft nodes before linearized reading' (duration: 2.276071921s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.25575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.979319Z","time spent":"2.276413341s","remote":"127.0.0.1:36946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:15:13.256151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69467591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:12713"} {"level":"warn","ts":"2026-02-26T21:15:13.256174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695610215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:15:13.256219Z","caller":"traceutil/trace.go:171","msg":"trace[1473408355] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:7133; }","duration":"1.694775173s","start":"2026-02-26T21:15:11.561425Z","end":"2026-02-26T21:15:13.2562Z","steps":["trace[1473408355] 'agreement among raft nodes before linearized reading' (duration: 1.69462977s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:15:13.256229Z","caller":"traceutil/trace.go:171","msg":"trace[1105361976] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:7133; }","duration":"1.695703198s","start":"2026-02-26T21:15:11.56051Z","end":"2026-02-26T21:15:13.256213Z","steps":["trace[1105361976] 'agreement among raft nodes before linearized reading' (duration: 1.695614975s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.256273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.561413Z","time spent":"1.694848006s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":12735,"request content":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" "} {"level":"warn","ts":"2026-02-26T21:15:13.256277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.560424Z","time spent":"1.695838802s","remote":"127.0.0.1:48300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:15:13.256509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696769125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:15:13.256533Z","caller":"traceutil/trace.go:171","msg":"trace[516659257] transaction","detail":"{read_only:false; response_revision:7132; number_of_response:1; }","duration":"1.842847759s","start":"2026-02-26T21:15:11.413666Z","end":"2026-02-26T21:15:13.256514Z","steps":["trace[516659257] 'process raft request' (duration: 1.841572196s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:15:13.256561Z","caller":"traceutil/trace.go:171","msg":"trace[1252344629] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:7133; }","duration":"1.696863628s","start":"2026-02-26T21:15:11.559683Z","end":"2026-02-26T21:15:13.256547Z","steps":["trace[1252344629] 'agreement among raft nodes before linearized reading' (duration: 1.696786346s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.256613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.55966Z","time spent":"1.69693263s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/barbican-ks-user\" "} {"level":"warn","ts":"2026-02-26T21:15:13.256645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.413642Z","time spent":"1.842930671s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:15:13.256859Z","caller":"traceutil/trace.go:171","msg":"trace[302694676] transaction","detail":"{read_only:false; response_revision:7133; number_of_response:1; }","duration":"1.361285907s","start":"2026-02-26T21:15:11.895557Z","end":"2026-02-26T21:15:13.256843Z","steps":["trace[302694676] 'process raft request' (duration: 1.359788469s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.256937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.895539Z","time spent":"1.361352718s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:15:13.257126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.866276359s","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-26T21:15:13.257177Z","caller":"traceutil/trace.go:171","msg":"trace[96524773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7133; }","duration":"1.866341502s","start":"2026-02-26T21:15:11.39082Z","end":"2026-02-26T21:15:13.257162Z","steps":["trace[96524773] 'agreement among raft nodes before linearized reading' (duration: 1.866143227s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.257228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.390812Z","time spent":"1.866404953s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:15:13.257269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.799693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T21:15:13.257314Z","caller":"traceutil/trace.go:171","msg":"trace[1569639614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7133; }","duration":"139.887155ms","start":"2026-02-26T21:15:13.117414Z","end":"2026-02-26T21:15:13.257301Z","steps":["trace[1569639614] 'agreement among raft nodes before linearized reading' (duration: 139.736951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.257483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.693471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:15:13.257522Z","caller":"traceutil/trace.go:171","msg":"trace[152797970] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:7133; }","duration":"667.770874ms","start":"2026-02-26T21:15:12.589741Z","end":"2026-02-26T21:15:13.257512Z","steps":["trace[152797970] 'agreement among raft nodes before linearized reading' (duration: 667.663461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.257555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:12.589718Z","time spent":"667.827385ms","remote":"127.0.0.1:36774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":54,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:15:13.257574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86708271s","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-26T21:15:13.257632Z","caller":"traceutil/trace.go:171","msg":"trace[1433745039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7133; }","duration":"1.867158652s","start":"2026-02-26T21:15:11.390454Z","end":"2026-02-26T21:15:13.257613Z","steps":["trace[1433745039] 'agreement among raft nodes before linearized reading' (duration: 1.867012369s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.257678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.390443Z","time spent":"1.867224485s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:15:13.257804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.275585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jcvcv\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T21:15:13.257847Z","caller":"traceutil/trace.go:171","msg":"trace[128247746] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jcvcv; range_end:; response_count:1; response_revision:7133; }","duration":"689.344067ms","start":"2026-02-26T21:15:12.568491Z","end":"2026-02-26T21:15:13.257835Z","steps":["trace[128247746] 'agreement among raft nodes before linearized reading' (duration: 689.218004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.257882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:12.568475Z","time spent":"689.396738ms","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-jcvcv\" "} {"level":"warn","ts":"2026-02-26T21:15:13.257956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911609761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T21:15:13.258012Z","caller":"traceutil/trace.go:171","msg":"trace[960760938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7133; }","duration":"1.911692194s","start":"2026-02-26T21:15:11.346306Z","end":"2026-02-26T21:15:13.257998Z","steps":["trace[960760938] 'agreement among raft nodes before linearized reading' (duration: 1.91154237s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.258069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280111306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:15:13.258113Z","caller":"traceutil/trace.go:171","msg":"trace[705866488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7133; }","duration":"1.280153767s","start":"2026-02-26T21:15:11.977948Z","end":"2026-02-26T21:15:13.258102Z","steps":["trace[705866488] 'agreement among raft nodes before linearized reading' (duration: 1.280092376s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.258149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.9779Z","time spent":"1.280241739s","remote":"127.0.0.1:36512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:15:13.258204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.970929532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T21:15:13.258254Z","caller":"traceutil/trace.go:171","msg":"trace[2024364303] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:7133; }","duration":"1.971005483s","start":"2026-02-26T21:15:11.287236Z","end":"2026-02-26T21:15:13.258241Z","steps":["trace[2024364303] 'agreement among raft nodes before linearized reading' (duration: 1.970866039s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.2583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.287219Z","time spent":"1.971070785s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.41\" "} {"level":"warn","ts":"2026-02-26T21:15:13.258338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652444699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:15:13.258381Z","caller":"traceutil/trace.go:171","msg":"trace[814712221] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:7133; }","duration":"1.652515612s","start":"2026-02-26T21:15:11.605855Z","end":"2026-02-26T21:15:13.25837Z","steps":["trace[814712221] 'agreement among raft nodes before linearized reading' (duration: 1.652449399s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.25842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.605842Z","time spent":"1.652568383s","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:15:13.258547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.066687116s","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-26T21:15:13.258604Z","caller":"traceutil/trace.go:171","msg":"trace[784358907] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7133; }","duration":"2.066780198s","start":"2026-02-26T21:15:11.191808Z","end":"2026-02-26T21:15:13.258588Z","steps":["trace[784358907] 'agreement among raft nodes before linearized reading' (duration: 2.066692986s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.258651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.19179Z","time spent":"2.066846559s","remote":"127.0.0.1:38408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:15:13.25868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681411892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T21:15:13.258725Z","caller":"traceutil/trace.go:171","msg":"trace[447844532] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7133; }","duration":"1.681454493s","start":"2026-02-26T21:15:11.577257Z","end":"2026-02-26T21:15:13.258712Z","steps":["trace[447844532] 'agreement among raft nodes before linearized reading' (duration: 1.6813276s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.258765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.577221Z","time spent":"1.681536565s","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-26T21:15:13.256935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.865584951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T21:15:13.258983Z","caller":"traceutil/trace.go:171","msg":"trace[1836584916] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7133; }","duration":"1.867657514s","start":"2026-02-26T21:15:11.391311Z","end":"2026-02-26T21:15:13.258968Z","steps":["trace[1836584916] 'agreement among raft nodes before linearized reading' (duration: 1.865498599s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.259028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.391299Z","time spent":"1.867717015s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T21:15:13.258074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.346292Z","time spent":"1.911762385s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:16:10.197832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.304602ms","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-26T21:16:10.197969Z","caller":"traceutil/trace.go:171","msg":"trace[76470810] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7611; }","duration":"196.456095ms","start":"2026-02-26T21:16:10.001493Z","end":"2026-02-26T21:16:10.197949Z","steps":["trace[76470810] 'range keys from in-memory index tree' (duration: 196.288451ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:11.757303Z","caller":"traceutil/trace.go:171","msg":"trace[1104504915] transaction","detail":"{read_only:false; response_revision:7617; number_of_response:1; }","duration":"112.032641ms","start":"2026-02-26T21:16:11.645233Z","end":"2026-02-26T21:16:11.757266Z","steps":["trace[1104504915] 'process raft request' (duration: 87.348179ms)","trace[1104504915] 'compare' (duration: 24.437986ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:11.757342Z","caller":"traceutil/trace.go:171","msg":"trace[1405835463] transaction","detail":"{read_only:false; response_revision:7618; number_of_response:1; }","duration":"112.457731ms","start":"2026-02-26T21:16:11.644855Z","end":"2026-02-26T21:16:11.757313Z","steps":["trace[1405835463] 'process raft request' (duration: 112.311128ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:11.757436Z","caller":"traceutil/trace.go:171","msg":"trace[676140089] transaction","detail":"{read_only:false; response_revision:7619; number_of_response:1; }","duration":"111.737323ms","start":"2026-02-26T21:16:11.645678Z","end":"2026-02-26T21:16:11.757415Z","steps":["trace[676140089] 'process raft request' (duration: 111.555449ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:11.757593Z","caller":"traceutil/trace.go:171","msg":"trace[1374860545] transaction","detail":"{read_only:false; response_revision:7620; number_of_response:1; }","duration":"111.804996ms","start":"2026-02-26T21:16:11.645775Z","end":"2026-02-26T21:16:11.75758Z","steps":["trace[1374860545] 'process raft request' (duration: 111.514368ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:38.277148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5737} {"level":"info","ts":"2026-02-26T21:16:38.362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5737,"took":"82.29749ms","hash":2762799965,"current-db-size-bytes":42913792,"current-db-size":"43 MB","current-db-size-in-use-bytes":23261184,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-26T21:16:38.362077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2762799965,"revision":5737,"compact-revision":2767} {"level":"warn","ts":"2026-02-26T21:16:40.21447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.244149ms","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-26T21:16:40.214554Z","caller":"traceutil/trace.go:171","msg":"trace[1599973503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7803; }","duration":"290.361132ms","start":"2026-02-26T21:16:39.924173Z","end":"2026-02-26T21:16:40.214534Z","steps":["trace[1599973503] 'range keys from in-memory index tree' (duration: 290.036824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.215032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.084933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T21:16:40.215076Z","caller":"traceutil/trace.go:171","msg":"trace[1173100246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7803; }","duration":"423.165975ms","start":"2026-02-26T21:16:39.7919Z","end":"2026-02-26T21:16:40.215066Z","steps":["trace[1173100246] 'range keys from in-memory index tree' (duration: 422.844117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.215124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:39.791879Z","time spent":"423.236348ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:16:40.215479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.665191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T21:16:40.215519Z","caller":"traceutil/trace.go:171","msg":"trace[616173996] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7803; }","duration":"365.737993ms","start":"2026-02-26T21:16:39.849771Z","end":"2026-02-26T21:16:40.215509Z","steps":["trace[616173996] 'range keys from in-memory index tree' (duration: 365.482247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.215545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:39.84975Z","time spent":"365.789204ms","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-26T21:16:40.216005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.393155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T21:16:40.216143Z","caller":"traceutil/trace.go:171","msg":"trace[721876270] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7803; }","duration":"303.526608ms","start":"2026-02-26T21:16:39.912602Z","end":"2026-02-26T21:16:40.216129Z","steps":["trace[721876270] 'range keys from in-memory index tree' (duration: 303.230741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.216186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:39.912553Z","time spent":"303.62228ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T21:16:40.216379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.4668ms","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-26T21:16:40.216413Z","caller":"traceutil/trace.go:171","msg":"trace[747407588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7803; }","duration":"298.501651ms","start":"2026-02-26T21:16:39.917904Z","end":"2026-02-26T21:16:40.216405Z","steps":["trace[747407588] 'range keys from in-memory index tree' (duration: 298.366828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.216667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.648482ms","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-26T21:16:40.216702Z","caller":"traceutil/trace.go:171","msg":"trace[1455154400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7803; }","duration":"294.724374ms","start":"2026-02-26T21:16:39.92197Z","end":"2026-02-26T21:16:40.216694Z","steps":["trace[1455154400] 'range keys from in-memory index tree' (duration: 294.464907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.217302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.02767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:16:40.217368Z","caller":"traceutil/trace.go:171","msg":"trace[1064716112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7803; }","duration":"238.123073ms","start":"2026-02-26T21:16:39.979231Z","end":"2026-02-26T21:16:40.217355Z","steps":["trace[1064716112] 'range keys from in-memory index tree' (duration: 237.905108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.21735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.514913ms","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-26T21:16:40.21743Z","caller":"traceutil/trace.go:171","msg":"trace[1891588542] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7803; }","duration":"215.599035ms","start":"2026-02-26T21:16:40.00182Z","end":"2026-02-26T21:16:40.217419Z","steps":["trace[1891588542] 'range keys from in-memory index tree' (duration: 215.504663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.217607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.13479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T21:16:40.217645Z","caller":"traceutil/trace.go:171","msg":"trace[1250778568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7803; }","duration":"289.205832ms","start":"2026-02-26T21:16:39.92843Z","end":"2026-02-26T21:16:40.217636Z","steps":["trace[1250778568] 'range keys from in-memory index tree' (duration: 289.019348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.218024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.683018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jcvcv\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T21:16:40.218121Z","caller":"traceutil/trace.go:171","msg":"trace[1849958453] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jcvcv; range_end:; response_count:1; response_revision:7803; }","duration":"286.813501ms","start":"2026-02-26T21:16:39.931285Z","end":"2026-02-26T21:16:40.218098Z","steps":["trace[1849958453] 'range keys from in-memory index tree' (duration: 286.526494ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:40.386369Z","caller":"traceutil/trace.go:171","msg":"trace[1781297792] transaction","detail":"{read_only:false; response_revision:7804; number_of_response:1; }","duration":"286.287067ms","start":"2026-02-26T21:16:40.100053Z","end":"2026-02-26T21:16:40.38634Z","steps":["trace[1781297792] 'process raft request' (duration: 286.155324ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:40.386846Z","caller":"traceutil/trace.go:171","msg":"trace[158533658] linearizableReadLoop","detail":"{readStateIndex:8087; appliedIndex:8087; }","duration":"160.887253ms","start":"2026-02-26T21:16:40.225945Z","end":"2026-02-26T21:16:40.386832Z","steps":["trace[158533658] 'read index received' (duration: 160.882963ms)","trace[158533658] 'applied index is now lower than readState.Index' (duration: 3.52µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:16:40.38694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.980885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:16:40.387028Z","caller":"traceutil/trace.go:171","msg":"trace[1233314634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7804; }","duration":"161.096358ms","start":"2026-02-26T21:16:40.225919Z","end":"2026-02-26T21:16:40.387016Z","steps":["trace[1233314634] 'agreement among raft nodes before linearized reading' (duration: 160.981616ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:40.47725Z","caller":"traceutil/trace.go:171","msg":"trace[1648165223] transaction","detail":"{read_only:false; response_revision:7805; number_of_response:1; }","duration":"250.95388ms","start":"2026-02-26T21:16:40.22627Z","end":"2026-02-26T21:16:40.477224Z","steps":["trace[1648165223] 'process raft request' (duration: 250.641303ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:40.477253Z","caller":"traceutil/trace.go:171","msg":"trace[1568413023] transaction","detail":"{read_only:false; response_revision:7807; number_of_response:1; }","duration":"248.94829ms","start":"2026-02-26T21:16:40.228285Z","end":"2026-02-26T21:16:40.477233Z","steps":["trace[1568413023] 'process raft request' (duration: 248.903249ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:40.477246Z","caller":"traceutil/trace.go:171","msg":"trace[107322617] transaction","detail":"{read_only:false; response_revision:7806; number_of_response:1; }","duration":"250.839848ms","start":"2026-02-26T21:16:40.226382Z","end":"2026-02-26T21:16:40.477222Z","steps":["trace[107322617] 'process raft request' (duration: 250.777377ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:40.47752Z","caller":"traceutil/trace.go:171","msg":"trace[1633249319] transaction","detail":"{read_only:false; response_revision:7808; number_of_response:1; }","duration":"249.175785ms","start":"2026-02-26T21:16:40.228333Z","end":"2026-02-26T21:16:40.477509Z","steps":["trace[1633249319] 'process raft request' (duration: 248.878377ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:40.477636Z","caller":"traceutil/trace.go:171","msg":"trace[72101004] transaction","detail":"{read_only:false; response_revision:7809; number_of_response:1; }","duration":"249.280738ms","start":"2026-02-26T21:16:40.228345Z","end":"2026-02-26T21:16:40.477626Z","steps":["trace[72101004] 'process raft request' (duration: 249.078752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.477781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.508205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T21:16:40.477821Z","caller":"traceutil/trace.go:171","msg":"trace[225704728] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7810; }","duration":"251.599618ms","start":"2026-02-26T21:16:40.226213Z","end":"2026-02-26T21:16:40.477812Z","steps":["trace[225704728] 'agreement among raft nodes before linearized reading' (duration: 251.433214ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:40.477789Z","caller":"traceutil/trace.go:171","msg":"trace[1483955684] transaction","detail":"{read_only:false; response_revision:7810; number_of_response:1; }","duration":"249.408642ms","start":"2026-02-26T21:16:40.228371Z","end":"2026-02-26T21:16:40.47778Z","steps":["trace[1483955684] 'process raft request' (duration: 249.205077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:40.478164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.015211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228492"} {"level":"info","ts":"2026-02-26T21:16:40.478226Z","caller":"traceutil/trace.go:171","msg":"trace[2065872522] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:7810; }","duration":"249.109074ms","start":"2026-02-26T21:16:40.229104Z","end":"2026-02-26T21:16:40.478213Z","steps":["trace[2065872522] 'agreement among raft nodes before linearized reading' (duration: 248.708234ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:42.659745Z","caller":"traceutil/trace.go:171","msg":"trace[931348053] transaction","detail":"{read_only:false; response_revision:7816; number_of_response:1; }","duration":"173.130348ms","start":"2026-02-26T21:16:42.486586Z","end":"2026-02-26T21:16:42.659716Z","steps":["trace[931348053] 'process raft request' (duration: 136.291943ms)","trace[931348053] 'compare' (duration: 36.601238ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:42.659973Z","caller":"traceutil/trace.go:171","msg":"trace[855191435] transaction","detail":"{read_only:false; response_revision:7817; number_of_response:1; }","duration":"173.366664ms","start":"2026-02-26T21:16:42.486593Z","end":"2026-02-26T21:16:42.65996Z","steps":["trace[855191435] 'process raft request' (duration: 173.067346ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:42.660022Z","caller":"traceutil/trace.go:171","msg":"trace[1569496422] transaction","detail":"{read_only:false; response_revision:7818; number_of_response:1; }","duration":"172.719987ms","start":"2026-02-26T21:16:42.487292Z","end":"2026-02-26T21:16:42.660012Z","steps":["trace[1569496422] 'process raft request' (duration: 172.592874ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:42.660086Z","caller":"traceutil/trace.go:171","msg":"trace[2040902754] transaction","detail":"{read_only:false; response_revision:7819; number_of_response:1; }","duration":"172.204583ms","start":"2026-02-26T21:16:42.487874Z","end":"2026-02-26T21:16:42.660078Z","steps":["trace[2040902754] 'process raft request' (duration: 172.089361ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:42.660081Z","caller":"traceutil/trace.go:171","msg":"trace[2145096317] linearizableReadLoop","detail":"{readStateIndex:8104; appliedIndex:8098; }","duration":"168.874298ms","start":"2026-02-26T21:16:42.491186Z","end":"2026-02-26T21:16:42.66006Z","steps":["trace[2145096317] 'read index received' (duration: 59.353002ms)","trace[2145096317] 'applied index is now lower than readState.Index' (duration: 109.520016ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:42.660136Z","caller":"traceutil/trace.go:171","msg":"trace[1563126999] transaction","detail":"{read_only:false; response_revision:7820; number_of_response:1; }","duration":"171.558537ms","start":"2026-02-26T21:16:42.488546Z","end":"2026-02-26T21:16:42.660105Z","steps":["trace[1563126999] 'process raft request' (duration: 171.464825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:42.660286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.079203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jcvcv\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T21:16:42.660344Z","caller":"traceutil/trace.go:171","msg":"trace[2003048996] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jcvcv; range_end:; response_count:1; response_revision:7820; }","duration":"169.158634ms","start":"2026-02-26T21:16:42.491168Z","end":"2026-02-26T21:16:42.660326Z","steps":["trace[2003048996] 'agreement among raft nodes before linearized reading' (duration: 168.96261ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:52.797152Z","caller":"traceutil/trace.go:171","msg":"trace[2097822079] linearizableReadLoop","detail":"{readStateIndex:8194; appliedIndex:8192; }","duration":"193.179421ms","start":"2026-02-26T21:16:52.603949Z","end":"2026-02-26T21:16:52.797129Z","steps":["trace[2097822079] 'read index received' (duration: 26.205701ms)","trace[2097822079] 'applied index is now lower than readState.Index' (duration: 166.97284ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:52.797237Z","caller":"traceutil/trace.go:171","msg":"trace[270532779] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7904; }","duration":"196.908246ms","start":"2026-02-26T21:16:52.600302Z","end":"2026-02-26T21:16:52.79721Z","steps":["trace[270532779] 'process raft request' (duration: 196.745692ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:52.79723Z","caller":"traceutil/trace.go:171","msg":"trace[1802136509] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7903; }","duration":"197.154153ms","start":"2026-02-26T21:16:52.600035Z","end":"2026-02-26T21:16:52.797189Z","steps":["trace[1802136509] 'process raft request' (duration: 196.892856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:52.797753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.769785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T21:16:52.797894Z","caller":"traceutil/trace.go:171","msg":"trace[306730248] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7904; }","duration":"193.906309ms","start":"2026-02-26T21:16:52.603921Z","end":"2026-02-26T21:16:52.797827Z","steps":["trace[306730248] 'agreement among raft nodes before linearized reading' (duration: 193.355015ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:52.924433Z","caller":"traceutil/trace.go:171","msg":"trace[1901768433] transaction","detail":"{read_only:false; response_revision:7906; number_of_response:1; }","duration":"120.033846ms","start":"2026-02-26T21:16:52.804373Z","end":"2026-02-26T21:16:52.924406Z","steps":["trace[1901768433] 'process raft request' (duration: 102.875217ms)","trace[1901768433] 'compare' (duration: 16.990655ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:52.925326Z","caller":"traceutil/trace.go:171","msg":"trace[1287574544] transaction","detail":"{read_only:false; response_revision:7907; number_of_response:1; }","duration":"120.751975ms","start":"2026-02-26T21:16:52.804537Z","end":"2026-02-26T21:16:52.925289Z","steps":["trace[1287574544] 'process raft request' (duration: 119.825791ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:52.925557Z","caller":"traceutil/trace.go:171","msg":"trace[984101236] transaction","detail":"{read_only:false; response_revision:7908; number_of_response:1; }","duration":"120.97528ms","start":"2026-02-26T21:16:52.804563Z","end":"2026-02-26T21:16:52.925538Z","steps":["trace[984101236] 'process raft request' (duration: 120.606741ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:52.925814Z","caller":"traceutil/trace.go:171","msg":"trace[101211113] transaction","detail":"{read_only:false; response_revision:7909; number_of_response:1; }","duration":"121.229227ms","start":"2026-02-26T21:16:52.804578Z","end":"2026-02-26T21:16:52.925807Z","steps":["trace[101211113] 'process raft request' (duration: 120.892759ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:52.925947Z","caller":"traceutil/trace.go:171","msg":"trace[1265606238] linearizableReadLoop","detail":"{readStateIndex:8201; appliedIndex:8194; }","duration":"118.976859ms","start":"2026-02-26T21:16:52.806961Z","end":"2026-02-26T21:16:52.925938Z","steps":["trace[1265606238] 'read index received' (duration: 64.762619ms)","trace[1265606238] 'applied index is now lower than readState.Index' (duration: 54.21349ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:52.926013Z","caller":"traceutil/trace.go:171","msg":"trace[1789981867] transaction","detail":"{read_only:false; response_revision:7910; number_of_response:1; }","duration":"121.274228ms","start":"2026-02-26T21:16:52.804734Z","end":"2026-02-26T21:16:52.926008Z","steps":["trace[1789981867] 'process raft request' (duration: 121.040641ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:52.92618Z","caller":"traceutil/trace.go:171","msg":"trace[1097060436] transaction","detail":"{read_only:false; response_revision:7911; number_of_response:1; }","duration":"121.021462ms","start":"2026-02-26T21:16:52.805151Z","end":"2026-02-26T21:16:52.926173Z","steps":["trace[1097060436] 'process raft request' (duration: 120.754435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:52.927156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.170749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228427"} {"level":"info","ts":"2026-02-26T21:16:52.92745Z","caller":"traceutil/trace.go:171","msg":"trace[1488996103] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:7911; }","duration":"120.481017ms","start":"2026-02-26T21:16:52.806947Z","end":"2026-02-26T21:16:52.927428Z","steps":["trace[1488996103] 'agreement among raft nodes before linearized reading' (duration: 119.293356ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:53.690423Z","caller":"traceutil/trace.go:171","msg":"trace[408415916] transaction","detail":"{read_only:false; response_revision:7914; number_of_response:1; }","duration":"106.186752ms","start":"2026-02-26T21:16:53.584195Z","end":"2026-02-26T21:16:53.690382Z","steps":["trace[408415916] 'process raft request' (duration: 105.898114ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:53.863172Z","caller":"traceutil/trace.go:171","msg":"trace[1063069417] linearizableReadLoop","detail":"{readStateIndex:8205; appliedIndex:8203; }","duration":"274.187086ms","start":"2026-02-26T21:16:53.588958Z","end":"2026-02-26T21:16:53.863145Z","steps":["trace[1063069417] 'read index received' (duration: 101.112021ms)","trace[1063069417] 'applied index is now lower than readState.Index' (duration: 173.074015ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:53.863199Z","caller":"traceutil/trace.go:171","msg":"trace[1702404432] transaction","detail":"{read_only:false; response_revision:7915; number_of_response:1; }","duration":"274.403993ms","start":"2026-02-26T21:16:53.588759Z","end":"2026-02-26T21:16:53.863163Z","steps":["trace[1702404432] 'process raft request' (duration: 274.185737ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:53.863295Z","caller":"traceutil/trace.go:171","msg":"trace[1716860948] transaction","detail":"{read_only:false; response_revision:7916; number_of_response:1; }","duration":"265.273298ms","start":"2026-02-26T21:16:53.597972Z","end":"2026-02-26T21:16:53.863245Z","steps":["trace[1716860948] 'process raft request' (duration: 265.110954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:53.863419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.446093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-plj6g\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-02-26T21:16:53.863469Z","caller":"traceutil/trace.go:171","msg":"trace[1942485076] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-plj6g; range_end:; response_count:1; response_revision:7916; }","duration":"274.554206ms","start":"2026-02-26T21:16:53.588905Z","end":"2026-02-26T21:16:53.863459Z","steps":["trace[1942485076] 'agreement among raft nodes before linearized reading' (duration: 274.361391ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:53.874184Z","caller":"traceutil/trace.go:171","msg":"trace[1634576409] transaction","detail":"{read_only:false; response_revision:7917; number_of_response:1; }","duration":"101.141582ms","start":"2026-02-26T21:16:53.773017Z","end":"2026-02-26T21:16:53.874158Z","steps":["trace[1634576409] 'process raft request' (duration: 100.727151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:53.874219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.729449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-02-26T21:16:53.874287Z","caller":"traceutil/trace.go:171","msg":"trace[440496463] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:7918; }","duration":"176.851121ms","start":"2026-02-26T21:16:53.697416Z","end":"2026-02-26T21:16:53.874267Z","steps":["trace[440496463] 'agreement among raft nodes before linearized reading' (duration: 176.584135ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:55.082323Z","caller":"traceutil/trace.go:171","msg":"trace[1845714108] linearizableReadLoop","detail":"{readStateIndex:8216; appliedIndex:8211; }","duration":"146.256469ms","start":"2026-02-26T21:16:54.936045Z","end":"2026-02-26T21:16:55.082302Z","steps":["trace[1845714108] 'read index received' (duration: 39.788171ms)","trace[1845714108] 'applied index is now lower than readState.Index' (duration: 106.467398ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:55.08244Z","caller":"traceutil/trace.go:171","msg":"trace[864754069] transaction","detail":"{read_only:false; response_revision:7925; number_of_response:1; }","duration":"146.551816ms","start":"2026-02-26T21:16:54.935877Z","end":"2026-02-26T21:16:55.082429Z","steps":["trace[864754069] 'process raft request' (duration: 146.329651ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:55.082454Z","caller":"traceutil/trace.go:171","msg":"trace[1253227587] transaction","detail":"{read_only:false; response_revision:7924; number_of_response:1; }","duration":"146.698921ms","start":"2026-02-26T21:16:54.935722Z","end":"2026-02-26T21:16:55.082421Z","steps":["trace[1253227587] 'process raft request' (duration: 146.441165ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:55.082617Z","caller":"traceutil/trace.go:171","msg":"trace[2016115779] transaction","detail":"{read_only:false; response_revision:7926; number_of_response:1; }","duration":"146.74676ms","start":"2026-02-26T21:16:54.935861Z","end":"2026-02-26T21:16:55.082607Z","steps":["trace[2016115779] 'process raft request' (duration: 146.378531ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:55.082729Z","caller":"traceutil/trace.go:171","msg":"trace[298075932] transaction","detail":"{read_only:false; response_revision:7927; number_of_response:1; }","duration":"146.33231ms","start":"2026-02-26T21:16:54.936389Z","end":"2026-02-26T21:16:55.082722Z","steps":["trace[298075932] 'process raft request' (duration: 145.877189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:55.082948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.897035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jcvcv\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T21:16:55.082988Z","caller":"traceutil/trace.go:171","msg":"trace[631515098] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jcvcv; range_end:; response_count:1; response_revision:7927; }","duration":"146.974087ms","start":"2026-02-26T21:16:54.936004Z","end":"2026-02-26T21:16:55.082978Z","steps":["trace[631515098] 'agreement among raft nodes before linearized reading' (duration: 146.842094ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:55.083041Z","caller":"traceutil/trace.go:171","msg":"trace[788423879] transaction","detail":"{read_only:false; response_revision:7923; number_of_response:1; }","duration":"147.314177ms","start":"2026-02-26T21:16:54.935711Z","end":"2026-02-26T21:16:55.083025Z","steps":["trace[788423879] 'process raft request' (duration: 73.192697ms)","trace[788423879] 'compare' (duration: 73.075972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:16:55.083148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.868207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:16:55.083182Z","caller":"traceutil/trace.go:171","msg":"trace[1249684378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7927; }","duration":"104.941639ms","start":"2026-02-26T21:16:54.978232Z","end":"2026-02-26T21:16:55.083174Z","steps":["trace[1249684378] 'agreement among raft nodes before linearized reading' (duration: 104.891068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:18:06.559149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.143606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:18:06.559292Z","caller":"traceutil/trace.go:171","msg":"trace[242373475] linearizableReadLoop","detail":"{readStateIndex:8915; appliedIndex:8914; }","duration":"145.393577ms","start":"2026-02-26T21:18:06.413881Z","end":"2026-02-26T21:18:06.559275Z","steps":["trace[242373475] 'read index received' (duration: 45.311µs)","trace[242373475] 'applied index is now lower than readState.Index' (duration: 145.346976ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:18:06.559351Z","caller":"traceutil/trace.go:171","msg":"trace[998143189] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"400.233398ms","start":"2026-02-26T21:18:06.159084Z","end":"2026-02-26T21:18:06.559317Z","steps":["trace[998143189] 'process raft request' (duration: 157.848296ms)","trace[998143189] 'compare' (duration: 241.997421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:18:06.559395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.507719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:18:06.559524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:18:06.159065Z","time spent":"400.368801ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:18:06.559552Z","caller":"traceutil/trace.go:171","msg":"trace[68294395] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8606; }","duration":"145.699834ms","start":"2026-02-26T21:18:06.413839Z","end":"2026-02-26T21:18:06.559539Z","steps":["trace[68294395] 'agreement among raft nodes before linearized reading' (duration: 145.51961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:18:06.848427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.735539ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-26T21:18:06.848868Z","caller":"traceutil/trace.go:171","msg":"trace[505081346] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"282.815028ms","start":"2026-02-26T21:18:06.56602Z","end":"2026-02-26T21:18:06.848835Z","steps":["trace[505081346] 'process raft request' (duration: 282.52783ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:20:33.105122Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"17dd22a2661aae79","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T21:20:33.109503Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-26T21:20:33.109591Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-26T21:21:25.402933Z","caller":"traceutil/trace.go:171","msg":"trace[1700835287] transaction","detail":"{read_only:false; response_revision:10197; number_of_response:1; }","duration":"115.397452ms","start":"2026-02-26T21:21:25.287506Z","end":"2026-02-26T21:21:25.402903Z","steps":["trace[1700835287] 'process raft request' (duration: 35.613114ms)","trace[1700835287] 'compare' (duration: 79.665015ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:21:30.861029Z","caller":"traceutil/trace.go:171","msg":"trace[1821367805] linearizableReadLoop","detail":"{readStateIndex:10598; appliedIndex:10597; }","duration":"311.027121ms","start":"2026-02-26T21:21:30.549972Z","end":"2026-02-26T21:21:30.860999Z","steps":["trace[1821367805] 'read index received' (duration: 310.836477ms)","trace[1821367805] 'applied index is now lower than readState.Index' (duration: 189.554µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:21:30.861071Z","caller":"traceutil/trace.go:171","msg":"trace[1189975780] transaction","detail":"{read_only:false; response_revision:10239; number_of_response:1; }","duration":"311.190605ms","start":"2026-02-26T21:21:30.549846Z","end":"2026-02-26T21:21:30.861037Z","steps":["trace[1189975780] 'process raft request' (duration: 310.96387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.861265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.271868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-02-26T21:21:30.861349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:21:30.549825Z","time spent":"311.3118ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:21:30.861362Z","caller":"traceutil/trace.go:171","msg":"trace[13742441] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10239; }","duration":"311.420492ms","start":"2026-02-26T21:21:30.549924Z","end":"2026-02-26T21:21:30.861344Z","steps":["trace[13742441] 'agreement among raft nodes before linearized reading' (duration: 311.205477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.861462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:21:30.549904Z","time spent":"311.495774ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T21:21:30.86391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.901639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:21:30.864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.473785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-02-26T21:21:30.864034Z","caller":"traceutil/trace.go:171","msg":"trace[737379788] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:10240; }","duration":"200.519017ms","start":"2026-02-26T21:21:30.663503Z","end":"2026-02-26T21:21:30.864022Z","steps":["trace[737379788] 'agreement among raft nodes before linearized reading' (duration: 200.457755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.864051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.703736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-02-26T21:21:30.864037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.07618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:21:30.864104Z","caller":"traceutil/trace.go:171","msg":"trace[497634032] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10240; }","duration":"194.779528ms","start":"2026-02-26T21:21:30.669311Z","end":"2026-02-26T21:21:30.86409Z","steps":["trace[497634032] 'agreement among raft nodes before linearized reading' (duration: 194.598484ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:30.864147Z","caller":"traceutil/trace.go:171","msg":"trace[540280601] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:10240; }","duration":"199.217003ms","start":"2026-02-26T21:21:30.664904Z","end":"2026-02-26T21:21:30.864121Z","steps":["trace[540280601] 'agreement among raft nodes before linearized reading' (duration: 199.027618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.864201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.995114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:21:30.864204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.223003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:21:30.864223Z","caller":"traceutil/trace.go:171","msg":"trace[1096869109] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:10240; }","duration":"225.058996ms","start":"2026-02-26T21:21:30.63916Z","end":"2026-02-26T21:21:30.864219Z","steps":["trace[1096869109] 'agreement among raft nodes before linearized reading' (duration: 225.023535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.864051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.711046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:21:30.86421Z","caller":"traceutil/trace.go:171","msg":"trace[1222434243] transaction","detail":"{read_only:false; response_revision:10240; number_of_response:1; }","duration":"313.983858ms","start":"2026-02-26T21:21:30.550206Z","end":"2026-02-26T21:21:30.86419Z","steps":["trace[1222434243] 'process raft request' (duration: 313.448694ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:30.864249Z","caller":"traceutil/trace.go:171","msg":"trace[1491603224] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:10240; }","duration":"199.281245ms","start":"2026-02-26T21:21:30.664955Z","end":"2026-02-26T21:21:30.864236Z","steps":["trace[1491603224] 'agreement among raft nodes before linearized reading' (duration: 199.216403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.864258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.340981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:21:30.864271Z","caller":"traceutil/trace.go:171","msg":"trace[1610185142] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:10240; }","duration":"199.927531ms","start":"2026-02-26T21:21:30.664321Z","end":"2026-02-26T21:21:30.864248Z","steps":["trace[1610185142] 'agreement among raft nodes before linearized reading' (duration: 199.678835ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:30.864309Z","caller":"traceutil/trace.go:171","msg":"trace[1104801737] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:10240; }","duration":"223.435733ms","start":"2026-02-26T21:21:30.640857Z","end":"2026-02-26T21:21:30.864293Z","steps":["trace[1104801737] 'agreement among raft nodes before linearized reading' (duration: 223.374712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.864391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.597489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-26T21:21:30.864377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:21:30.550187Z","time spent":"314.110601ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:21:30.864425Z","caller":"traceutil/trace.go:171","msg":"trace[1321584620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10240; }","duration":"310.641701ms","start":"2026-02-26T21:21:30.553776Z","end":"2026-02-26T21:21:30.864418Z","steps":["trace[1321584620] 'agreement among raft nodes before linearized reading' (duration: 310.510578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.864388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.127026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:21:30.864451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:21:30.55377Z","time spent":"310.673642ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:21:30.864195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.32379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:21:30.864515Z","caller":"traceutil/trace.go:171","msg":"trace[482823135] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:10240; }","duration":"223.28637ms","start":"2026-02-26T21:21:30.641196Z","end":"2026-02-26T21:21:30.864483Z","steps":["trace[482823135] 'agreement among raft nodes before linearized reading' (duration: 223.040204ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:30.863989Z","caller":"traceutil/trace.go:171","msg":"trace[877523352] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:10240; }","duration":"200.028973ms","start":"2026-02-26T21:21:30.663943Z","end":"2026-02-26T21:21:30.863972Z","steps":["trace[877523352] 'agreement among raft nodes before linearized reading' (duration: 199.91826ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:30.864535Z","caller":"traceutil/trace.go:171","msg":"trace[1468326861] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:10240; }","duration":"223.726681ms","start":"2026-02-26T21:21:30.640797Z","end":"2026-02-26T21:21:30.864523Z","steps":["trace[1468326861] 'agreement among raft nodes before linearized reading' (duration: 223.338691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.863926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.023838ms","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":"warn","ts":"2026-02-26T21:21:30.864637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.294175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-02-26T21:21:30.864836Z","caller":"traceutil/trace.go:171","msg":"trace[2027270385] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10240; }","duration":"222.547211ms","start":"2026-02-26T21:21:30.642272Z","end":"2026-02-26T21:21:30.864819Z","steps":["trace[2027270385] 'agreement among raft nodes before linearized reading' (duration: 222.13126ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:30.864709Z","caller":"traceutil/trace.go:171","msg":"trace[1262813600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10240; }","duration":"312.837608ms","start":"2026-02-26T21:21:30.55186Z","end":"2026-02-26T21:21:30.864698Z","steps":["trace[1262813600] 'agreement among raft nodes before linearized reading' (duration: 311.933414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.864944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:21:30.551847Z","time spent":"313.085575ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T21:21:38.283762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7800} {"level":"info","ts":"2026-02-26T21:21:38.345137Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7800,"took":"59.617661ms","hash":1083893693,"current-db-size-bytes":42913792,"current-db-size":"43 MB","current-db-size-in-use-bytes":27570176,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T21:21:38.345215Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1083893693,"revision":7800,"compact-revision":5737} {"level":"info","ts":"2026-02-26T21:26:38.290983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10283} {"level":"info","ts":"2026-02-26T21:26:38.375839Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10283,"took":"82.718299ms","hash":3473860838,"current-db-size-bytes":42913792,"current-db-size":"43 MB","current-db-size-in-use-bytes":24444928,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-26T21:26:38.375929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3473860838,"revision":10283,"compact-revision":7800} {"level":"info","ts":"2026-02-26T21:27:52.475872Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000330d.wal"} {"level":"info","ts":"2026-02-26T21:27:57.849223Z","caller":"traceutil/trace.go:171","msg":"trace[1501071523] transaction","detail":"{read_only:false; response_revision:12654; number_of_response:1; }","duration":"122.509299ms","start":"2026-02-26T21:27:57.72669Z","end":"2026-02-26T21:27:57.849199Z","steps":["trace[1501071523] 'process raft request' (duration: 122.378356ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:57.849229Z","caller":"traceutil/trace.go:171","msg":"trace[345379384] transaction","detail":"{read_only:false; response_revision:12653; number_of_response:1; }","duration":"126.05604ms","start":"2026-02-26T21:27:57.723132Z","end":"2026-02-26T21:27:57.849188Z","steps":["trace[345379384] 'process raft request' (duration: 119.544684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.082377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.870388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:27:59.082478Z","caller":"traceutil/trace.go:171","msg":"trace[690588149] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:12661; }","duration":"249.027702ms","start":"2026-02-26T21:27:58.833427Z","end":"2026-02-26T21:27:59.082454Z","steps":["trace[690588149] 'range keys from in-memory index tree' (duration: 248.749175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.082386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.653165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:27:59.082608Z","caller":"traceutil/trace.go:171","msg":"trace[549017835] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:12661; }","duration":"245.887731ms","start":"2026-02-26T21:27:58.836683Z","end":"2026-02-26T21:27:59.082571Z","steps":["trace[549017835] 'count revisions from in-memory index tree' (duration: 245.536773ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:59.082629Z","caller":"traceutil/trace.go:171","msg":"trace[2117350936] linearizableReadLoop","detail":"{readStateIndex:13114; appliedIndex:13113; }","duration":"216.881788ms","start":"2026-02-26T21:27:58.865724Z","end":"2026-02-26T21:27:59.082606Z","steps":["trace[2117350936] 'read index received' (duration: 216.466548ms)","trace[2117350936] 'applied index is now lower than readState.Index' (duration: 414.25µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:27:59.082691Z","caller":"traceutil/trace.go:171","msg":"trace[1451701100] transaction","detail":"{read_only:false; response_revision:12662; number_of_response:1; }","duration":"227.643263ms","start":"2026-02-26T21:27:58.855015Z","end":"2026-02-26T21:27:59.082658Z","steps":["trace[1451701100] 'process raft request' (duration: 227.427458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.082913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.490135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:27:59.082963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.218977ms","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-26T21:27:59.082994Z","caller":"traceutil/trace.go:171","msg":"trace[535586191] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:12662; }","duration":"146.622368ms","start":"2026-02-26T21:27:58.93635Z","end":"2026-02-26T21:27:59.082972Z","steps":["trace[535586191] 'agreement among raft nodes before linearized reading' (duration: 146.497234ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:59.08301Z","caller":"traceutil/trace.go:171","msg":"trace[1582232053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12662; }","duration":"217.309179ms","start":"2026-02-26T21:27:58.865683Z","end":"2026-02-26T21:27:59.082992Z","steps":["trace[1582232053] 'agreement among raft nodes before linearized reading' (duration: 217.113123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.08303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.403503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T21:27:59.08316Z","caller":"traceutil/trace.go:171","msg":"trace[1052427595] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12662; }","duration":"212.573978ms","start":"2026-02-26T21:27:58.870567Z","end":"2026-02-26T21:27:59.083141Z","steps":["trace[1052427595] 'agreement among raft nodes before linearized reading' (duration: 212.265999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.08303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.767495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T21:27:59.083348Z","caller":"traceutil/trace.go:171","msg":"trace[1236289162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12662; }","duration":"208.121663ms","start":"2026-02-26T21:27:58.875215Z","end":"2026-02-26T21:27:59.083337Z","steps":["trace[1236289162] 'agreement among raft nodes before linearized reading' (duration: 207.667141ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:00.920178Z","caller":"traceutil/trace.go:171","msg":"trace[1392426112] linearizableReadLoop","detail":"{readStateIndex:13123; appliedIndex:13122; }","duration":"356.528416ms","start":"2026-02-26T21:28:00.563621Z","end":"2026-02-26T21:28:00.920149Z","steps":["trace[1392426112] 'read index received' (duration: 356.258698ms)","trace[1392426112] 'applied index is now lower than readState.Index' (duration: 268.508µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:28:00.92027Z","caller":"traceutil/trace.go:171","msg":"trace[1721686690] transaction","detail":"{read_only:false; response_revision:12671; number_of_response:1; }","duration":"378.483929ms","start":"2026-02-26T21:28:00.541767Z","end":"2026-02-26T21:28:00.920251Z","steps":["trace[1721686690] 'process raft request' (duration: 378.199382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:00.92038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:00.541751Z","time spent":"378.555691ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:28:00.920448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.815063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T21:28:00.920501Z","caller":"traceutil/trace.go:171","msg":"trace[1062744972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12671; }","duration":"356.918976ms","start":"2026-02-26T21:28:00.563567Z","end":"2026-02-26T21:28:00.920486Z","steps":["trace[1062744972] 'agreement among raft nodes before linearized reading' (duration: 356.69534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:00.920871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:00.563549Z","time spent":"357.028499ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T21:28:01.034743Z","caller":"traceutil/trace.go:171","msg":"trace[1867473806] transaction","detail":"{read_only:false; response_revision:12672; number_of_response:1; }","duration":"109.134136ms","start":"2026-02-26T21:28:00.925578Z","end":"2026-02-26T21:28:01.034713Z","steps":["trace[1867473806] 'process raft request' (duration: 86.820275ms)","trace[1867473806] 'compare' (duration: 22.163877ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:28:03.491919Z","caller":"traceutil/trace.go:171","msg":"trace[2132555831] transaction","detail":"{read_only:false; response_revision:12685; number_of_response:1; }","duration":"382.917142ms","start":"2026-02-26T21:28:03.108974Z","end":"2026-02-26T21:28:03.491891Z","steps":["trace[2132555831] 'process raft request' (duration: 380.89037ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:03.491921Z","caller":"traceutil/trace.go:171","msg":"trace[158436048] linearizableReadLoop","detail":"{readStateIndex:13138; appliedIndex:13137; }","duration":"382.404018ms","start":"2026-02-26T21:28:03.109485Z","end":"2026-02-26T21:28:03.491889Z","steps":["trace[158436048] 'read index received' (duration: 380.389306ms)","trace[158436048] 'applied index is now lower than readState.Index' (duration: 2.012632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:28:03.492102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:03.108931Z","time spent":"383.053035ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:28:03.492139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.636745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:28:03.492196Z","caller":"traceutil/trace.go:171","msg":"trace[1688190176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12685; }","duration":"382.732447ms","start":"2026-02-26T21:28:03.109449Z","end":"2026-02-26T21:28:03.492181Z","steps":["trace[1688190176] 'agreement among raft nodes before linearized reading' (duration: 382.527741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:03.492233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:03.109437Z","time spent":"382.787149ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:28:03.496852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.050441ms","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-26T21:28:03.496979Z","caller":"traceutil/trace.go:171","msg":"trace[895475239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12686; }","duration":"386.187285ms","start":"2026-02-26T21:28:03.110755Z","end":"2026-02-26T21:28:03.496942Z","steps":["trace[895475239] 'agreement among raft nodes before linearized reading' (duration: 385.927749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:03.49703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:03.11075Z","time spent":"386.270118ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:28:03.497043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.297179ms","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-26T21:28:03.497097Z","caller":"traceutil/trace.go:171","msg":"trace[603002723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12686; }","duration":"356.376412ms","start":"2026-02-26T21:28:03.140703Z","end":"2026-02-26T21:28:03.497079Z","steps":["trace[603002723] 'agreement among raft nodes before linearized reading' (duration: 356.232497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:03.497097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.582701ms","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-26T21:28:03.497143Z","caller":"traceutil/trace.go:171","msg":"trace[962438029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12686; }","duration":"378.672223ms","start":"2026-02-26T21:28:03.118462Z","end":"2026-02-26T21:28:03.497134Z","steps":["trace[962438029] 'agreement among raft nodes before linearized reading' (duration: 378.54468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:03.49714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:03.140685Z","time spent":"356.447273ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T21:28:03.497163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:03.118438Z","time spent":"378.719624ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T21:28:03.496914Z","caller":"traceutil/trace.go:171","msg":"trace[1405607933] transaction","detail":"{read_only:false; response_revision:12686; number_of_response:1; }","duration":"314.528389ms","start":"2026-02-26T21:28:03.182371Z","end":"2026-02-26T21:28:03.496899Z","steps":["trace[1405607933] 'process raft request' (duration: 314.14966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:03.497258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:03.182347Z","time spent":"314.880649ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:28:09.84998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.106086ms","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-26T21:28:09.85005Z","caller":"traceutil/trace.go:171","msg":"trace[418790685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12715; }","duration":"758.217889ms","start":"2026-02-26T21:28:09.091816Z","end":"2026-02-26T21:28:09.850034Z","steps":["trace[418790685] 'range keys from in-memory index tree' (duration: 757.953362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:09.850078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:09.091792Z","time spent":"758.279491ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:28:09.850073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.434106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:28:09.850177Z","caller":"traceutil/trace.go:171","msg":"trace[355033645] linearizableReadLoop","detail":"{readStateIndex:13170; appliedIndex:13169; }","duration":"599.472871ms","start":"2026-02-26T21:28:09.250682Z","end":"2026-02-26T21:28:09.850155Z","steps":["trace[355033645] 'read index received' (duration: 311.737197ms)","trace[355033645] 'applied index is now lower than readState.Index' (duration: 287.734104ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:28:09.850296Z","caller":"traceutil/trace.go:171","msg":"trace[1470929273] transaction","detail":"{read_only:false; response_revision:12716; number_of_response:1; }","duration":"738.281108ms","start":"2026-02-26T21:28:09.111998Z","end":"2026-02-26T21:28:09.850279Z","steps":["trace[1470929273] 'process raft request' (duration: 450.543934ms)","trace[1470929273] 'compare' (duration: 287.17299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:28:09.850463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.293592ms","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-26T21:28:09.850483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.862491ms","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-26T21:28:09.850497Z","caller":"traceutil/trace.go:171","msg":"trace[970766034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12716; }","duration":"223.347243ms","start":"2026-02-26T21:28:09.62714Z","end":"2026-02-26T21:28:09.850487Z","steps":["trace[970766034] 'agreement among raft nodes before linearized reading' (duration: 223.25748ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:09.850517Z","caller":"traceutil/trace.go:171","msg":"trace[1821403717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12716; }","duration":"222.968274ms","start":"2026-02-26T21:28:09.627536Z","end":"2026-02-26T21:28:09.850505Z","steps":["trace[1821403717] 'agreement among raft nodes before linearized reading' (duration: 222.886872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:09.850562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.881202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T21:28:09.850614Z","caller":"traceutil/trace.go:171","msg":"trace[960756625] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12716; }","duration":"599.955383ms","start":"2026-02-26T21:28:09.250641Z","end":"2026-02-26T21:28:09.850597Z","steps":["trace[960756625] 'agreement among raft nodes before linearized reading' (duration: 599.762229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:09.850646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:09.250624Z","time spent":"600.015055ms","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-26T21:28:09.850621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.164224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-26T21:28:09.850629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.79692ms","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-26T21:28:09.850694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.035883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T21:28:09.850725Z","caller":"traceutil/trace.go:171","msg":"trace[1412390520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12716; }","duration":"233.319139ms","start":"2026-02-26T21:28:09.61738Z","end":"2026-02-26T21:28:09.850699Z","steps":["trace[1412390520] 'agreement among raft nodes before linearized reading' (duration: 233.020932ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:09.85075Z","caller":"traceutil/trace.go:171","msg":"trace[1703129857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12716; }","duration":"224.942153ms","start":"2026-02-26T21:28:09.625779Z","end":"2026-02-26T21:28:09.850721Z","steps":["trace[1703129857] 'agreement among raft nodes before linearized reading' (duration: 224.629656ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:09.850775Z","caller":"traceutil/trace.go:171","msg":"trace[272665370] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12716; }","duration":"260.164067ms","start":"2026-02-26T21:28:09.590585Z","end":"2026-02-26T21:28:09.850749Z","steps":["trace[272665370] 'agreement among raft nodes before linearized reading' (duration: 259.752467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:09.850973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:28:09.111968Z","time spent":"738.379381ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:28:10.00436Z","caller":"traceutil/trace.go:171","msg":"trace[933979757] transaction","detail":"{read_only:false; response_revision:12717; number_of_response:1; }","duration":"149.535881ms","start":"2026-02-26T21:28:09.854805Z","end":"2026-02-26T21:28:10.00434Z","steps":["trace[933979757] 'process raft request' (duration: 148.013713ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:10.012077Z","caller":"traceutil/trace.go:171","msg":"trace[126421276] transaction","detail":"{read_only:false; response_revision:12718; number_of_response:1; }","duration":"157.208818ms","start":"2026-02-26T21:28:09.854846Z","end":"2026-02-26T21:28:10.012055Z","steps":["trace[126421276] 'process raft request' (duration: 157.058404ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:10.01236Z","caller":"traceutil/trace.go:171","msg":"trace[1539534016] transaction","detail":"{read_only:false; response_revision:12719; number_of_response:1; }","duration":"157.462215ms","start":"2026-02-26T21:28:09.854876Z","end":"2026-02-26T21:28:10.012338Z","steps":["trace[1539534016] 'process raft request' (duration: 157.138456ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:10.012621Z","caller":"traceutil/trace.go:171","msg":"trace[198236807] transaction","detail":"{read_only:false; response_revision:12721; number_of_response:1; }","duration":"157.643049ms","start":"2026-02-26T21:28:09.854969Z","end":"2026-02-26T21:28:10.012612Z","steps":["trace[198236807] 'process raft request' (duration: 157.478775ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:10.012492Z","caller":"traceutil/trace.go:171","msg":"trace[1683678328] transaction","detail":"{read_only:false; response_revision:12720; number_of_response:1; }","duration":"157.552127ms","start":"2026-02-26T21:28:09.854929Z","end":"2026-02-26T21:28:10.012481Z","steps":["trace[1683678328] 'process raft request' (duration: 157.321831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:10.012857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.433195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19024"} {"level":"info","ts":"2026-02-26T21:28:10.012893Z","caller":"traceutil/trace.go:171","msg":"trace[2066114601] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12722; }","duration":"157.506356ms","start":"2026-02-26T21:28:09.855378Z","end":"2026-02-26T21:28:10.012884Z","steps":["trace[2066114601] 'agreement among raft nodes before linearized reading' (duration: 157.364821ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:10.013047Z","caller":"traceutil/trace.go:171","msg":"trace[232414392] transaction","detail":"{read_only:false; response_revision:12722; number_of_response:1; }","duration":"158.059141ms","start":"2026-02-26T21:28:09.854981Z","end":"2026-02-26T21:28:10.01304Z","steps":["trace[232414392] 'process raft request' (duration: 157.606149ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:10.012728Z","caller":"traceutil/trace.go:171","msg":"trace[1779035909] linearizableReadLoop","detail":"{readStateIndex:13176; appliedIndex:13170; }","duration":"157.3016ms","start":"2026-02-26T21:28:09.855418Z","end":"2026-02-26T21:28:10.01272Z","steps":["trace[1779035909] 'read index received' (duration: 147.404977ms)","trace[1779035909] 'applied index is now lower than readState.Index' (duration: 9.895863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:28:23.130665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.940304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:28:23.130767Z","caller":"traceutil/trace.go:171","msg":"trace[942616989] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:12969; }","duration":"113.110789ms","start":"2026-02-26T21:28:23.017635Z","end":"2026-02-26T21:28:23.130746Z","steps":["trace[942616989] 'count revisions from in-memory index tree' (duration: 112.833121ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:29:04.422161Z","caller":"traceutil/trace.go:171","msg":"trace[352261550] linearizableReadLoop","detail":"{readStateIndex:13962; appliedIndex:13960; }","duration":"125.499537ms","start":"2026-02-26T21:29:04.296635Z","end":"2026-02-26T21:29:04.422135Z","steps":["trace[352261550] 'read index received' (duration: 27.253089ms)","trace[352261550] 'applied index is now lower than readState.Index' (duration: 98.244858ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:29:04.422212Z","caller":"traceutil/trace.go:171","msg":"trace[1133704674] transaction","detail":"{read_only:false; response_revision:13485; number_of_response:1; }","duration":"126.452821ms","start":"2026-02-26T21:29:04.295727Z","end":"2026-02-26T21:29:04.42218Z","steps":["trace[1133704674] 'process raft request' (duration: 88.331174ms)","trace[1133704674] 'compare' (duration: 37.765607ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:29:04.422457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.794664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-02-26T21:29:04.422568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.857868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T21:29:04.422577Z","caller":"traceutil/trace.go:171","msg":"trace[1461097275] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13485; }","duration":"125.945887ms","start":"2026-02-26T21:29:04.296597Z","end":"2026-02-26T21:29:04.422543Z","steps":["trace[1461097275] 'agreement among raft nodes before linearized reading' (duration: 125.66166ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:29:04.422636Z","caller":"traceutil/trace.go:171","msg":"trace[1845374990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13485; }","duration":"122.963171ms","start":"2026-02-26T21:29:04.299653Z","end":"2026-02-26T21:29:04.422616Z","steps":["trace[1845374990] 'agreement among raft nodes before linearized reading' (duration: 122.645893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:29:04.422882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.930899ms","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-26T21:29:04.42293Z","caller":"traceutil/trace.go:171","msg":"trace[429215465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13485; }","duration":"120.9891ms","start":"2026-02-26T21:29:04.30193Z","end":"2026-02-26T21:29:04.422919Z","steps":["trace[429215465] 'agreement among raft nodes before linearized reading' (duration: 120.60196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:29:04.422961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.047613ms","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-26T21:29:04.423083Z","caller":"traceutil/trace.go:171","msg":"trace[2072178664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13485; }","duration":"123.192607ms","start":"2026-02-26T21:29:04.299858Z","end":"2026-02-26T21:29:04.423051Z","steps":["trace[2072178664] 'agreement among raft nodes before linearized reading' (duration: 122.745575ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:29:13.410926Z","caller":"traceutil/trace.go:171","msg":"trace[1424741223] transaction","detail":"{read_only:false; response_revision:13565; number_of_response:1; }","duration":"104.13692ms","start":"2026-02-26T21:29:13.306762Z","end":"2026-02-26T21:29:13.410899Z","steps":["trace[1424741223] 'process raft request' (duration: 103.956735ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:29:48.05261Z","caller":"traceutil/trace.go:171","msg":"trace[24432453] transaction","detail":"{read_only:false; response_revision:13731; number_of_response:1; }","duration":"106.766567ms","start":"2026-02-26T21:29:47.945809Z","end":"2026-02-26T21:29:48.052576Z","steps":["trace[24432453] 'process raft request' (duration: 93.963398ms)","trace[24432453] 'compare' (duration: 12.107041ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:29:48.527094Z","caller":"traceutil/trace.go:171","msg":"trace[1803561843] transaction","detail":"{read_only:false; response_revision:13732; number_of_response:1; }","duration":"314.219714ms","start":"2026-02-26T21:29:48.21284Z","end":"2026-02-26T21:29:48.52706Z","steps":["trace[1803561843] 'process raft request' (duration: 313.981028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:29:48.527295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:29:48.212808Z","time spent":"314.382448ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:29:48.797377Z","caller":"traceutil/trace.go:171","msg":"trace[68354975] transaction","detail":"{read_only:false; response_revision:13733; number_of_response:1; }","duration":"255.363404ms","start":"2026-02-26T21:29:48.541982Z","end":"2026-02-26T21:29:48.797345Z","steps":["trace[68354975] 'process raft request' (duration: 254.128923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:29:48.797491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.290671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:29:48.797532Z","caller":"traceutil/trace.go:171","msg":"trace[1584966387] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:13733; }","duration":"187.394943ms","start":"2026-02-26T21:29:48.610122Z","end":"2026-02-26T21:29:48.797517Z","steps":["trace[1584966387] 'agreement among raft nodes before linearized reading' (duration: 187.313521ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:29:48.797389Z","caller":"traceutil/trace.go:171","msg":"trace[759709554] linearizableReadLoop","detail":"{readStateIndex:14219; appliedIndex:14218; }","duration":"187.140207ms","start":"2026-02-26T21:29:48.61019Z","end":"2026-02-26T21:29:48.79733Z","steps":["trace[759709554] 'read index received' (duration: 185.947287ms)","trace[759709554] 'applied index is now lower than readState.Index' (duration: 1.19118ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:29:48.797756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.051827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T21:29:48.797807Z","caller":"traceutil/trace.go:171","msg":"trace[926515650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13733; }","duration":"182.165189ms","start":"2026-02-26T21:29:48.615627Z","end":"2026-02-26T21:29:48.797792Z","steps":["trace[926515650] 'agreement among raft nodes before linearized reading' (duration: 181.999305ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:29:49.644099Z","caller":"traceutil/trace.go:171","msg":"trace[1763085556] transaction","detail":"{read_only:false; response_revision:13739; number_of_response:1; }","duration":"113.128551ms","start":"2026-02-26T21:29:49.530944Z","end":"2026-02-26T21:29:49.644073Z","steps":["trace[1763085556] 'process raft request' (duration: 112.942356ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:31:38.298002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12165} {"level":"info","ts":"2026-02-26T21:31:38.375362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12165,"took":"74.54914ms","hash":2143531649,"current-db-size-bytes":42913792,"current-db-size":"43 MB","current-db-size-in-use-bytes":27729920,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T21:31:38.37548Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2143531649,"revision":12165,"compact-revision":10283} {"level":"info","ts":"2026-02-26T21:36:38.305822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14451} {"level":"info","ts":"2026-02-26T21:36:38.400655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14451,"took":"91.31191ms","hash":1951839254,"current-db-size-bytes":42913792,"current-db-size":"43 MB","current-db-size-in-use-bytes":23465984,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-26T21:36:38.400852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1951839254,"revision":14451,"compact-revision":12165}