{"level":"warn","ts":"2026-02-26T06:17:44.709393Z","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-26T06:17:44.709496Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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://162.253.55.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-26T06:17:44.709568Z","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-26T06:17:44.709582Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-26T06:17:44.7096Z","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-26T06:17:44.710239Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-02-26T06:17:44.710315Z","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://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-26T06:17:44.715168Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.622111ms"} {"level":"info","ts":"2026-02-26T06:17:44.721957Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-02-26T06:17:44.722053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T06:17:44.722073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-02-26T06:17:44.722095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T06:17:44.722108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-02-26T06:17:44.722151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-02-26T06:17:44.728193Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T06:17:44.729639Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T06:17:44.731018Z","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-26T06:17:44.734488Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T06:17:44.734735Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T06:17:44.734988Z","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-26T06:17:44.735105Z","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-26T06:17:44.735119Z","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-26T06:17:44.736363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-02-26T06:17:44.737981Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-26T06:17:44.742852Z","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-26T06:17:44.742996Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-26T06:17:44.743041Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-26T06:17:44.74315Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T06:17:44.743192Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T06:17:45.125147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T06:17:45.125208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T06:17:45.125239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-02-26T06:17:45.125253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-02-26T06:17:45.12526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-26T06:17:45.12527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-02-26T06:17:45.125279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-26T06:17:45.12713Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T06:17:45.127141Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T06:17:45.127207Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T06:17:45.127342Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T06:17:45.127358Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T06:17:45.127255Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:45.129023Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:45.129258Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:45.129355Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:45.130873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T06:17:45.130951Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-02-26T06:17:51.195654Z","caller":"traceutil/trace.go:171","msg":"trace[340680197] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"128.365153ms","start":"2026-02-26T06:17:51.067263Z","end":"2026-02-26T06:17:51.195628Z","steps":["trace[340680197] 'process raft request' (duration: 128.194129ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:51.195747Z","caller":"traceutil/trace.go:171","msg":"trace[1137911682] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"111.981473ms","start":"2026-02-26T06:17:51.083733Z","end":"2026-02-26T06:17:51.195714Z","steps":["trace[1137911682] 'process raft request' (duration: 111.80879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:51.195746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.263372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-26T06:17:51.195651Z","caller":"traceutil/trace.go:171","msg":"trace[586035966] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:329; }","duration":"102.170951ms","start":"2026-02-26T06:17:51.093458Z","end":"2026-02-26T06:17:51.195629Z","steps":["trace[586035966] 'read index received' (duration: 57.099648ms)","trace[586035966] 'applied index is now lower than readState.Index' (duration: 45.070133ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:51.195861Z","caller":"traceutil/trace.go:171","msg":"trace[1159147782] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:320; }","duration":"102.454606ms","start":"2026-02-26T06:17:51.093394Z","end":"2026-02-26T06:17:51.195849Z","steps":["trace[1159147782] 'agreement among raft nodes before linearized reading' (duration: 102.291653ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:04.981826Z","caller":"traceutil/trace.go:171","msg":"trace[118824777] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"170.927838ms","start":"2026-02-26T06:18:04.810869Z","end":"2026-02-26T06:18:04.981796Z","steps":["trace[118824777] 'process raft request' (duration: 170.779335ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:22.632635Z","caller":"traceutil/trace.go:171","msg":"trace[960187445] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"378.007298ms","start":"2026-02-26T06:18:22.254602Z","end":"2026-02-26T06:18:22.632609Z","steps":["trace[960187445] 'read index received' (duration: 377.726353ms)","trace[960187445] 'applied index is now lower than readState.Index' (duration: 279.965µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:22.632718Z","caller":"traceutil/trace.go:171","msg":"trace[1653123969] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"472.409143ms","start":"2026-02-26T06:18:22.16027Z","end":"2026-02-26T06:18:22.632679Z","steps":["trace[1653123969] 'process raft request' (duration: 472.138828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:22.632809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.185302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:22.632876Z","caller":"traceutil/trace.go:171","msg":"trace[1045541971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"378.315774ms","start":"2026-02-26T06:18:22.254535Z","end":"2026-02-26T06:18:22.63285Z","steps":["trace[1045541971] 'agreement among raft nodes before linearized reading' (duration: 378.222962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:22.632913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:22.254508Z","time spent":"378.395686ms","remote":"127.0.0.1:35358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:18:22.632996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.479754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T06:18:22.633113Z","caller":"traceutil/trace.go:171","msg":"trace[1398479509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"355.678388ms","start":"2026-02-26T06:18:22.27741Z","end":"2026-02-26T06:18:22.633089Z","steps":["trace[1398479509] 'agreement among raft nodes before linearized reading' (duration: 355.383622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:22.633231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:22.277391Z","time spent":"355.826951ms","remote":"127.0.0.1:35630","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-26T06:18:22.633268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.831205ms","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-26T06:18:22.633362Z","caller":"traceutil/trace.go:171","msg":"trace[1642958395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"312.923917ms","start":"2026-02-26T06:18:22.320419Z","end":"2026-02-26T06:18:22.633343Z","steps":["trace[1642958395] 'agreement among raft nodes before linearized reading' (duration: 312.730284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:22.633406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:22.320342Z","time spent":"313.055529ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T06:18:22.633735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:22.160244Z","time spent":"472.562635ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:23.45431Z","caller":"traceutil/trace.go:171","msg":"trace[76122333] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"369.685478ms","start":"2026-02-26T06:18:23.084597Z","end":"2026-02-26T06:18:23.454283Z","steps":["trace[76122333] 'process raft request' (duration: 369.523014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:23.454477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:23.084574Z","time spent":"369.813091ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:24.281528Z","caller":"traceutil/trace.go:171","msg":"trace[1950857673] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"107.920326ms","start":"2026-02-26T06:18:24.173573Z","end":"2026-02-26T06:18:24.281493Z","steps":["trace[1950857673] 'process raft request' (duration: 107.628451ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:44.086531Z","caller":"traceutil/trace.go:171","msg":"trace[1624769047] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"488.464282ms","start":"2026-02-26T06:18:43.598041Z","end":"2026-02-26T06:18:44.086505Z","steps":["trace[1624769047] 'read index received' (duration: 488.210208ms)","trace[1624769047] 'applied index is now lower than readState.Index' (duration: 252.874µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:44.086622Z","caller":"traceutil/trace.go:171","msg":"trace[1912566691] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"525.29568ms","start":"2026-02-26T06:18:43.561287Z","end":"2026-02-26T06:18:44.086582Z","steps":["trace[1912566691] 'process raft request' (duration: 525.062115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:44.086749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.694406ms","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-26T06:18:44.086793Z","caller":"traceutil/trace.go:171","msg":"trace[681888001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:937; }","duration":"488.796759ms","start":"2026-02-26T06:18:43.597985Z","end":"2026-02-26T06:18:44.086781Z","steps":["trace[681888001] 'agreement among raft nodes before linearized reading' (duration: 488.624615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:44.086809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:43.561246Z","time spent":"525.465723ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:44.08683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:43.597958Z","time spent":"488.862269ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:19:01.556951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.66375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-dn8cr\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-02-26T06:19:01.5571Z","caller":"traceutil/trace.go:171","msg":"trace[98062835] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-dn8cr; range_end:; response_count:1; response_revision:1079; }","duration":"171.841823ms","start":"2026-02-26T06:19:01.385204Z","end":"2026-02-26T06:19:01.557046Z","steps":["trace[98062835] 'range keys from in-memory index tree' (duration: 171.433746ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:08.607873Z","caller":"traceutil/trace.go:171","msg":"trace[1782807290] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"124.826069ms","start":"2026-02-26T06:19:08.48301Z","end":"2026-02-26T06:19:08.607836Z","steps":["trace[1782807290] 'process raft request' (duration: 124.646686ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:08.874898Z","caller":"traceutil/trace.go:171","msg":"trace[163764834] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"114.586592ms","start":"2026-02-26T06:19:08.760287Z","end":"2026-02-26T06:19:08.874874Z","steps":["trace[163764834] 'process raft request' (duration: 114.418939ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:32.670514Z","caller":"traceutil/trace.go:171","msg":"trace[773297375] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"306.929313ms","start":"2026-02-26T06:19:32.363559Z","end":"2026-02-26T06:19:32.670488Z","steps":["trace[773297375] 'process raft request' (duration: 306.76488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:32.670697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:32.363522Z","time spent":"307.082906ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:19:32.826906Z","caller":"traceutil/trace.go:171","msg":"trace[1225403162] linearizableReadLoop","detail":"{readStateIndex:1646; appliedIndex:1644; }","duration":"268.699523ms","start":"2026-02-26T06:19:32.558172Z","end":"2026-02-26T06:19:32.826871Z","steps":["trace[1225403162] 'read index received' (duration: 112.179819ms)","trace[1225403162] 'applied index is now lower than readState.Index' (duration: 156.518454ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:32.827002Z","caller":"traceutil/trace.go:171","msg":"trace[970519512] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"462.690644ms","start":"2026-02-26T06:19:32.364266Z","end":"2026-02-26T06:19:32.826956Z","steps":["trace[970519512] 'process raft request' (duration: 393.759649ms)","trace[970519512] 'compare' (duration: 68.66094ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:32.827202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:32.364245Z","time spent":"462.850166ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:19:32.827219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.01462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-086c8c99-7873-4159-81c2-a100412033f0\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-02-26T06:19:32.827254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.04161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-086c8c99-7873-4159-81c2-a100412033f0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-02-26T06:19:32.827303Z","caller":"traceutil/trace.go:171","msg":"trace[1949530177] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-086c8c99-7873-4159-81c2-a100412033f0; range_end:; response_count:1; response_revision:1588; }","duration":"269.168662ms","start":"2026-02-26T06:19:32.558119Z","end":"2026-02-26T06:19:32.827288Z","steps":["trace[1949530177] 'agreement among raft nodes before linearized reading' (duration: 268.938318ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:32.827302Z","caller":"traceutil/trace.go:171","msg":"trace[1546349993] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-086c8c99-7873-4159-81c2-a100412033f0; range_end:; response_count:1; response_revision:1588; }","duration":"269.147842ms","start":"2026-02-26T06:19:32.558132Z","end":"2026-02-26T06:19:32.82728Z","steps":["trace[1546349993] 'agreement among raft nodes before linearized reading' (duration: 268.882617ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:33.290043Z","caller":"traceutil/trace.go:171","msg":"trace[1594369896] linearizableReadLoop","detail":"{readStateIndex:1648; appliedIndex:1647; }","duration":"368.442656ms","start":"2026-02-26T06:19:32.921553Z","end":"2026-02-26T06:19:33.289996Z","steps":["trace[1594369896] 'read index received' (duration: 366.731113ms)","trace[1594369896] 'applied index is now lower than readState.Index' (duration: 1.710043ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:33.290114Z","caller":"traceutil/trace.go:171","msg":"trace[939992628] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"388.819614ms","start":"2026-02-26T06:19:32.901231Z","end":"2026-02-26T06:19:33.29005Z","steps":["trace[939992628] 'process raft request' (duration: 387.04973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:33.290376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:32.901202Z","time spent":"389.027069ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:19:33.290497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.993706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T06:19:33.290545Z","caller":"traceutil/trace.go:171","msg":"trace[86123351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1590; }","duration":"176.050617ms","start":"2026-02-26T06:19:33.114476Z","end":"2026-02-26T06:19:33.290527Z","steps":["trace[86123351] 'agreement among raft nodes before linearized reading' (duration: 175.956025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:33.290535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.964586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T06:19:33.29065Z","caller":"traceutil/trace.go:171","msg":"trace[635898529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1590; }","duration":"176.109369ms","start":"2026-02-26T06:19:33.114512Z","end":"2026-02-26T06:19:33.290622Z","steps":["trace[635898529] 'agreement among raft nodes before linearized reading' (duration: 175.765682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:33.290389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.804773ms","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":"warn","ts":"2026-02-26T06:19:33.290731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.627436ms","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-26T06:19:33.290771Z","caller":"traceutil/trace.go:171","msg":"trace[1768479391] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1590; }","duration":"179.707196ms","start":"2026-02-26T06:19:33.11105Z","end":"2026-02-26T06:19:33.290757Z","steps":["trace[1768479391] 'agreement among raft nodes before linearized reading' (duration: 179.619625ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:33.290798Z","caller":"traceutil/trace.go:171","msg":"trace[888457635] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1590; }","duration":"369.233331ms","start":"2026-02-26T06:19:32.921516Z","end":"2026-02-26T06:19:33.29075Z","steps":["trace[888457635] 'agreement among raft nodes before linearized reading' (duration: 368.663071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:33.290383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.897418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T06:19:33.290987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:32.921504Z","time spent":"369.380504ms","remote":"127.0.0.1:37382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-26T06:19:33.29096Z","caller":"traceutil/trace.go:171","msg":"trace[959771154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1590; }","duration":"215.5112ms","start":"2026-02-26T06:19:33.075409Z","end":"2026-02-26T06:19:33.29092Z","steps":["trace[959771154] 'agreement among raft nodes before linearized reading' (duration: 214.808876ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:40.730591Z","caller":"traceutil/trace.go:171","msg":"trace[1387247869] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"107.551731ms","start":"2026-02-26T06:19:40.623012Z","end":"2026-02-26T06:19:40.730564Z","steps":["trace[1387247869] 'process raft request' (duration: 107.358547ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:40.739208Z","caller":"traceutil/trace.go:171","msg":"trace[117909071] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"105.695446ms","start":"2026-02-26T06:19:40.633492Z","end":"2026-02-26T06:19:40.739188Z","steps":["trace[117909071] 'process raft request' (duration: 105.564864ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:20:16.24094Z","caller":"traceutil/trace.go:171","msg":"trace[1065181972] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"185.629369ms","start":"2026-02-26T06:20:16.055275Z","end":"2026-02-26T06:20:16.240904Z","steps":["trace[1065181972] 'process raft request' (duration: 170.101324ms)","trace[1065181972] 'compare' (duration: 15.378732ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:20:17.32417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.502412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T06:20:17.324257Z","caller":"traceutil/trace.go:171","msg":"trace[547529897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1861; }","duration":"157.643826ms","start":"2026-02-26T06:20:17.166596Z","end":"2026-02-26T06:20:17.32424Z","steps":["trace[547529897] 'range keys from in-memory index tree' (duration: 157.34314ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:21:07.427858Z","caller":"traceutil/trace.go:171","msg":"trace[1037868723] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"143.047897ms","start":"2026-02-26T06:21:07.284782Z","end":"2026-02-26T06:21:07.42783Z","steps":["trace[1037868723] 'process raft request' (duration: 142.897614ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:21:41.735216Z","caller":"traceutil/trace.go:171","msg":"trace[1052771941] linearizableReadLoop","detail":"{readStateIndex:2413; appliedIndex:2412; }","duration":"106.44685ms","start":"2026-02-26T06:21:41.628733Z","end":"2026-02-26T06:21:41.73518Z","steps":["trace[1052771941] 'read index received' (duration: 106.160065ms)","trace[1052771941] 'applied index is now lower than readState.Index' (duration: 285.385µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:21:41.735282Z","caller":"traceutil/trace.go:171","msg":"trace[1777694735] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"135.885181ms","start":"2026-02-26T06:21:41.599356Z","end":"2026-02-26T06:21:41.735241Z","steps":["trace[1777694735] 'process raft request' (duration: 135.637837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:21:41.735491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.733545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-26T06:21:41.735563Z","caller":"traceutil/trace.go:171","msg":"trace[1894990680] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2326; }","duration":"106.872448ms","start":"2026-02-26T06:21:41.62867Z","end":"2026-02-26T06:21:41.735543Z","steps":["trace[1894990680] 'agreement among raft nodes before linearized reading' (duration: 106.629763ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:23:04.584068Z","caller":"traceutil/trace.go:171","msg":"trace[687103452] linearizableReadLoop","detail":"{readStateIndex:2829; appliedIndex:2826; }","duration":"156.449093ms","start":"2026-02-26T06:23:04.427587Z","end":"2026-02-26T06:23:04.584036Z","steps":["trace[687103452] 'read index received' (duration: 56.279416ms)","trace[687103452] 'applied index is now lower than readState.Index' (duration: 100.168357ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:23:04.584103Z","caller":"traceutil/trace.go:171","msg":"trace[1344747578] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"169.930341ms","start":"2026-02-26T06:23:04.414134Z","end":"2026-02-26T06:23:04.584065Z","steps":["trace[1344747578] 'process raft request' (duration: 169.855379ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:23:04.584103Z","caller":"traceutil/trace.go:171","msg":"trace[2094388044] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"169.952902ms","start":"2026-02-26T06:23:04.414112Z","end":"2026-02-26T06:23:04.584065Z","steps":["trace[2094388044] 'process raft request' (duration: 168.137476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:23:04.584234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.623707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:23:04.584452Z","caller":"traceutil/trace.go:171","msg":"trace[1611377173] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2724; }","duration":"156.877913ms","start":"2026-02-26T06:23:04.427545Z","end":"2026-02-26T06:23:04.584423Z","steps":["trace[1611377173] 'agreement among raft nodes before linearized reading' (duration: 156.625188ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:24:33.065347Z","caller":"traceutil/trace.go:171","msg":"trace[1202221134] linearizableReadLoop","detail":"{readStateIndex:3457; appliedIndex:3456; }","duration":"225.24081ms","start":"2026-02-26T06:24:32.840073Z","end":"2026-02-26T06:24:33.065313Z","steps":["trace[1202221134] 'read index received' (duration: 224.761219ms)","trace[1202221134] 'applied index is now lower than readState.Index' (duration: 478.181µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:24:33.065419Z","caller":"traceutil/trace.go:171","msg":"trace[1065703391] transaction","detail":"{read_only:false; response_revision:3329; number_of_response:1; }","duration":"227.588091ms","start":"2026-02-26T06:24:32.837792Z","end":"2026-02-26T06:24:33.06538Z","steps":["trace[1065703391] 'process raft request' (duration: 227.327616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:24:33.065634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.277375ms","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-26T06:24:33.065672Z","caller":"traceutil/trace.go:171","msg":"trace[1347923432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3329; }","duration":"156.385607ms","start":"2026-02-26T06:24:32.909276Z","end":"2026-02-26T06:24:33.065661Z","steps":["trace[1347923432] 'agreement among raft nodes before linearized reading' (duration: 156.211404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:24:33.066317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.203252ms","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-26T06:24:33.066423Z","caller":"traceutil/trace.go:171","msg":"trace[1146646801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3329; }","duration":"226.355525ms","start":"2026-02-26T06:24:32.840045Z","end":"2026-02-26T06:24:33.066401Z","steps":["trace[1146646801] 'agreement among raft nodes before linearized reading' (duration: 225.439904ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:24:33.293521Z","caller":"traceutil/trace.go:171","msg":"trace[1162777331] linearizableReadLoop","detail":"{readStateIndex:3459; appliedIndex:3457; }","duration":"213.931891ms","start":"2026-02-26T06:24:33.07956Z","end":"2026-02-26T06:24:33.293492Z","steps":["trace[1162777331] 'read index received' (duration: 8.14197ms)","trace[1162777331] 'applied index is now lower than readState.Index' (duration: 205.788271ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:24:33.293707Z","caller":"traceutil/trace.go:171","msg":"trace[782917768] transaction","detail":"{read_only:false; response_revision:3331; number_of_response:1; }","duration":"222.973ms","start":"2026-02-26T06:24:33.070676Z","end":"2026-02-26T06:24:33.293649Z","steps":["trace[782917768] 'process raft request' (duration: 186.34598ms)","trace[782917768] 'compare' (duration: 36.335604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:24:33.293792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.218977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-02-26T06:24:33.293854Z","caller":"traceutil/trace.go:171","msg":"trace[35302415] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3331; }","duration":"214.3359ms","start":"2026-02-26T06:24:33.079494Z","end":"2026-02-26T06:24:33.29383Z","steps":["trace[35302415] 'agreement among raft nodes before linearized reading' (duration: 214.148036ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:24:33.294228Z","caller":"traceutil/trace.go:171","msg":"trace[1839297001] transaction","detail":"{read_only:false; response_revision:3332; number_of_response:1; }","duration":"133.245037ms","start":"2026-02-26T06:24:33.160935Z","end":"2026-02-26T06:24:33.29418Z","steps":["trace[1839297001] 'process raft request' (duration: 132.96471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:24:33.360421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.080444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:24:33.360496Z","caller":"traceutil/trace.go:171","msg":"trace[1571899218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3332; }","duration":"105.202577ms","start":"2026-02-26T06:24:33.255276Z","end":"2026-02-26T06:24:33.360479Z","steps":["trace[1571899218] 'agreement among raft nodes before linearized reading' (duration: 105.087974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:24:33.3605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.392596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T06:24:33.360618Z","caller":"traceutil/trace.go:171","msg":"trace[1738788222] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3332; }","duration":"278.548639ms","start":"2026-02-26T06:24:33.082039Z","end":"2026-02-26T06:24:33.360588Z","steps":["trace[1738788222] 'agreement among raft nodes before linearized reading' (duration: 278.245022ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:24:33.404409Z","caller":"traceutil/trace.go:171","msg":"trace[1224529452] transaction","detail":"{read_only:false; response_revision:3333; number_of_response:1; }","duration":"104.816287ms","start":"2026-02-26T06:24:33.299531Z","end":"2026-02-26T06:24:33.404347Z","steps":["trace[1224529452] 'process raft request' (duration: 104.551331ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:42.675497Z","caller":"traceutil/trace.go:171","msg":"trace[1479451482] transaction","detail":"{read_only:false; response_revision:4059; number_of_response:1; }","duration":"106.031391ms","start":"2026-02-26T06:25:42.569445Z","end":"2026-02-26T06:25:42.675476Z","steps":["trace[1479451482] 'process raft request' (duration: 105.694164ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:45.548932Z","caller":"traceutil/trace.go:171","msg":"trace[1062670873] transaction","detail":"{read_only:false; response_revision:4178; number_of_response:1; }","duration":"136.248446ms","start":"2026-02-26T06:25:45.412644Z","end":"2026-02-26T06:25:45.548893Z","steps":["trace[1062670873] 'process raft request' (duration: 72.630053ms)","trace[1062670873] 'compare' (duration: 63.421609ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:45.548936Z","caller":"traceutil/trace.go:171","msg":"trace[1663727303] linearizableReadLoop","detail":"{readStateIndex:4341; appliedIndex:4340; }","duration":"136.009131ms","start":"2026-02-26T06:25:45.412874Z","end":"2026-02-26T06:25:45.548883Z","steps":["trace[1663727303] 'read index received' (duration: 72.283456ms)","trace[1663727303] 'applied index is now lower than readState.Index' (duration: 63.719515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:25:45.549159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.269657ms","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-26T06:25:45.549216Z","caller":"traceutil/trace.go:171","msg":"trace[552273406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4178; }","duration":"136.366428ms","start":"2026-02-26T06:25:45.412831Z","end":"2026-02-26T06:25:45.549197Z","steps":["trace[552273406] 'agreement among raft nodes before linearized reading' (duration: 136.137634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:45.592681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.359898ms","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-26T06:25:45.592776Z","caller":"traceutil/trace.go:171","msg":"trace[1834939291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4179; }","duration":"168.506791ms","start":"2026-02-26T06:25:45.424243Z","end":"2026-02-26T06:25:45.59275Z","steps":["trace[1834939291] 'agreement among raft nodes before linearized reading' (duration: 168.237805ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.580278Z","caller":"traceutil/trace.go:171","msg":"trace[1179406893] transaction","detail":"{read_only:false; response_revision:4205; number_of_response:1; }","duration":"180.190545ms","start":"2026-02-26T06:25:47.400059Z","end":"2026-02-26T06:25:47.58025Z","steps":["trace[1179406893] 'process raft request' (duration: 180.023071ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.75617Z","caller":"traceutil/trace.go:171","msg":"trace[1545213237] transaction","detail":"{read_only:false; response_revision:4206; number_of_response:1; }","duration":"169.746087ms","start":"2026-02-26T06:25:47.586388Z","end":"2026-02-26T06:25:47.756134Z","steps":["trace[1545213237] 'process raft request' (duration: 77.098423ms)","trace[1545213237] 'compare' (duration: 92.404819ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:47.756259Z","caller":"traceutil/trace.go:171","msg":"trace[1172673210] linearizableReadLoop","detail":"{readStateIndex:4369; appliedIndex:4368; }","duration":"155.91931ms","start":"2026-02-26T06:25:47.600293Z","end":"2026-02-26T06:25:47.756212Z","steps":["trace[1172673210] 'read index received' (duration: 63.243466ms)","trace[1172673210] 'applied index is now lower than readState.Index' (duration: 92.671964ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:47.75636Z","caller":"traceutil/trace.go:171","msg":"trace[17153371] transaction","detail":"{read_only:false; response_revision:4207; number_of_response:1; }","duration":"154.405169ms","start":"2026-02-26T06:25:47.601929Z","end":"2026-02-26T06:25:47.756334Z","steps":["trace[17153371] 'process raft request' (duration: 154.143504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:47.756741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.42301ms","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-26T06:25:47.756801Z","caller":"traceutil/trace.go:171","msg":"trace[1949096631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4207; }","duration":"156.554793ms","start":"2026-02-26T06:25:47.600232Z","end":"2026-02-26T06:25:47.756787Z","steps":["trace[1949096631] 'agreement among raft nodes before linearized reading' (duration: 156.225576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:47.756869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.167204ms","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-26T06:25:47.756967Z","caller":"traceutil/trace.go:171","msg":"trace[1224304691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4207; }","duration":"153.325768ms","start":"2026-02-26T06:25:47.603617Z","end":"2026-02-26T06:25:47.756942Z","steps":["trace[1224304691] 'agreement among raft nodes before linearized reading' (duration: 153.029892ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.930706Z","caller":"traceutil/trace.go:171","msg":"trace[844358716] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4212; }","duration":"115.49442ms","start":"2026-02-26T06:25:47.815181Z","end":"2026-02-26T06:25:47.930676Z","steps":["trace[844358716] 'process raft request' (duration: 78.298796ms)","trace[844358716] 'compare' (duration: 36.98611ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:50.730055Z","caller":"traceutil/trace.go:171","msg":"trace[825216362] linearizableReadLoop","detail":"{readStateIndex:4464; appliedIndex:4463; }","duration":"105.616947ms","start":"2026-02-26T06:25:50.62441Z","end":"2026-02-26T06:25:50.730027Z","steps":["trace[825216362] 'read index received' (duration: 91.610218ms)","trace[825216362] 'applied index is now lower than readState.Index' (duration: 14.005669ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:50.730178Z","caller":"traceutil/trace.go:171","msg":"trace[968841888] transaction","detail":"{read_only:false; response_revision:4297; number_of_response:1; }","duration":"108.636348ms","start":"2026-02-26T06:25:50.621493Z","end":"2026-02-26T06:25:50.730129Z","steps":["trace[968841888] 'process raft request' (duration: 94.630529ms)","trace[968841888] 'compare' (duration: 13.739764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:25:50.730369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.923663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-02-26T06:25:50.730541Z","caller":"traceutil/trace.go:171","msg":"trace[1866258003] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4297; }","duration":"106.119777ms","start":"2026-02-26T06:25:50.624396Z","end":"2026-02-26T06:25:50.730516Z","steps":["trace[1866258003] 'agreement among raft nodes before linearized reading' (duration: 105.80042ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:50.955923Z","caller":"traceutil/trace.go:171","msg":"trace[1865857143] transaction","detail":"{read_only:false; response_revision:4298; number_of_response:1; }","duration":"129.744079ms","start":"2026-02-26T06:25:50.826153Z","end":"2026-02-26T06:25:50.955897Z","steps":["trace[1865857143] 'process raft request' (duration: 123.898572ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:50.956084Z","caller":"traceutil/trace.go:171","msg":"trace[934085453] transaction","detail":"{read_only:false; response_revision:4299; number_of_response:1; }","duration":"102.008575ms","start":"2026-02-26T06:25:50.854039Z","end":"2026-02-26T06:25:50.956047Z","steps":["trace[934085453] 'process raft request' (duration: 101.77462ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:51.937496Z","caller":"traceutil/trace.go:171","msg":"trace[1383789371] transaction","detail":"{read_only:false; response_revision:4311; number_of_response:1; }","duration":"108.632168ms","start":"2026-02-26T06:25:51.828825Z","end":"2026-02-26T06:25:51.937458Z","steps":["trace[1383789371] 'process raft request' (duration: 108.387094ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:55.158562Z","caller":"traceutil/trace.go:171","msg":"trace[195613812] transaction","detail":"{read_only:false; response_revision:4435; number_of_response:1; }","duration":"200.518782ms","start":"2026-02-26T06:25:54.95799Z","end":"2026-02-26T06:25:55.158508Z","steps":["trace[195613812] 'process raft request' (duration: 178.530093ms)","trace[195613812] 'compare' (duration: 21.613672ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:55.368563Z","caller":"traceutil/trace.go:171","msg":"trace[434930917] linearizableReadLoop","detail":"{readStateIndex:4607; appliedIndex:4606; }","duration":"192.847018ms","start":"2026-02-26T06:25:55.175676Z","end":"2026-02-26T06:25:55.368523Z","steps":["trace[434930917] 'read index received' (duration: 186.463711ms)","trace[434930917] 'applied index is now lower than readState.Index' (duration: 6.381987ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:55.368627Z","caller":"traceutil/trace.go:171","msg":"trace[532575476] transaction","detail":"{read_only:false; response_revision:4436; number_of_response:1; }","duration":"204.530561ms","start":"2026-02-26T06:25:55.164051Z","end":"2026-02-26T06:25:55.368582Z","steps":["trace[532575476] 'process raft request' (duration: 198.108113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:55.36889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.840372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:25:55.368943Z","caller":"traceutil/trace.go:171","msg":"trace[418842826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4436; }","duration":"114.967144ms","start":"2026-02-26T06:25:55.253961Z","end":"2026-02-26T06:25:55.368928Z","steps":["trace[418842826] 'agreement among raft nodes before linearized reading' (duration: 114.862712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:55.368968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.257487ms","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-26T06:25:55.369124Z","caller":"traceutil/trace.go:171","msg":"trace[1492687948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4436; }","duration":"193.438249ms","start":"2026-02-26T06:25:55.175624Z","end":"2026-02-26T06:25:55.369062Z","steps":["trace[1492687948] 'agreement among raft nodes before linearized reading' (duration: 193.076562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:55.369424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.31099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-fg6ng-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-02-26T06:25:55.369547Z","caller":"traceutil/trace.go:171","msg":"trace[328689983] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-fg6ng-tls; range_end:; response_count:1; response_revision:4436; }","duration":"131.504854ms","start":"2026-02-26T06:25:55.238012Z","end":"2026-02-26T06:25:55.369517Z","steps":["trace[328689983] 'agreement among raft nodes before linearized reading' (duration: 130.825891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:55.369677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.776916ms","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-26T06:25:55.369741Z","caller":"traceutil/trace.go:171","msg":"trace[520527993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4436; }","duration":"189.872699ms","start":"2026-02-26T06:25:55.179857Z","end":"2026-02-26T06:25:55.36973Z","steps":["trace[520527993] 'agreement among raft nodes before linearized reading' (duration: 188.876109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:55.790262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.29774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:25:55.790355Z","caller":"traceutil/trace.go:171","msg":"trace[1042165112] linearizableReadLoop","detail":"{readStateIndex:4619; appliedIndex:4618; }","duration":"175.471452ms","start":"2026-02-26T06:25:55.614867Z","end":"2026-02-26T06:25:55.790339Z","steps":["trace[1042165112] 'read index received' (duration: 33.881526ms)","trace[1042165112] 'applied index is now lower than readState.Index' (duration: 141.588856ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:55.790459Z","caller":"traceutil/trace.go:171","msg":"trace[1098038323] transaction","detail":"{read_only:false; response_revision:4448; number_of_response:1; }","duration":"260.473397ms","start":"2026-02-26T06:25:55.529957Z","end":"2026-02-26T06:25:55.790431Z","steps":["trace[1098038323] 'process raft request' (duration: 118.79908ms)","trace[1098038323] 'compare' (duration: 141.053694ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:25:55.790938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.062853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:811620"} {"level":"info","ts":"2026-02-26T06:25:55.791008Z","caller":"traceutil/trace.go:171","msg":"trace[1008692898] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4448; }","duration":"176.176375ms","start":"2026-02-26T06:25:55.614813Z","end":"2026-02-26T06:25:55.79099Z","steps":["trace[1008692898] 'agreement among raft nodes before linearized reading' (duration: 175.573994ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:57.364068Z","caller":"traceutil/trace.go:171","msg":"trace[1766215734] transaction","detail":"{read_only:false; response_revision:4476; number_of_response:1; }","duration":"143.154467ms","start":"2026-02-26T06:25:57.220867Z","end":"2026-02-26T06:25:57.364021Z","steps":["trace[1766215734] 'process raft request' (duration: 142.881792ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:57.364853Z","caller":"traceutil/trace.go:171","msg":"trace[1033084512] linearizableReadLoop","detail":"{readStateIndex:4647; appliedIndex:4647; }","duration":"110.326283ms","start":"2026-02-26T06:25:57.254488Z","end":"2026-02-26T06:25:57.364815Z","steps":["trace[1033084512] 'read index received' (duration: 110.312512ms)","trace[1033084512] 'applied index is now lower than readState.Index' (duration: 10.981µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:25:57.36506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.554037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:25:57.365123Z","caller":"traceutil/trace.go:171","msg":"trace[1702992521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4476; }","duration":"110.681829ms","start":"2026-02-26T06:25:57.25443Z","end":"2026-02-26T06:25:57.365112Z","steps":["trace[1702992521] 'agreement among raft nodes before linearized reading' (duration: 110.519436ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:26:09.979246Z","caller":"traceutil/trace.go:171","msg":"trace[446037106] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"125.194279ms","start":"2026-02-26T06:26:09.854023Z","end":"2026-02-26T06:26:09.979217Z","steps":["trace[446037106] 'process raft request' (duration: 124.995725ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:26:14.2327Z","caller":"traceutil/trace.go:171","msg":"trace[1920119452] linearizableReadLoop","detail":"{readStateIndex:5000; appliedIndex:4999; }","duration":"160.642755ms","start":"2026-02-26T06:26:14.072022Z","end":"2026-02-26T06:26:14.232665Z","steps":["trace[1920119452] 'read index received' (duration: 160.490312ms)","trace[1920119452] 'applied index is now lower than readState.Index' (duration: 150.873µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:26:14.233099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.085274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:63312"} {"level":"info","ts":"2026-02-26T06:26:14.233139Z","caller":"traceutil/trace.go:171","msg":"trace[710884481] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:4818; }","duration":"161.231147ms","start":"2026-02-26T06:26:14.071897Z","end":"2026-02-26T06:26:14.233128Z","steps":["trace[710884481] 'agreement among raft nodes before linearized reading' (duration: 160.924261ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:26:14.232715Z","caller":"traceutil/trace.go:171","msg":"trace[976821409] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"160.826539ms","start":"2026-02-26T06:26:14.071858Z","end":"2026-02-26T06:26:14.232685Z","steps":["trace[976821409] 'process raft request' (duration: 160.609724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:26:14.233721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.678896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-02-26T06:26:14.233826Z","caller":"traceutil/trace.go:171","msg":"trace[1233944942] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4818; }","duration":"161.93567ms","start":"2026-02-26T06:26:14.071861Z","end":"2026-02-26T06:26:14.233796Z","steps":["trace[1233944942] 'agreement among raft nodes before linearized reading' (duration: 160.89435ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:26:14.253647Z","caller":"traceutil/trace.go:171","msg":"trace[673584451] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"181.181325ms","start":"2026-02-26T06:26:14.072435Z","end":"2026-02-26T06:26:14.253616Z","steps":["trace[673584451] 'process raft request' (duration: 181.096193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:26:14.253854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.3948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T06:26:14.253955Z","caller":"traceutil/trace.go:171","msg":"trace[1932310971] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4820; }","duration":"178.512903ms","start":"2026-02-26T06:26:14.075418Z","end":"2026-02-26T06:26:14.253931Z","steps":["trace[1932310971] 'agreement among raft nodes before linearized reading' (duration: 178.331678ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:26:14.253893Z","caller":"traceutil/trace.go:171","msg":"trace[1061651447] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"181.830459ms","start":"2026-02-26T06:26:14.072049Z","end":"2026-02-26T06:26:14.253879Z","steps":["trace[1061651447] 'process raft request' (duration: 181.151105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:26:14.475504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.290167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:26:14.475635Z","caller":"traceutil/trace.go:171","msg":"trace[797001826] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5003; }","duration":"209.505111ms","start":"2026-02-26T06:26:14.266107Z","end":"2026-02-26T06:26:14.475612Z","steps":["trace[797001826] 'read index received' (duration: 7.951918ms)","trace[797001826] 'applied index is now lower than readState.Index' (duration: 201.551863ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:26:14.475937Z","caller":"traceutil/trace.go:171","msg":"trace[1350782533] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"218.609443ms","start":"2026-02-26T06:26:14.257314Z","end":"2026-02-26T06:26:14.475923Z","steps":["trace[1350782533] 'process raft request' (duration: 16.797905ms)","trace[1350782533] 'compare' (duration: 201.160845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:26:14.476384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.601814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-02-26T06:26:14.476507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.446101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa30415d-f509-44f8-b6d7-366324c14f03\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-02-26T06:26:14.476519Z","caller":"traceutil/trace.go:171","msg":"trace[1777043848] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4822; }","duration":"201.806478ms","start":"2026-02-26T06:26:14.274675Z","end":"2026-02-26T06:26:14.476482Z","steps":["trace[1777043848] 'agreement among raft nodes before linearized reading' (duration: 201.453651ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:26:14.476558Z","caller":"traceutil/trace.go:171","msg":"trace[1706697863] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa30415d-f509-44f8-b6d7-366324c14f03; range_end:; response_count:1; response_revision:4822; }","duration":"189.509592ms","start":"2026-02-26T06:26:14.287034Z","end":"2026-02-26T06:26:14.476543Z","steps":["trace[1706697863] 'agreement among raft nodes before linearized reading' (duration: 189.334578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:26:14.476459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.750515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:26:14.476765Z","caller":"traceutil/trace.go:171","msg":"trace[778562883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4822; }","duration":"222.077792ms","start":"2026-02-26T06:26:14.254656Z","end":"2026-02-26T06:26:14.476734Z","steps":["trace[778562883] 'agreement among raft nodes before linearized reading' (duration: 221.695804ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:26:14.890618Z","caller":"traceutil/trace.go:171","msg":"trace[2048551122] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"200.912689ms","start":"2026-02-26T06:26:14.689669Z","end":"2026-02-26T06:26:14.890582Z","steps":["trace[2048551122] 'process raft request' (duration: 200.688845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:27:02.319999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.403251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:27:02.320051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.328148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:27:02.320105Z","caller":"traceutil/trace.go:171","msg":"trace[46380949] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5284; }","duration":"112.554694ms","start":"2026-02-26T06:27:02.207526Z","end":"2026-02-26T06:27:02.32008Z","steps":["trace[46380949] 'range keys from in-memory index tree' (duration: 112.323929ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:27:02.320144Z","caller":"traceutil/trace.go:171","msg":"trace[412658187] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5284; }","duration":"112.489951ms","start":"2026-02-26T06:27:02.207629Z","end":"2026-02-26T06:27:02.320119Z","steps":["trace[412658187] 'range keys from in-memory index tree' (duration: 112.203546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:27:02.320087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.029472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-02-26T06:27:02.32026Z","caller":"traceutil/trace.go:171","msg":"trace[1520719289] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5284; }","duration":"105.253277ms","start":"2026-02-26T06:27:02.214974Z","end":"2026-02-26T06:27:02.320228Z","steps":["trace[1520719289] 'range keys from in-memory index tree' (duration: 104.818888ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:27:45.15729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2631} {"level":"info","ts":"2026-02-26T06:27:45.215336Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2631,"took":"56.399279ms","hash":2260479750,"current-db-size-bytes":40333312,"current-db-size":"40 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-26T06:27:45.215432Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2260479750,"revision":2631,"compact-revision":-1} {"level":"warn","ts":"2026-02-26T06:31:56.682966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.66292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T06:31:56.682978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.079393ms","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-26T06:31:56.683081Z","caller":"traceutil/trace.go:171","msg":"trace[660974130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7358; }","duration":"546.238566ms","start":"2026-02-26T06:31:56.136818Z","end":"2026-02-26T06:31:56.683057Z","steps":["trace[660974130] 'range keys from in-memory index tree' (duration: 545.861639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:56.683123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.877881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-26T06:31:56.683218Z","caller":"traceutil/trace.go:171","msg":"trace[1293431207] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7358; }","duration":"525.002213ms","start":"2026-02-26T06:31:56.158197Z","end":"2026-02-26T06:31:56.683199Z","steps":["trace[1293431207] 'range keys from in-memory index tree' (duration: 524.744428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:56.683261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:56.158191Z","time spent":"525.060695ms","remote":"127.0.0.1:35546","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-26T06:31:56.683303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.914158ms","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-26T06:31:56.683332Z","caller":"traceutil/trace.go:171","msg":"trace[570111080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7358; }","duration":"475.952778ms","start":"2026-02-26T06:31:56.207375Z","end":"2026-02-26T06:31:56.683328Z","steps":["trace[570111080] 'range keys from in-memory index tree' (duration: 475.820896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:56.683158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:56.136788Z","time spent":"546.355099ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T06:31:56.683351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:56.207368Z","time spent":"475.978139ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T06:31:56.683322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.5746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-02-26T06:31:56.683031Z","caller":"traceutil/trace.go:171","msg":"trace[285926930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7358; }","duration":"499.754511ms","start":"2026-02-26T06:31:56.183265Z","end":"2026-02-26T06:31:56.683019Z","steps":["trace[285926930] 'range keys from in-memory index tree' (duration: 499.613108ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:56.6834Z","caller":"traceutil/trace.go:171","msg":"trace[323186240] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7358; }","duration":"477.669511ms","start":"2026-02-26T06:31:56.205708Z","end":"2026-02-26T06:31:56.683377Z","steps":["trace[323186240] 'range keys from in-memory index tree' (duration: 477.462748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:56.683416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:56.183259Z","time spent":"500.139219ms","remote":"127.0.0.1:35630","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-26T06:31:56.683446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:56.205702Z","time spent":"477.730953ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T06:31:56.683619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.370346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-55d5f54b68-tsjqf.1897b832ce77a0be\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-02-26T06:31:56.683674Z","caller":"traceutil/trace.go:171","msg":"trace[111980545] range","detail":"{range_begin:/registry/events/openstack/barbican-api-55d5f54b68-tsjqf.1897b832ce77a0be; range_end:; response_count:1; response_revision:7358; }","duration":"760.486708ms","start":"2026-02-26T06:31:55.92317Z","end":"2026-02-26T06:31:56.683657Z","steps":["trace[111980545] 'range keys from in-memory index tree' (duration: 759.442567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:56.68372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:55.923134Z","time spent":"760.57361ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1159,"request content":"key:\"/registry/events/openstack/barbican-api-55d5f54b68-tsjqf.1897b832ce77a0be\" "} {"level":"info","ts":"2026-02-26T06:31:56.697132Z","caller":"traceutil/trace.go:171","msg":"trace[1152645871] transaction","detail":"{read_only:false; response_revision:7359; number_of_response:1; }","duration":"476.319285ms","start":"2026-02-26T06:31:56.220797Z","end":"2026-02-26T06:31:56.697117Z","steps":["trace[1152645871] 'process raft request' (duration: 476.200293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:56.697226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:56.220758Z","time spent":"476.415317ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:31:56.69714Z","caller":"traceutil/trace.go:171","msg":"trace[840408140] linearizableReadLoop","detail":"{readStateIndex:7623; appliedIndex:7623; }","duration":"371.423779ms","start":"2026-02-26T06:31:56.325694Z","end":"2026-02-26T06:31:56.697118Z","steps":["trace[840408140] 'read index received' (duration: 371.416979ms)","trace[840408140] 'applied index is now lower than readState.Index' (duration: 5.82µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:31:56.697432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.745695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-clbbv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T06:31:56.69746Z","caller":"traceutil/trace.go:171","msg":"trace[1102606889] range","detail":"{range_begin:/registry/pods/openstack/keepalived-clbbv; range_end:; response_count:1; response_revision:7359; }","duration":"371.811486ms","start":"2026-02-26T06:31:56.325639Z","end":"2026-02-26T06:31:56.69745Z","steps":["trace[1102606889] 'agreement among raft nodes before linearized reading' (duration: 371.724595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:56.697479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:56.325608Z","time spent":"371.866948ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-clbbv\" "} {"level":"warn","ts":"2026-02-26T06:31:56.748966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.494006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-26T06:31:56.749084Z","caller":"traceutil/trace.go:171","msg":"trace[1563209693] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7364; }","duration":"130.649669ms","start":"2026-02-26T06:31:56.618406Z","end":"2026-02-26T06:31:56.749055Z","steps":["trace[1563209693] 'agreement among raft nodes before linearized reading' (duration: 130.425604ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:08.284849Z","caller":"traceutil/trace.go:171","msg":"trace[1535323591] linearizableReadLoop","detail":"{readStateIndex:7685; appliedIndex:7684; }","duration":"154.410632ms","start":"2026-02-26T06:32:08.13041Z","end":"2026-02-26T06:32:08.28482Z","steps":["trace[1535323591] 'read index received' (duration: 154.328211ms)","trace[1535323591] 'applied index is now lower than readState.Index' (duration: 81.081µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:32:08.28502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.581137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:32:08.285055Z","caller":"traceutil/trace.go:171","msg":"trace[1636928633] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:7417; }","duration":"154.641028ms","start":"2026-02-26T06:32:08.130402Z","end":"2026-02-26T06:32:08.285044Z","steps":["trace[1636928633] 'agreement among raft nodes before linearized reading' (duration: 154.519165ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:08.937913Z","caller":"traceutil/trace.go:171","msg":"trace[2038291572] transaction","detail":"{read_only:false; response_revision:7419; number_of_response:1; }","duration":"117.299114ms","start":"2026-02-26T06:32:08.820584Z","end":"2026-02-26T06:32:08.937883Z","steps":["trace[2038291572] 'process raft request' (duration: 116.924255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:09.442459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812147777100630,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:32:09.669669Z","caller":"traceutil/trace.go:171","msg":"trace[1210774941] transaction","detail":"{read_only:false; response_revision:7420; number_of_response:1; }","duration":"727.732807ms","start":"2026-02-26T06:32:08.941899Z","end":"2026-02-26T06:32:09.669632Z","steps":["trace[1210774941] 'process raft request' (duration: 727.422721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:09.669877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:08.941869Z","time spent":"727.90206ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:32:09.746641Z","caller":"traceutil/trace.go:171","msg":"trace[1604046253] transaction","detail":"{read_only:false; response_revision:7421; number_of_response:1; }","duration":"804.32659ms","start":"2026-02-26T06:32:08.942285Z","end":"2026-02-26T06:32:09.746612Z","steps":["trace[1604046253] 'process raft request' (duration: 804.134226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:09.746812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:08.942268Z","time spent":"804.456773ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:32:09.747103Z","caller":"traceutil/trace.go:171","msg":"trace[527112832] transaction","detail":"{read_only:false; response_revision:7422; number_of_response:1; }","duration":"804.714378ms","start":"2026-02-26T06:32:08.942353Z","end":"2026-02-26T06:32:09.747067Z","steps":["trace[527112832] 'process raft request' (duration: 804.215888ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:09.747114Z","caller":"traceutil/trace.go:171","msg":"trace[1682990341] linearizableReadLoop","detail":"{readStateIndex:7692; appliedIndex:7687; }","duration":"804.81669ms","start":"2026-02-26T06:32:08.94227Z","end":"2026-02-26T06:32:09.747087Z","steps":["trace[1682990341] 'read index received' (duration: 727.011533ms)","trace[1682990341] 'applied index is now lower than readState.Index' (duration: 77.804237ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:09.747161Z","caller":"traceutil/trace.go:171","msg":"trace[54059177] transaction","detail":"{read_only:false; response_revision:7423; number_of_response:1; }","duration":"803.848139ms","start":"2026-02-26T06:32:08.9433Z","end":"2026-02-26T06:32:09.747148Z","steps":["trace[54059177] 'process raft request' (duration: 803.581564ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:09.747203Z","caller":"traceutil/trace.go:171","msg":"trace[56250321] transaction","detail":"{read_only:false; response_revision:7424; number_of_response:1; }","duration":"392.203192ms","start":"2026-02-26T06:32:09.354976Z","end":"2026-02-26T06:32:09.74718Z","steps":["trace[56250321] 'process raft request' (duration: 392.004307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:09.74723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:08.94329Z","time spent":"803.906592ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:32:09.747263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:08.94234Z","time spent":"804.839491ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:32:09.747321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:09.354958Z","time spent":"392.298825ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:32:09.747422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.31233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-02-26T06:32:09.747457Z","caller":"traceutil/trace.go:171","msg":"trace[1765099215] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7424; }","duration":"522.421163ms","start":"2026-02-26T06:32:09.225024Z","end":"2026-02-26T06:32:09.747445Z","steps":["trace[1765099215] 'agreement among raft nodes before linearized reading' (duration: 522.290211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:09.747533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:09.225003Z","time spent":"522.520225ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-02-26T06:32:09.747529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.363056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:32:09.747555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.387709ms","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-26T06:32:09.74758Z","caller":"traceutil/trace.go:171","msg":"trace[928122109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7424; }","duration":"493.475827ms","start":"2026-02-26T06:32:09.25409Z","end":"2026-02-26T06:32:09.747565Z","steps":["trace[928122109] 'agreement among raft nodes before linearized reading' (duration: 493.348995ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:09.747594Z","caller":"traceutil/trace.go:171","msg":"trace[1041913924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7424; }","duration":"271.461961ms","start":"2026-02-26T06:32:09.47612Z","end":"2026-02-26T06:32:09.747582Z","steps":["trace[1041913924] 'agreement among raft nodes before linearized reading' (duration: 271.268487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:09.747614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:09.254079Z","time spent":"493.527958ms","remote":"127.0.0.1:35358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:32:09.747662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.371102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207035"} {"level":"warn","ts":"2026-02-26T06:32:09.747667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.302119ms","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-26T06:32:09.747703Z","caller":"traceutil/trace.go:171","msg":"trace[364997934] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7424; }","duration":"805.458613ms","start":"2026-02-26T06:32:08.942234Z","end":"2026-02-26T06:32:09.747692Z","steps":["trace[364997934] 'agreement among raft nodes before linearized reading' (duration: 804.992964ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:09.747721Z","caller":"traceutil/trace.go:171","msg":"trace[1441443309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7424; }","duration":"256.402681ms","start":"2026-02-26T06:32:09.491304Z","end":"2026-02-26T06:32:09.747707Z","steps":["trace[1441443309] 'agreement among raft nodes before linearized reading' (duration: 256.233648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:09.747744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:08.94222Z","time spent":"805.513335ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":24,"response size":207059,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-02-26T06:32:09.922281Z","caller":"traceutil/trace.go:171","msg":"trace[1059855327] transaction","detail":"{read_only:false; response_revision:7425; number_of_response:1; }","duration":"169.41214ms","start":"2026-02-26T06:32:09.752832Z","end":"2026-02-26T06:32:09.922244Z","steps":["trace[1059855327] 'process raft request' (duration: 105.959817ms)","trace[1059855327] 'compare' (duration: 63.190738ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:10.090505Z","caller":"traceutil/trace.go:171","msg":"trace[1806420759] transaction","detail":"{read_only:false; response_revision:7426; number_of_response:1; }","duration":"337.464333ms","start":"2026-02-26T06:32:09.753013Z","end":"2026-02-26T06:32:10.090477Z","steps":["trace[1806420759] 'process raft request' (duration: 329.871142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:10.090659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:09.752993Z","time spent":"337.585495ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:32:10.090511Z","caller":"traceutil/trace.go:171","msg":"trace[1283130469] transaction","detail":"{read_only:false; response_revision:7427; number_of_response:1; }","duration":"337.251789ms","start":"2026-02-26T06:32:09.75324Z","end":"2026-02-26T06:32:10.090492Z","steps":["trace[1283130469] 'process raft request' (duration: 337.161757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:10.090842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:09.753205Z","time spent":"337.579455ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:32:10.417149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.543563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:32:10.417248Z","caller":"traceutil/trace.go:171","msg":"trace[352992730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7427; }","duration":"162.761827ms","start":"2026-02-26T06:32:10.254466Z","end":"2026-02-26T06:32:10.417228Z","steps":["trace[352992730] 'range keys from in-memory index tree' (duration: 162.403151ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:11.213431Z","caller":"traceutil/trace.go:171","msg":"trace[964057435] transaction","detail":"{read_only:false; response_revision:7428; number_of_response:1; }","duration":"266.087543ms","start":"2026-02-26T06:32:10.947318Z","end":"2026-02-26T06:32:11.213406Z","steps":["trace[964057435] 'process raft request' (duration: 265.92188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:11.420214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.236227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:32:11.420311Z","caller":"traceutil/trace.go:171","msg":"trace[1467430470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7428; }","duration":"165.421311ms","start":"2026-02-26T06:32:11.254867Z","end":"2026-02-26T06:32:11.420288Z","steps":["trace[1467430470] 'range keys from in-memory index tree' (duration: 165.039943ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:11.868693Z","caller":"traceutil/trace.go:171","msg":"trace[2130002404] linearizableReadLoop","detail":"{readStateIndex:7701; appliedIndex:7698; }","duration":"108.381167ms","start":"2026-02-26T06:32:11.760285Z","end":"2026-02-26T06:32:11.868666Z","steps":["trace[2130002404] 'read index received' (duration: 3.467699ms)","trace[2130002404] 'applied index is now lower than readState.Index' (duration: 104.912328ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:11.86884Z","caller":"traceutil/trace.go:171","msg":"trace[163309568] transaction","detail":"{read_only:false; response_revision:7431; number_of_response:1; }","duration":"111.858345ms","start":"2026-02-26T06:32:11.756935Z","end":"2026-02-26T06:32:11.868793Z","steps":["trace[163309568] 'process raft request' (duration: 92.780515ms)","trace[163309568] 'compare' (duration: 18.725093ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:11.868856Z","caller":"traceutil/trace.go:171","msg":"trace[1515404040] transaction","detail":"{read_only:false; response_revision:7432; number_of_response:1; }","duration":"110.761654ms","start":"2026-02-26T06:32:11.758054Z","end":"2026-02-26T06:32:11.868815Z","steps":["trace[1515404040] 'process raft request' (duration: 110.512039ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:11.868964Z","caller":"traceutil/trace.go:171","msg":"trace[1954102091] transaction","detail":"{read_only:false; response_revision:7433; number_of_response:1; }","duration":"109.721293ms","start":"2026-02-26T06:32:11.759203Z","end":"2026-02-26T06:32:11.868925Z","steps":["trace[1954102091] 'process raft request' (duration: 109.414407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:11.869244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.936547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207035"} {"level":"info","ts":"2026-02-26T06:32:11.869298Z","caller":"traceutil/trace.go:171","msg":"trace[1163348758] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7433; }","duration":"109.023729ms","start":"2026-02-26T06:32:11.760261Z","end":"2026-02-26T06:32:11.869285Z","steps":["trace[1163348758] 'agreement among raft nodes before linearized reading' (duration: 108.514409ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:12.094595Z","caller":"traceutil/trace.go:171","msg":"trace[1542419627] transaction","detail":"{read_only:false; response_revision:7434; number_of_response:1; }","duration":"163.862909ms","start":"2026-02-26T06:32:11.930706Z","end":"2026-02-26T06:32:12.094569Z","steps":["trace[1542419627] 'process raft request' (duration: 159.87116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:38.850414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.207218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:32:38.850583Z","caller":"traceutil/trace.go:171","msg":"trace[732014233] linearizableReadLoop","detail":"{readStateIndex:7893; appliedIndex:7892; }","duration":"393.775522ms","start":"2026-02-26T06:32:38.456784Z","end":"2026-02-26T06:32:38.85056Z","steps":["trace[732014233] 'read index received' (duration: 242.254727ms)","trace[732014233] 'applied index is now lower than readState.Index' (duration: 151.519185ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:38.850735Z","caller":"traceutil/trace.go:171","msg":"trace[639545548] transaction","detail":"{read_only:false; response_revision:7618; number_of_response:1; }","duration":"605.213928ms","start":"2026-02-26T06:32:38.245501Z","end":"2026-02-26T06:32:38.850715Z","steps":["trace[639545548] 'process raft request' (duration: 453.620622ms)","trace[639545548] 'compare' (duration: 151.098916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:32:38.850834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:38.245485Z","time spent":"605.29137ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:32:38.85121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.406704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:32:38.851277Z","caller":"traceutil/trace.go:171","msg":"trace[290627236] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:7618; }","duration":"394.484656ms","start":"2026-02-26T06:32:38.456776Z","end":"2026-02-26T06:32:38.851261Z","steps":["trace[290627236] 'agreement among raft nodes before linearized reading' (duration: 394.332733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:38.851316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:38.456711Z","time spent":"394.595009ms","remote":"127.0.0.1:35894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":37,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-26T06:32:45.168443Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5602} {"level":"info","ts":"2026-02-26T06:32:45.258717Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5602,"took":"88.169814ms","hash":2098199352,"current-db-size-bytes":41689088,"current-db-size":"42 MB","current-db-size-in-use-bytes":22552576,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-26T06:32:45.258774Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2098199352,"revision":5602,"compact-revision":2631} {"level":"info","ts":"2026-02-26T06:32:50.915862Z","caller":"traceutil/trace.go:171","msg":"trace[1450488652] transaction","detail":"{read_only:false; response_revision:7717; number_of_response:1; }","duration":"110.944926ms","start":"2026-02-26T06:32:50.804859Z","end":"2026-02-26T06:32:50.915804Z","steps":["trace[1450488652] 'process raft request' (duration: 110.702882ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:51.175355Z","caller":"traceutil/trace.go:171","msg":"trace[1152238538] transaction","detail":"{read_only:false; response_revision:7719; number_of_response:1; }","duration":"161.395471ms","start":"2026-02-26T06:32:51.013926Z","end":"2026-02-26T06:32:51.175322Z","steps":["trace[1152238538] 'process raft request' (duration: 161.198777ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:51.997819Z","caller":"traceutil/trace.go:171","msg":"trace[794442587] transaction","detail":"{read_only:false; response_revision:7721; number_of_response:1; }","duration":"206.000358ms","start":"2026-02-26T06:32:51.791777Z","end":"2026-02-26T06:32:51.997777Z","steps":["trace[794442587] 'process raft request' (duration: 205.679441ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:52.751365Z","caller":"traceutil/trace.go:171","msg":"trace[1209889854] linearizableReadLoop","detail":"{readStateIndex:8011; appliedIndex:8010; }","duration":"124.525447ms","start":"2026-02-26T06:32:52.62681Z","end":"2026-02-26T06:32:52.751335Z","steps":["trace[1209889854] 'read index received' (duration: 115.789713ms)","trace[1209889854] 'applied index is now lower than readState.Index' (duration: 8.730434ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:52.751386Z","caller":"traceutil/trace.go:171","msg":"trace[992691777] transaction","detail":"{read_only:false; response_revision:7730; number_of_response:1; }","duration":"131.399384ms","start":"2026-02-26T06:32:52.619954Z","end":"2026-02-26T06:32:52.751353Z","steps":["trace[992691777] 'process raft request' (duration: 122.585618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:52.751732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.840694ms","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-26T06:32:52.751867Z","caller":"traceutil/trace.go:171","msg":"trace[1367676593] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:7730; }","duration":"125.068188ms","start":"2026-02-26T06:32:52.626766Z","end":"2026-02-26T06:32:52.751834Z","steps":["trace[1367676593] 'agreement among raft nodes before linearized reading' (duration: 124.68395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:53.324551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.589201ms","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-26T06:32:53.324645Z","caller":"traceutil/trace.go:171","msg":"trace[1645891906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7730; }","duration":"370.757765ms","start":"2026-02-26T06:32:52.953869Z","end":"2026-02-26T06:32:53.324627Z","steps":["trace[1645891906] 'range keys from in-memory index tree' (duration: 370.389697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:53.324696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:52.953842Z","time spent":"370.843976ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:32:53.32481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.042444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-26T06:32:53.324867Z","caller":"traceutil/trace.go:171","msg":"trace[793941356] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7730; }","duration":"432.154975ms","start":"2026-02-26T06:32:52.892699Z","end":"2026-02-26T06:32:53.324854Z","steps":["trace[793941356] 'range keys from in-memory index tree' (duration: 431.569244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:53.324895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:52.892677Z","time spent":"432.214007ms","remote":"127.0.0.1:35546","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-26T06:32:53.325957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.221099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2364562"} {"level":"info","ts":"2026-02-26T06:32:53.326113Z","caller":"traceutil/trace.go:171","msg":"trace[1225901534] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:7730; }","duration":"327.318611ms","start":"2026-02-26T06:32:52.998705Z","end":"2026-02-26T06:32:53.326023Z","steps":["trace[1225901534] 'range keys from in-memory index tree' (duration: 325.653487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:53.326226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:52.99865Z","time spent":"327.536885ms","remote":"127.0.0.1:35470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2364586,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-02-26T06:32:54.160739Z","caller":"traceutil/trace.go:171","msg":"trace[438520909] linearizableReadLoop","detail":"{readStateIndex:8016; appliedIndex:8015; }","duration":"141.953574ms","start":"2026-02-26T06:32:54.018762Z","end":"2026-02-26T06:32:54.160716Z","steps":["trace[438520909] 'read index received' (duration: 141.789341ms)","trace[438520909] 'applied index is now lower than readState.Index' (duration: 163.363µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:54.160785Z","caller":"traceutil/trace.go:171","msg":"trace[178643072] transaction","detail":"{read_only:false; response_revision:7734; number_of_response:1; }","duration":"238.551445ms","start":"2026-02-26T06:32:53.922209Z","end":"2026-02-26T06:32:54.16076Z","steps":["trace[178643072] 'process raft request' (duration: 238.32847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:54.160938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.170608ms","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-26T06:32:54.160979Z","caller":"traceutil/trace.go:171","msg":"trace[1470403128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7734; }","duration":"142.26783ms","start":"2026-02-26T06:32:54.018701Z","end":"2026-02-26T06:32:54.160969Z","steps":["trace[1470403128] 'agreement among raft nodes before linearized reading' (duration: 142.131657ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:54.342679Z","caller":"traceutil/trace.go:171","msg":"trace[460840430] transaction","detail":"{read_only:false; response_revision:7735; number_of_response:1; }","duration":"249.232847ms","start":"2026-02-26T06:32:54.093405Z","end":"2026-02-26T06:32:54.342638Z","steps":["trace[460840430] 'process raft request' (duration: 174.682554ms)","trace[460840430] 'compare' (duration: 74.38248ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:54.342908Z","caller":"traceutil/trace.go:171","msg":"trace[896264139] transaction","detail":"{read_only:false; response_revision:7736; number_of_response:1; }","duration":"178.550922ms","start":"2026-02-26T06:32:54.164332Z","end":"2026-02-26T06:32:54.342883Z","steps":["trace[896264139] 'process raft request' (duration: 178.289827ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:54.342689Z","caller":"traceutil/trace.go:171","msg":"trace[2128177615] linearizableReadLoop","detail":"{readStateIndex:8017; appliedIndex:8016; }","duration":"181.856417ms","start":"2026-02-26T06:32:54.160806Z","end":"2026-02-26T06:32:54.342662Z","steps":["trace[2128177615] 'read index received' (duration: 107.297094ms)","trace[2128177615] 'applied index is now lower than readState.Index' (duration: 74.558293ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:32:54.34303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.967531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-26T06:32:54.3431Z","caller":"traceutil/trace.go:171","msg":"trace[1618624311] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7736; }","duration":"252.101404ms","start":"2026-02-26T06:32:54.090983Z","end":"2026-02-26T06:32:54.343085Z","steps":["trace[1618624311] 'agreement among raft nodes before linearized reading' (duration: 251.761178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:54.343071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.229513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-clbbv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T06:32:54.34316Z","caller":"traceutil/trace.go:171","msg":"trace[1689363217] range","detail":"{range_begin:/registry/pods/openstack/keepalived-clbbv; range_end:; response_count:1; response_revision:7736; }","duration":"193.360706ms","start":"2026-02-26T06:32:54.149778Z","end":"2026-02-26T06:32:54.343139Z","steps":["trace[1689363217] 'agreement among raft nodes before linearized reading' (duration: 192.969419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:54.343169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.344273ms","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-26T06:32:54.343215Z","caller":"traceutil/trace.go:171","msg":"trace[153645172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7736; }","duration":"221.415114ms","start":"2026-02-26T06:32:54.121789Z","end":"2026-02-26T06:32:54.343204Z","steps":["trace[153645172] 'agreement among raft nodes before linearized reading' (duration: 220.952694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:54.343433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.461507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T06:32:54.343432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.280264ms","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-26T06:32:54.343492Z","caller":"traceutil/trace.go:171","msg":"trace[407593284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7736; }","duration":"202.570279ms","start":"2026-02-26T06:32:54.140907Z","end":"2026-02-26T06:32:54.343477Z","steps":["trace[407593284] 'agreement among raft nodes before linearized reading' (duration: 201.814314ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:54.343553Z","caller":"traceutil/trace.go:171","msg":"trace[1950249] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7736; }","duration":"196.439367ms","start":"2026-02-26T06:32:54.147079Z","end":"2026-02-26T06:32:54.343519Z","steps":["trace[1950249] 'agreement among raft nodes before linearized reading' (duration: 195.637531ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:33:02.554555Z","caller":"traceutil/trace.go:171","msg":"trace[1995091364] linearizableReadLoop","detail":"{readStateIndex:8087; appliedIndex:8086; }","duration":"107.858245ms","start":"2026-02-26T06:33:02.446668Z","end":"2026-02-26T06:33:02.554526Z","steps":["trace[1995091364] 'read index received' (duration: 97.928208ms)","trace[1995091364] 'applied index is now lower than readState.Index' (duration: 9.928247ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:33:02.554711Z","caller":"traceutil/trace.go:171","msg":"trace[188205246] transaction","detail":"{read_only:false; response_revision:7803; number_of_response:1; }","duration":"108.818985ms","start":"2026-02-26T06:33:02.445878Z","end":"2026-02-26T06:33:02.554697Z","steps":["trace[188205246] 'process raft request' (duration: 98.613403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:33:02.554947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.870806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-26T06:33:02.555037Z","caller":"traceutil/trace.go:171","msg":"trace[257255159] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7803; }","duration":"100.987138ms","start":"2026-02-26T06:33:02.454026Z","end":"2026-02-26T06:33:02.555013Z","steps":["trace[257255159] 'agreement among raft nodes before linearized reading' (duration: 100.755764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:33:02.555232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.547979ms","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-26T06:33:02.555322Z","caller":"traceutil/trace.go:171","msg":"trace[1656063723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7803; }","duration":"108.689851ms","start":"2026-02-26T06:33:02.44661Z","end":"2026-02-26T06:33:02.5553Z","steps":["trace[1656063723] 'agreement among raft nodes before linearized reading' (duration: 108.535638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:33:02.555428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.353856ms","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-26T06:33:02.555482Z","caller":"traceutil/trace.go:171","msg":"trace[1082138397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7803; }","duration":"101.423057ms","start":"2026-02-26T06:33:02.454049Z","end":"2026-02-26T06:33:02.555472Z","steps":["trace[1082138397] 'agreement among raft nodes before linearized reading' (duration: 101.297175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:33:48.772104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.788103ms","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-26T06:33:48.772187Z","caller":"traceutil/trace.go:171","msg":"trace[1515406216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8269; }","duration":"109.932836ms","start":"2026-02-26T06:33:48.662234Z","end":"2026-02-26T06:33:48.772167Z","steps":["trace[1515406216] 'range keys from in-memory index tree' (duration: 109.60845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:34:45.426433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.46851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:34:45.426546Z","caller":"traceutil/trace.go:171","msg":"trace[819576688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8708; }","duration":"171.649425ms","start":"2026-02-26T06:34:45.254862Z","end":"2026-02-26T06:34:45.426512Z","steps":["trace[819576688] 'range keys from in-memory index tree' (duration: 171.345108ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:36:41.729875Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a11c20306510c1f7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T06:36:41.736014Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-26T06:36:41.73617Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-26T06:37:20.16178Z","caller":"traceutil/trace.go:171","msg":"trace[571827051] transaction","detail":"{read_only:false; response_revision:10044; number_of_response:1; }","duration":"177.093842ms","start":"2026-02-26T06:37:19.984649Z","end":"2026-02-26T06:37:20.161743Z","steps":["trace[571827051] 'process raft request' (duration: 177.03011ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:37:20.161924Z","caller":"traceutil/trace.go:171","msg":"trace[1536764920] transaction","detail":"{read_only:false; response_revision:10043; number_of_response:1; }","duration":"177.249164ms","start":"2026-02-26T06:37:19.984634Z","end":"2026-02-26T06:37:20.161883Z","steps":["trace[1536764920] 'process raft request' (duration: 176.791795ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:37:20.162521Z","caller":"traceutil/trace.go:171","msg":"trace[27571487] transaction","detail":"{read_only:false; response_revision:10045; number_of_response:1; }","duration":"177.707494ms","start":"2026-02-26T06:37:19.984784Z","end":"2026-02-26T06:37:20.162492Z","steps":["trace[27571487] 'process raft request' (duration: 177.547891ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:37:20.162761Z","caller":"traceutil/trace.go:171","msg":"trace[1092756142] transaction","detail":"{read_only:false; response_revision:10046; number_of_response:1; }","duration":"177.582621ms","start":"2026-02-26T06:37:19.985157Z","end":"2026-02-26T06:37:20.162739Z","steps":["trace[1092756142] 'process raft request' (duration: 177.291685ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:37:33.103966Z","caller":"traceutil/trace.go:171","msg":"trace[824612368] transaction","detail":"{read_only:false; response_revision:10112; number_of_response:1; }","duration":"148.842407ms","start":"2026-02-26T06:37:32.955081Z","end":"2026-02-26T06:37:33.103924Z","steps":["trace[824612368] 'process raft request' (duration: 148.553792ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:37:45.186847Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7678} {"level":"info","ts":"2026-02-26T06:37:45.257053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7678,"took":"67.834046ms","hash":737232066,"current-db-size-bytes":41689088,"current-db-size":"42 MB","current-db-size-in-use-bytes":27496448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T06:37:45.257142Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":737232066,"revision":7678,"compact-revision":5602} {"level":"info","ts":"2026-02-26T06:42:45.193341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10200} {"level":"info","ts":"2026-02-26T06:42:45.277015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10200,"took":"80.775748ms","hash":1042829198,"current-db-size-bytes":41689088,"current-db-size":"42 MB","current-db-size-in-use-bytes":23953408,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-26T06:42:45.277089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1042829198,"revision":10200,"compact-revision":7678} {"level":"info","ts":"2026-02-26T06:43:56.961274Z","caller":"traceutil/trace.go:171","msg":"trace[1766674875] linearizableReadLoop","detail":"{readStateIndex:12947; appliedIndex:12946; }","duration":"490.203549ms","start":"2026-02-26T06:43:56.471008Z","end":"2026-02-26T06:43:56.961212Z","steps":["trace[1766674875] 'read index received' (duration: 489.865303ms)","trace[1766674875] 'applied index is now lower than readState.Index' (duration: 335.516µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:43:56.961281Z","caller":"traceutil/trace.go:171","msg":"trace[1799760475] transaction","detail":"{read_only:false; response_revision:12502; number_of_response:1; }","duration":"502.908944ms","start":"2026-02-26T06:43:56.458337Z","end":"2026-02-26T06:43:56.961245Z","steps":["trace[1799760475] 'process raft request' (duration: 502.533287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:56.961465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.438835ms","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-26T06:43:56.961529Z","caller":"traceutil/trace.go:171","msg":"trace[1122949005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12502; }","duration":"490.545226ms","start":"2026-02-26T06:43:56.47097Z","end":"2026-02-26T06:43:56.961515Z","steps":["trace[1122949005] 'agreement among raft nodes before linearized reading' (duration: 490.351403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:56.961569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:43:56.470957Z","time spent":"490.606478ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T06:43:56.961568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:43:56.45832Z","time spent":"503.175599ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:43:56.961895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.629518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-26T06:43:56.961956Z","caller":"traceutil/trace.go:171","msg":"trace[1459555908] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12502; }","duration":"489.722711ms","start":"2026-02-26T06:43:56.472215Z","end":"2026-02-26T06:43:56.961938Z","steps":["trace[1459555908] 'agreement among raft nodes before linearized reading' (duration: 489.546757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:56.961991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:43:56.472199Z","time spent":"489.784021ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T06:43:56.962077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.325501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-26T06:43:56.962179Z","caller":"traceutil/trace.go:171","msg":"trace[1295521055] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12502; }","duration":"288.464674ms","start":"2026-02-26T06:43:56.673695Z","end":"2026-02-26T06:43:56.96216Z","steps":["trace[1295521055] 'agreement among raft nodes before linearized reading' (duration: 288.106967ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:43:57.237644Z","caller":"traceutil/trace.go:171","msg":"trace[733927201] linearizableReadLoop","detail":"{readStateIndex:12950; appliedIndex:12949; }","duration":"186.916692ms","start":"2026-02-26T06:43:57.050693Z","end":"2026-02-26T06:43:57.23761Z","steps":["trace[733927201] 'read index received' (duration: 175.997234ms)","trace[733927201] 'applied index is now lower than readState.Index' (duration: 10.918478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:43:57.237838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.126307ms","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-26T06:43:57.237882Z","caller":"traceutil/trace.go:171","msg":"trace[141274330] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12505; }","duration":"187.235318ms","start":"2026-02-26T06:43:57.050636Z","end":"2026-02-26T06:43:57.237872Z","steps":["trace[141274330] 'agreement among raft nodes before linearized reading' (duration: 187.117526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:57.237914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.445743ms","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-26T06:43:57.237949Z","caller":"traceutil/trace.go:171","msg":"trace[400974237] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:12505; }","duration":"186.518424ms","start":"2026-02-26T06:43:57.051421Z","end":"2026-02-26T06:43:57.237939Z","steps":["trace[400974237] 'agreement among raft nodes before linearized reading' (duration: 186.457263ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:43:57.238052Z","caller":"traceutil/trace.go:171","msg":"trace[438385656] transaction","detail":"{read_only:false; response_revision:12505; number_of_response:1; }","duration":"239.138982ms","start":"2026-02-26T06:43:56.998904Z","end":"2026-02-26T06:43:57.238043Z","steps":["trace[438385656] 'process raft request' (duration: 227.792377ms)","trace[438385656] 'compare' (duration: 10.683011ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:43:59.099033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.088062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:43:59.0992Z","caller":"traceutil/trace.go:171","msg":"trace[1820126164] transaction","detail":"{read_only:false; response_revision:12523; number_of_response:1; }","duration":"113.457878ms","start":"2026-02-26T06:43:58.985724Z","end":"2026-02-26T06:43:59.099182Z","steps":["trace[1820126164] 'compare' (duration: 110.97775ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:43:59.099431Z","caller":"traceutil/trace.go:171","msg":"trace[1416397252] transaction","detail":"{read_only:false; response_revision:12524; number_of_response:1; }","duration":"112.845587ms","start":"2026-02-26T06:43:58.986574Z","end":"2026-02-26T06:43:59.099419Z","steps":["trace[1416397252] 'process raft request' (duration: 112.561401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:05.031679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.141257ms","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-26T06:44:05.031819Z","caller":"traceutil/trace.go:171","msg":"trace[288079903] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:12651; }","duration":"291.31225ms","start":"2026-02-26T06:44:04.740487Z","end":"2026-02-26T06:44:05.031799Z","steps":["trace[288079903] 'range keys from in-memory index tree' (duration: 291.120246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:05.031903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.183893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-7785c59fb4-rkkpx\" ","response":"range_response_count:1 size:6825"} {"level":"info","ts":"2026-02-26T06:44:05.032172Z","caller":"traceutil/trace.go:171","msg":"trace[515820967] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-7785c59fb4-rkkpx; range_end:; response_count:1; response_revision:12651; }","duration":"110.52046ms","start":"2026-02-26T06:44:04.921639Z","end":"2026-02-26T06:44:05.03216Z","steps":["trace[515820967] 'range keys from in-memory index tree' (duration: 110.05774ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:44:05.207818Z","caller":"traceutil/trace.go:171","msg":"trace[1905962695] transaction","detail":"{read_only:false; response_revision:12652; number_of_response:1; }","duration":"171.078236ms","start":"2026-02-26T06:44:05.036704Z","end":"2026-02-26T06:44:05.207782Z","steps":["trace[1905962695] 'process raft request' (duration: 170.919813ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:44:05.341464Z","caller":"traceutil/trace.go:171","msg":"trace[239646436] linearizableReadLoop","detail":"{readStateIndex:13102; appliedIndex:13100; }","duration":"217.371538ms","start":"2026-02-26T06:44:05.124068Z","end":"2026-02-26T06:44:05.341439Z","steps":["trace[239646436] 'read index received' (duration: 83.631735ms)","trace[239646436] 'applied index is now lower than readState.Index' (duration: 133.738263ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:44:05.341573Z","caller":"traceutil/trace.go:171","msg":"trace[233625827] transaction","detail":"{read_only:false; response_revision:12653; number_of_response:1; }","duration":"301.254087ms","start":"2026-02-26T06:44:05.040282Z","end":"2026-02-26T06:44:05.341536Z","steps":["trace[233625827] 'process raft request' (duration: 300.970362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:05.341791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.710354ms","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-26T06:44:05.341828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:44:05.04026Z","time spent":"301.38868ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7048,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:44:05.341844Z","caller":"traceutil/trace.go:171","msg":"trace[949499457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12653; }","duration":"217.827366ms","start":"2026-02-26T06:44:05.124006Z","end":"2026-02-26T06:44:05.341833Z","steps":["trace[949499457] 'agreement among raft nodes before linearized reading' (duration: 217.546311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:05.342438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.105622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T06:44:05.342525Z","caller":"traceutil/trace.go:171","msg":"trace[964888648] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12654; }","duration":"216.221604ms","start":"2026-02-26T06:44:05.126281Z","end":"2026-02-26T06:44:05.342503Z","steps":["trace[964888648] 'agreement among raft nodes before linearized reading' (duration: 215.953409ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:44:10.473131Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000033af.wal"} {"level":"warn","ts":"2026-02-26T06:44:57.314467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.646843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-vcw5x\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-02-26T06:44:57.314565Z","caller":"traceutil/trace.go:171","msg":"trace[2003864169] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-vcw5x; range_end:; response_count:1; response_revision:13397; }","duration":"111.783876ms","start":"2026-02-26T06:44:57.202758Z","end":"2026-02-26T06:44:57.314542Z","steps":["trace[2003864169] 'agreement among raft nodes before linearized reading' (duration: 111.550792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:45:09.958329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.091802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-26T06:45:09.95848Z","caller":"traceutil/trace.go:171","msg":"trace[2010494786] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13521; }","duration":"111.338526ms","start":"2026-02-26T06:45:09.847113Z","end":"2026-02-26T06:45:09.958452Z","steps":["trace[2010494786] 'range keys from in-memory index tree' (duration: 110.854786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:45:10.43073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.548335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:45:10.430843Z","caller":"traceutil/trace.go:171","msg":"trace[2057965303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13529; }","duration":"176.726959ms","start":"2026-02-26T06:45:10.254095Z","end":"2026-02-26T06:45:10.430822Z","steps":["trace[2057965303] 'range keys from in-memory index tree' (duration: 176.431313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:45:44.391471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.725636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:45:44.392755Z","caller":"traceutil/trace.go:171","msg":"trace[516579456] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:13693; }","duration":"311.027932ms","start":"2026-02-26T06:45:44.081704Z","end":"2026-02-26T06:45:44.392732Z","steps":["trace[516579456] 'count revisions from in-memory index tree' (duration: 309.620764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:45:44.391846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.282603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-02-26T06:45:44.39197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.608657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-02-26T06:45:44.392668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.834295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:45:44.393433Z","caller":"traceutil/trace.go:171","msg":"trace[793023460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13693; }","duration":"138.633461ms","start":"2026-02-26T06:45:44.254784Z","end":"2026-02-26T06:45:44.393417Z","steps":["trace[793023460] 'range keys from in-memory index tree' (duration: 137.769164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:45:44.393107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:45:44.081619Z","time spent":"311.42269ms","remote":"127.0.0.1:35474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-26T06:45:44.393295Z","caller":"traceutil/trace.go:171","msg":"trace[430037459] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13693; }","duration":"248.757713ms","start":"2026-02-26T06:45:44.144506Z","end":"2026-02-26T06:45:44.393264Z","steps":["trace[430037459] 'range keys from in-memory index tree' (duration: 247.15174ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:45:44.393364Z","caller":"traceutil/trace.go:171","msg":"trace[1094438619] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:13693; }","duration":"279.089777ms","start":"2026-02-26T06:45:44.114251Z","end":"2026-02-26T06:45:44.39334Z","steps":["trace[1094438619] 'range keys from in-memory index tree' (duration: 277.358142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:45:44.729989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.142432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:45:44.730124Z","caller":"traceutil/trace.go:171","msg":"trace[678403250] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:13694; }","duration":"232.352697ms","start":"2026-02-26T06:45:44.497744Z","end":"2026-02-26T06:45:44.730097Z","steps":["trace[678403250] 'count revisions from in-memory index tree' (duration: 232.02843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:45:44.730625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.98458ms","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-26T06:45:44.730723Z","caller":"traceutil/trace.go:171","msg":"trace[146052452] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13694; }","duration":"224.121623ms","start":"2026-02-26T06:45:44.506579Z","end":"2026-02-26T06:45:44.7307Z","steps":["trace[146052452] 'range keys from in-memory index tree' (duration: 223.860718ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:45:44.890277Z","caller":"traceutil/trace.go:171","msg":"trace[1933798669] linearizableReadLoop","detail":"{readStateIndex:14178; appliedIndex:14177; }","duration":"110.265155ms","start":"2026-02-26T06:45:44.779986Z","end":"2026-02-26T06:45:44.890251Z","steps":["trace[1933798669] 'read index received' (duration: 110.064391ms)","trace[1933798669] 'applied index is now lower than readState.Index' (duration: 196.344µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:45:44.890578Z","caller":"traceutil/trace.go:171","msg":"trace[27951230] transaction","detail":"{read_only:false; response_revision:13695; number_of_response:1; }","duration":"124.754263ms","start":"2026-02-26T06:45:44.765809Z","end":"2026-02-26T06:45:44.890563Z","steps":["trace[27951230] 'process raft request' (duration: 124.300554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:45:44.89167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.654813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-02-26T06:45:44.891793Z","caller":"traceutil/trace.go:171","msg":"trace[1977607739] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13695; }","duration":"111.837097ms","start":"2026-02-26T06:45:44.779921Z","end":"2026-02-26T06:45:44.891758Z","steps":["trace[1977607739] 'agreement among raft nodes before linearized reading' (duration: 111.560301ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:47:45.200529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12077} {"level":"info","ts":"2026-02-26T06:47:45.276406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12077,"took":"71.291729ms","hash":1173385926,"current-db-size-bytes":41689088,"current-db-size":"42 MB","current-db-size-in-use-bytes":28311552,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T06:47:45.276918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1173385926,"revision":12077,"compact-revision":10200} {"level":"info","ts":"2026-02-26T06:51:21.876828Z","caller":"traceutil/trace.go:171","msg":"trace[1583328544] transaction","detail":"{read_only:false; response_revision:16211; number_of_response:1; }","duration":"110.671994ms","start":"2026-02-26T06:51:21.766129Z","end":"2026-02-26T06:51:21.876801Z","steps":["trace[1583328544] 'process raft request' (duration: 110.501891ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:21.90556Z","caller":"traceutil/trace.go:171","msg":"trace[1559164214] transaction","detail":"{read_only:false; response_revision:16212; number_of_response:1; }","duration":"136.394996ms","start":"2026-02-26T06:51:21.769118Z","end":"2026-02-26T06:51:21.905512Z","steps":["trace[1559164214] 'process raft request' (duration: 136.139051ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:21.905706Z","caller":"traceutil/trace.go:171","msg":"trace[370746828] transaction","detail":"{read_only:false; response_revision:16213; number_of_response:1; }","duration":"136.250683ms","start":"2026-02-26T06:51:21.769415Z","end":"2026-02-26T06:51:21.905666Z","steps":["trace[370746828] 'process raft request' (duration: 136.004098ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:26.836165Z","caller":"traceutil/trace.go:171","msg":"trace[908361984] transaction","detail":"{read_only:false; response_revision:16251; number_of_response:1; }","duration":"186.462151ms","start":"2026-02-26T06:51:26.649659Z","end":"2026-02-26T06:51:26.836121Z","steps":["trace[908361984] 'process raft request' (duration: 186.201036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:51:27.289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.079255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:51:27.289461Z","caller":"traceutil/trace.go:171","msg":"trace[748246211] transaction","detail":"{read_only:false; response_revision:16252; number_of_response:1; }","duration":"448.817266ms","start":"2026-02-26T06:51:26.840591Z","end":"2026-02-26T06:51:27.289408Z","steps":["trace[748246211] 'process raft request' (duration: 146.19016ms)","trace[748246211] 'compare' (duration: 301.853641ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:51:27.289629Z","caller":"traceutil/trace.go:171","msg":"trace[2040997608] linearizableReadLoop","detail":"{readStateIndex:16820; appliedIndex:16818; }","duration":"120.373467ms","start":"2026-02-26T06:51:27.169216Z","end":"2026-02-26T06:51:27.28959Z","steps":["trace[2040997608] 'read index received' (duration: 119.287756ms)","trace[2040997608] 'applied index is now lower than readState.Index' (duration: 1.083881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:51:27.289653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:51:26.840571Z","time spent":"448.977769ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:51:27.290007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.920118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:51:27.28999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.569701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:51:27.290053Z","caller":"traceutil/trace.go:171","msg":"trace[2020114957] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:16253; }","duration":"120.01294ms","start":"2026-02-26T06:51:27.170023Z","end":"2026-02-26T06:51:27.290036Z","steps":["trace[2020114957] 'agreement among raft nodes before linearized reading' (duration: 119.745685ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:27.290076Z","caller":"traceutil/trace.go:171","msg":"trace[209220056] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:0; response_revision:16253; }","duration":"120.939548ms","start":"2026-02-26T06:51:27.169114Z","end":"2026-02-26T06:51:27.290054Z","steps":["trace[209220056] 'agreement among raft nodes before linearized reading' (duration: 120.595892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:51:27.290062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.775534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:51:27.29015Z","caller":"traceutil/trace.go:171","msg":"trace[2007452449] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:16253; }","duration":"120.980939ms","start":"2026-02-26T06:51:27.169145Z","end":"2026-02-26T06:51:27.290126Z","steps":["trace[2007452449] 'agreement among raft nodes before linearized reading' (duration: 120.615682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:51:27.29017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.880446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:51:27.290228Z","caller":"traceutil/trace.go:171","msg":"trace[1615437861] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:16253; }","duration":"120.984928ms","start":"2026-02-26T06:51:27.169228Z","end":"2026-02-26T06:51:27.290212Z","steps":["trace[1615437861] 'agreement among raft nodes before linearized reading' (duration: 120.906417ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:27.289754Z","caller":"traceutil/trace.go:171","msg":"trace[38718469] transaction","detail":"{read_only:false; response_revision:16253; number_of_response:1; }","duration":"151.349734ms","start":"2026-02-26T06:51:27.138368Z","end":"2026-02-26T06:51:27.289718Z","steps":["trace[38718469] 'process raft request' (duration: 151.125039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:51:41.465125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.128201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:1 size:5081"} {"level":"info","ts":"2026-02-26T06:51:41.465239Z","caller":"traceutil/trace.go:171","msg":"trace[1941084965] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:1; response_revision:16414; }","duration":"126.274154ms","start":"2026-02-26T06:51:41.338941Z","end":"2026-02-26T06:51:41.465215Z","steps":["trace[1941084965] 'range keys from in-memory index tree' (duration: 125.902237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:51:41.465293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.864126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-26T06:51:41.465293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.433867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T06:51:41.465388Z","caller":"traceutil/trace.go:171","msg":"trace[831828929] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:16414; }","duration":"130.011549ms","start":"2026-02-26T06:51:41.335355Z","end":"2026-02-26T06:51:41.465366Z","steps":["trace[831828929] 'range keys from in-memory index tree' (duration: 129.735862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:51:41.465381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.58395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-26T06:51:41.465411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.334505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:1 size:5081"} {"level":"info","ts":"2026-02-26T06:51:41.465404Z","caller":"traceutil/trace.go:171","msg":"trace[1705779688] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:16414; }","duration":"129.58499ms","start":"2026-02-26T06:51:41.335807Z","end":"2026-02-26T06:51:41.465392Z","steps":["trace[1705779688] 'range keys from in-memory index tree' (duration: 129.273043ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:41.465581Z","caller":"traceutil/trace.go:171","msg":"trace[1380405626] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:16414; }","duration":"131.790094ms","start":"2026-02-26T06:51:41.333725Z","end":"2026-02-26T06:51:41.465515Z","steps":["trace[1380405626] 'range keys from in-memory index tree' (duration: 131.488508ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:41.465636Z","caller":"traceutil/trace.go:171","msg":"trace[1396202915] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:1; response_revision:16414; }","duration":"126.592321ms","start":"2026-02-26T06:51:41.338973Z","end":"2026-02-26T06:51:41.465565Z","steps":["trace[1396202915] 'range keys from in-memory index tree' (duration: 126.03645ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:41.465629Z","caller":"traceutil/trace.go:171","msg":"trace[1235819103] linearizableReadLoop","detail":"{readStateIndex:16990; appliedIndex:16989; }","duration":"107.798316ms","start":"2026-02-26T06:51:41.357775Z","end":"2026-02-26T06:51:41.465573Z","steps":["trace[1235819103] 'read index received' (duration: 102.616193ms)","trace[1235819103] 'applied index is now lower than readState.Index' (duration: 5.180453ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:51:41.46572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.862866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T06:51:41.465762Z","caller":"traceutil/trace.go:171","msg":"trace[725408810] transaction","detail":"{read_only:false; response_revision:16415; number_of_response:1; }","duration":"118.826887ms","start":"2026-02-26T06:51:41.346916Z","end":"2026-02-26T06:51:41.465743Z","steps":["trace[725408810] 'process raft request' (duration: 113.545762ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:41.465795Z","caller":"traceutil/trace.go:171","msg":"trace[1288435306] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:16414; }","duration":"130.01218ms","start":"2026-02-26T06:51:41.335763Z","end":"2026-02-26T06:51:41.465775Z","steps":["trace[1288435306] 'range keys from in-memory index tree' (duration: 129.632511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:51:41.465815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.450427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-26T06:51:41.465947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.171555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-02-26T06:51:41.465959Z","caller":"traceutil/trace.go:171","msg":"trace[1211479068] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:16414; }","duration":"131.679702ms","start":"2026-02-26T06:51:41.334249Z","end":"2026-02-26T06:51:41.465929Z","steps":["trace[1211479068] 'range keys from in-memory index tree' (duration: 131.246102ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:51:41.465993Z","caller":"traceutil/trace.go:171","msg":"trace[2057001773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16415; }","duration":"108.242016ms","start":"2026-02-26T06:51:41.357738Z","end":"2026-02-26T06:51:41.46598Z","steps":["trace[2057001773] 'agreement among raft nodes before linearized reading' (duration: 108.044232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:51:41.465864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.975009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T06:51:41.466186Z","caller":"traceutil/trace.go:171","msg":"trace[551940051] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:16414; }","duration":"130.400607ms","start":"2026-02-26T06:51:41.335763Z","end":"2026-02-26T06:51:41.466163Z","steps":["trace[551940051] 'range keys from in-memory index tree' (duration: 129.524079ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:52:45.207844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14520} {"level":"info","ts":"2026-02-26T06:52:45.300542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14520,"took":"89.256502ms","hash":2843397559,"current-db-size-bytes":41689088,"current-db-size":"42 MB","current-db-size-in-use-bytes":28594176,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-26T06:52:45.300685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2843397559,"revision":14520,"compact-revision":12077} {"level":"info","ts":"2026-02-26T06:57:45.214483Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16783} {"level":"info","ts":"2026-02-26T06:57:45.308413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16783,"took":"91.47285ms","hash":2268792505,"current-db-size-bytes":41689088,"current-db-size":"42 MB","current-db-size-in-use-bytes":24465408,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-26T06:57:45.308568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2268792505,"revision":16783,"compact-revision":14520}