{"level":"warn","ts":"2026-04-20T17:42:44.510325Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T17:42:44.510426Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T17:42:44.510508Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T17:42:44.510525Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-20T17:42:44.510555Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T17:42:44.511437Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-04-20T17:42:44.511534Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T17:42:44.51519Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.396254ms"} {"level":"info","ts":"2026-04-20T17:42:44.525724Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-04-20T17:42:44.525848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T17:42:44.525882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-04-20T17:42:44.525902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T17:42:44.525918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-04-20T17:42:44.525972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-04-20T17:42:44.5387Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T17:42:44.540349Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T17:42:44.541611Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T17:42:44.544154Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T17:42:44.544367Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T17:42:44.544398Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T17:42:44.544625Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T17:42:44.544648Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T17:42:44.545361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-04-20T17:42:44.546079Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-20T17:42:44.548417Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T17:42:44.548611Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-20T17:42:44.548686Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-20T17:42:44.548642Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T17:42:44.548665Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T17:42:44.726567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T17:42:44.726627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T17:42:44.726661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-04-20T17:42:44.726679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-04-20T17:42:44.72669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-20T17:42:44.726706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-04-20T17:42:44.726725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-20T17:42:44.727841Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T17:42:44.727974Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T17:42:44.728048Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T17:42:44.728202Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:42:44.728494Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T17:42:44.728549Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T17:42:44.729272Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:42:44.7294Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:42:44.729438Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:42:44.731173Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"info","ts":"2026-04-20T17:42:44.73167Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T17:43:11.077135Z","caller":"traceutil/trace.go:171","msg":"trace[605221480] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"142.182455ms","start":"2026-04-20T17:43:10.93492Z","end":"2026-04-20T17:43:11.077103Z","steps":["trace[605221480] 'process raft request' (duration: 141.995551ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:11.082408Z","caller":"traceutil/trace.go:171","msg":"trace[636942147] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"146.009877ms","start":"2026-04-20T17:43:10.936382Z","end":"2026-04-20T17:43:11.082392Z","steps":["trace[636942147] 'process raft request' (duration: 145.573587ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:11.082755Z","caller":"traceutil/trace.go:171","msg":"trace[2136746053] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"145.765881ms","start":"2026-04-20T17:43:10.936958Z","end":"2026-04-20T17:43:11.082724Z","steps":["trace[2136746053] 'process raft request' (duration: 145.066785ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:17.229464Z","caller":"traceutil/trace.go:171","msg":"trace[1178607317] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"118.873833ms","start":"2026-04-20T17:43:17.11055Z","end":"2026-04-20T17:43:17.229424Z","steps":["trace[1178607317] 'process raft request' (duration: 118.797202ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:17.229791Z","caller":"traceutil/trace.go:171","msg":"trace[1684747402] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"119.598958ms","start":"2026-04-20T17:43:17.110176Z","end":"2026-04-20T17:43:17.229775Z","steps":["trace[1684747402] 'process raft request' (duration: 104.807665ms)","trace[1684747402] 'compare' (duration: 14.1811ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:43:17.913451Z","caller":"traceutil/trace.go:171","msg":"trace[189953162] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"203.90681ms","start":"2026-04-20T17:43:17.709486Z","end":"2026-04-20T17:43:17.913393Z","steps":["trace[189953162] 'read index received' (duration: 118.227569ms)","trace[189953162] 'applied index is now lower than readState.Index' (duration: 85.677011ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:43:17.91365Z","caller":"traceutil/trace.go:171","msg":"trace[157325361] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"397.393969ms","start":"2026-04-20T17:43:17.516186Z","end":"2026-04-20T17:43:17.91358Z","steps":["trace[157325361] 'process raft request' (duration: 311.558935ms)","trace[157325361] 'compare' (duration: 85.020837ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:43:17.913729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.224806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T17:43:17.913839Z","caller":"traceutil/trace.go:171","msg":"trace[740659512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"204.40026ms","start":"2026-04-20T17:43:17.709423Z","end":"2026-04-20T17:43:17.913823Z","steps":["trace[740659512] 'agreement among raft nodes before linearized reading' (duration: 204.111204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:43:17.914263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:43:17.516152Z","time spent":"397.589854ms","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:47:02.160959Z","caller":"traceutil/trace.go:171","msg":"trace[1085774866] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"122.370637ms","start":"2026-04-20T17:47:02.03852Z","end":"2026-04-20T17:47:02.16089Z","steps":["trace[1085774866] 'process raft request' (duration: 121.825177ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:02.161296Z","caller":"traceutil/trace.go:171","msg":"trace[2058826941] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"112.960229ms","start":"2026-04-20T17:47:02.04831Z","end":"2026-04-20T17:47:02.16127Z","steps":["trace[2058826941] 'process raft request' (duration: 112.483811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:02.161587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.811751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:47:02.161661Z","caller":"traceutil/trace.go:171","msg":"trace[643051248] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1856; }","duration":"108.933912ms","start":"2026-04-20T17:47:02.052713Z","end":"2026-04-20T17:47:02.161647Z","steps":["trace[643051248] 'agreement among raft nodes before linearized reading' (duration: 108.82205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:02.234589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.545894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:986"} {"level":"info","ts":"2026-04-20T17:47:02.234676Z","caller":"traceutil/trace.go:171","msg":"trace[667310894] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:1857; }","duration":"153.647406ms","start":"2026-04-20T17:47:02.081009Z","end":"2026-04-20T17:47:02.234656Z","steps":["trace[667310894] 'agreement among raft nodes before linearized reading' (duration: 153.444322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:02.234718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.655206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:971"} {"level":"info","ts":"2026-04-20T17:47:02.234811Z","caller":"traceutil/trace.go:171","msg":"trace[1363316854] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1857; }","duration":"153.79619ms","start":"2026-04-20T17:47:02.08097Z","end":"2026-04-20T17:47:02.234766Z","steps":["trace[1363316854] 'agreement among raft nodes before linearized reading' (duration: 153.518394ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:02.234943Z","caller":"traceutil/trace.go:171","msg":"trace[2115084894] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"139.930749ms","start":"2026-04-20T17:47:02.094989Z","end":"2026-04-20T17:47:02.23492Z","steps":["trace[2115084894] 'process raft request' (duration: 139.044571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:02.235112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.90558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:47:02.235215Z","caller":"traceutil/trace.go:171","msg":"trace[1205050862] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1857; }","duration":"147.068144ms","start":"2026-04-20T17:47:02.088125Z","end":"2026-04-20T17:47:02.235193Z","steps":["trace[1205050862] 'agreement among raft nodes before linearized reading' (duration: 146.87266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:02.234633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.575596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:1 size:968"} {"level":"info","ts":"2026-04-20T17:47:02.236104Z","caller":"traceutil/trace.go:171","msg":"trace[1648650000] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1857; }","duration":"154.071144ms","start":"2026-04-20T17:47:02.08202Z","end":"2026-04-20T17:47:02.236091Z","steps":["trace[1648650000] 'agreement among raft nodes before linearized reading' (duration: 152.430523ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:02.615629Z","caller":"traceutil/trace.go:171","msg":"trace[1509142883] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"189.222877ms","start":"2026-04-20T17:47:02.426361Z","end":"2026-04-20T17:47:02.615584Z","steps":["trace[1509142883] 'process raft request' (duration: 188.964562ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:02.615809Z","caller":"traceutil/trace.go:171","msg":"trace[1513984885] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"182.483182ms","start":"2026-04-20T17:47:02.433306Z","end":"2026-04-20T17:47:02.61579Z","steps":["trace[1513984885] 'process raft request' (duration: 182.270307ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:02.615814Z","caller":"traceutil/trace.go:171","msg":"trace[193363319] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"189.36091ms","start":"2026-04-20T17:47:02.42642Z","end":"2026-04-20T17:47:02.615781Z","steps":["trace[193363319] 'process raft request' (duration: 189.079434ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:02.615709Z","caller":"traceutil/trace.go:171","msg":"trace[717674753] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"167.261923ms","start":"2026-04-20T17:47:02.448422Z","end":"2026-04-20T17:47:02.615684Z","steps":["trace[717674753] 'process raft request' (duration: 167.216212ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:02.617938Z","caller":"traceutil/trace.go:171","msg":"trace[1400375549] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1966; }","duration":"155.109694ms","start":"2026-04-20T17:47:02.462804Z","end":"2026-04-20T17:47:02.617914Z","steps":["trace[1400375549] 'read index received' (duration: 152.466324ms)","trace[1400375549] 'applied index is now lower than readState.Index' (duration: 2.64234ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:47:02.618094Z","caller":"traceutil/trace.go:171","msg":"trace[1913215230] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"136.507003ms","start":"2026-04-20T17:47:02.481572Z","end":"2026-04-20T17:47:02.618079Z","steps":["trace[1913215230] 'process raft request' (duration: 136.233758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:02.618344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.522911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130214"} {"level":"info","ts":"2026-04-20T17:47:02.618378Z","caller":"traceutil/trace.go:171","msg":"trace[409841678] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1901; }","duration":"155.600973ms","start":"2026-04-20T17:47:02.462769Z","end":"2026-04-20T17:47:02.61837Z","steps":["trace[409841678] 'agreement among raft nodes before linearized reading' (duration: 155.384939ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:02.618123Z","caller":"traceutil/trace.go:171","msg":"trace[1784368856] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"159.441326ms","start":"2026-04-20T17:47:02.458663Z","end":"2026-04-20T17:47:02.618105Z","steps":["trace[1784368856] 'process raft request' (duration: 158.957227ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:09.198018Z","caller":"traceutil/trace.go:171","msg":"trace[1612492258] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"350.439136ms","start":"2026-04-20T17:47:08.847554Z","end":"2026-04-20T17:47:09.197993Z","steps":["trace[1612492258] 'process raft request' (duration: 350.265033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:09.198402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:47:08.847533Z","time spent":"350.57288ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11811,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:47:09.34167Z","caller":"traceutil/trace.go:171","msg":"trace[2086813260] linearizableReadLoop","detail":"{readStateIndex:2155; appliedIndex:2153; }","duration":"277.165454ms","start":"2026-04-20T17:47:09.064472Z","end":"2026-04-20T17:47:09.341638Z","steps":["trace[2086813260] 'read index received' (duration: 133.349233ms)","trace[2086813260] 'applied index is now lower than readState.Index' (duration: 143.815301ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:47:09.341811Z","caller":"traceutil/trace.go:171","msg":"trace[2029694652] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"308.7159ms","start":"2026-04-20T17:47:09.033051Z","end":"2026-04-20T17:47:09.341767Z","steps":["trace[2029694652] 'process raft request' (duration: 241.660457ms)","trace[2029694652] 'compare' (duration: 66.643525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:47:09.341924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.434481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T17:47:09.34198Z","caller":"traceutil/trace.go:171","msg":"trace[1885040162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2079; }","duration":"277.539423ms","start":"2026-04-20T17:47:09.064417Z","end":"2026-04-20T17:47:09.341957Z","steps":["trace[1885040162] 'agreement among raft nodes before linearized reading' (duration: 277.371219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:09.341992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:47:09.033022Z","time spent":"308.886275ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:47:09.342021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.632725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T17:47:09.34211Z","caller":"traceutil/trace.go:171","msg":"trace[2076765386] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2079; }","duration":"128.762168ms","start":"2026-04-20T17:47:09.213324Z","end":"2026-04-20T17:47:09.342086Z","steps":["trace[2076765386] 'agreement among raft nodes before linearized reading' (duration: 128.613385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:18.642127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.942908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:5 size:58731"} {"level":"info","ts":"2026-04-20T17:47:18.642214Z","caller":"traceutil/trace.go:171","msg":"trace[582250209] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:5; response_revision:2288; }","duration":"122.063551ms","start":"2026-04-20T17:47:18.520124Z","end":"2026-04-20T17:47:18.642187Z","steps":["trace[582250209] 'range keys from in-memory index tree' (duration: 121.740265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:38.67119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.407747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:47:38.671415Z","caller":"traceutil/trace.go:171","msg":"trace[524146353] linearizableReadLoop","detail":"{readStateIndex:2501; appliedIndex:2499; }","duration":"148.680058ms","start":"2026-04-20T17:47:38.522713Z","end":"2026-04-20T17:47:38.671393Z","steps":["trace[524146353] 'read index received' (duration: 39.910465ms)","trace[524146353] 'applied index is now lower than readState.Index' (duration: 108.768473ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:47:38.671501Z","caller":"traceutil/trace.go:171","msg":"trace[579696284] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"164.110556ms","start":"2026-04-20T17:47:38.507381Z","end":"2026-04-20T17:47:38.671491Z","steps":["trace[579696284] 'process raft request' (duration: 163.936523ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:38.671618Z","caller":"traceutil/trace.go:171","msg":"trace[1860046936] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"165.748728ms","start":"2026-04-20T17:47:38.505843Z","end":"2026-04-20T17:47:38.671592Z","steps":["trace[1860046936] 'process raft request' (duration: 56.72877ms)","trace[1860046936] 'compare' (duration: 108.302615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:47:38.671712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.993734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T17:47:38.671758Z","caller":"traceutil/trace.go:171","msg":"trace[329890253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2416; }","duration":"149.038635ms","start":"2026-04-20T17:47:38.522707Z","end":"2026-04-20T17:47:38.671746Z","steps":["trace[329890253] 'agreement among raft nodes before linearized reading' (duration: 148.909322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:38.671822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.477601ms","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-04-20T17:47:38.67188Z","caller":"traceutil/trace.go:171","msg":"trace[1704638654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2416; }","duration":"143.547662ms","start":"2026-04-20T17:47:38.528316Z","end":"2026-04-20T17:47:38.671864Z","steps":["trace[1704638654] 'agreement among raft nodes before linearized reading' (duration: 143.41492ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:40.302399Z","caller":"traceutil/trace.go:171","msg":"trace[615113052] transaction","detail":"{read_only:false; response_revision:2429; number_of_response:1; }","duration":"126.709497ms","start":"2026-04-20T17:47:40.175601Z","end":"2026-04-20T17:47:40.30231Z","steps":["trace[615113052] 'process raft request' (duration: 86.946165ms)","trace[615113052] 'compare' (duration: 39.512178ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:47:40.302502Z","caller":"traceutil/trace.go:171","msg":"trace[568511350] transaction","detail":"{read_only:false; response_revision:2430; number_of_response:1; }","duration":"125.347892ms","start":"2026-04-20T17:47:40.177126Z","end":"2026-04-20T17:47:40.302474Z","steps":["trace[568511350] 'process raft request' (duration: 125.067006ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:40.810079Z","caller":"traceutil/trace.go:171","msg":"trace[1495118489] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"131.243773ms","start":"2026-04-20T17:47:40.678809Z","end":"2026-04-20T17:47:40.810052Z","steps":["trace[1495118489] 'process raft request' (duration: 131.09286ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:40.819539Z","caller":"traceutil/trace.go:171","msg":"trace[1136937994] linearizableReadLoop","detail":"{readStateIndex:2518; appliedIndex:2516; }","duration":"130.860226ms","start":"2026-04-20T17:47:40.688654Z","end":"2026-04-20T17:47:40.819514Z","steps":["trace[1136937994] 'read index received' (duration: 121.352898ms)","trace[1136937994] 'applied index is now lower than readState.Index' (duration: 9.506098ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:47:40.819592Z","caller":"traceutil/trace.go:171","msg":"trace[390390277] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"140.355713ms","start":"2026-04-20T17:47:40.679217Z","end":"2026-04-20T17:47:40.819573Z","steps":["trace[390390277] 'process raft request' (duration: 140.138389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:40.819831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.514034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T17:47:40.819877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.199742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T17:47:40.819915Z","caller":"traceutil/trace.go:171","msg":"trace[622656384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2433; }","duration":"111.641797ms","start":"2026-04-20T17:47:40.708255Z","end":"2026-04-20T17:47:40.819897Z","steps":["trace[622656384] 'agreement among raft nodes before linearized reading' (duration: 111.415602ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:40.819945Z","caller":"traceutil/trace.go:171","msg":"trace[737089885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2433; }","duration":"131.303914ms","start":"2026-04-20T17:47:40.688629Z","end":"2026-04-20T17:47:40.819932Z","steps":["trace[737089885] 'agreement among raft nodes before linearized reading' (duration: 131.038919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:41.249587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.098597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:47:41.249671Z","caller":"traceutil/trace.go:171","msg":"trace[350815022] linearizableReadLoop","detail":"{readStateIndex:2520; appliedIndex:2519; }","duration":"409.773859ms","start":"2026-04-20T17:47:40.839884Z","end":"2026-04-20T17:47:41.249658Z","steps":["trace[350815022] 'read index received' (duration: 77.459968ms)","trace[350815022] 'applied index is now lower than readState.Index' (duration: 332.312901ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:47:41.249729Z","caller":"traceutil/trace.go:171","msg":"trace[16640027] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"425.322939ms","start":"2026-04-20T17:47:40.824381Z","end":"2026-04-20T17:47:41.249704Z","steps":["trace[16640027] 'process raft request' (duration: 93.029268ms)","trace[16640027] 'compare' (duration: 331.954164ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:47:41.249851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:47:40.824342Z","time spent":"425.446151ms","remote":"127.0.0.1:44582","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-04-20T17:47:41.250195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.312668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qh68g\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-04-20T17:47:41.250226Z","caller":"traceutil/trace.go:171","msg":"trace[1148462158] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qh68g; range_end:; response_count:1; response_revision:2435; }","duration":"410.363379ms","start":"2026-04-20T17:47:40.839853Z","end":"2026-04-20T17:47:41.250216Z","steps":["trace[1148462158] 'agreement among raft nodes before linearized reading' (duration: 410.256597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:41.250244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:47:40.839812Z","time spent":"410.42789ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6465,"request content":"key:\"/registry/pods/openstack/keepalived-qh68g\" "} {"level":"warn","ts":"2026-04-20T17:47:41.2506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.829572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:47:41.250728Z","caller":"traceutil/trace.go:171","msg":"trace[1325567435] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2435; }","duration":"186.986885ms","start":"2026-04-20T17:47:41.063703Z","end":"2026-04-20T17:47:41.25069Z","steps":["trace[1325567435] 'agreement among raft nodes before linearized reading' (duration: 186.805511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:41.250615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.279006ms","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-04-20T17:47:41.251446Z","caller":"traceutil/trace.go:171","msg":"trace[11116153] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2435; }","duration":"382.142581ms","start":"2026-04-20T17:47:40.86928Z","end":"2026-04-20T17:47:41.251422Z","steps":["trace[11116153] 'agreement among raft nodes before linearized reading' (duration: 381.219274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:41.251518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:47:40.869263Z","time spent":"382.233833ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":34,"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-04-20T17:48:00.321492Z","caller":"traceutil/trace.go:171","msg":"trace[1830416449] transaction","detail":"{read_only:false; response_revision:3051; number_of_response:1; }","duration":"106.805636ms","start":"2026-04-20T17:48:00.214625Z","end":"2026-04-20T17:48:00.32143Z","steps":["trace[1830416449] 'process raft request' (duration: 104.540823ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:00.70658Z","caller":"traceutil/trace.go:171","msg":"trace[2008647505] transaction","detail":"{read_only:false; response_revision:3058; number_of_response:1; }","duration":"143.605974ms","start":"2026-04-20T17:48:00.562943Z","end":"2026-04-20T17:48:00.706549Z","steps":["trace[2008647505] 'process raft request' (duration: 143.103965ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:00.942477Z","caller":"traceutil/trace.go:171","msg":"trace[723784079] linearizableReadLoop","detail":"{readStateIndex:3183; appliedIndex:3182; }","duration":"190.334057ms","start":"2026-04-20T17:48:00.752108Z","end":"2026-04-20T17:48:00.942443Z","steps":["trace[723784079] 'read index received' (duration: 189.978521ms)","trace[723784079] 'applied index is now lower than readState.Index' (duration: 354.226µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:48:00.942934Z","caller":"traceutil/trace.go:171","msg":"trace[1497160451] transaction","detail":"{read_only:false; response_revision:3059; number_of_response:1; }","duration":"227.86634ms","start":"2026-04-20T17:48:00.715047Z","end":"2026-04-20T17:48:00.942913Z","steps":["trace[1497160451] 'process raft request' (duration: 227.140556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:00.943339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.199944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:15 size:333056"} {"level":"info","ts":"2026-04-20T17:48:00.943438Z","caller":"traceutil/trace.go:171","msg":"trace[1484581213] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:15; response_revision:3059; }","duration":"191.322916ms","start":"2026-04-20T17:48:00.752092Z","end":"2026-04-20T17:48:00.943415Z","steps":["trace[1484581213] 'agreement among raft nodes before linearized reading' (duration: 190.914359ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:01.084518Z","caller":"traceutil/trace.go:171","msg":"trace[820180992] linearizableReadLoop","detail":"{readStateIndex:3186; appliedIndex:3183; }","duration":"120.044053ms","start":"2026-04-20T17:48:00.964442Z","end":"2026-04-20T17:48:01.084486Z","steps":["trace[820180992] 'read index received' (duration: 70.09966ms)","trace[820180992] 'applied index is now lower than readState.Index' (duration: 49.942823ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:48:01.084927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.522661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-vnc-ca\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:48:01.084988Z","caller":"traceutil/trace.go:171","msg":"trace[714677846] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/libvirt-vnc-ca; range_end:; response_count:0; response_revision:3062; }","duration":"113.641513ms","start":"2026-04-20T17:48:00.971327Z","end":"2026-04-20T17:48:01.084969Z","steps":["trace[714677846] 'agreement among raft nodes before linearized reading' (duration: 113.514691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:01.085111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.645425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:15 size:333056"} {"level":"info","ts":"2026-04-20T17:48:01.085204Z","caller":"traceutil/trace.go:171","msg":"trace[1201269821] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:15; response_revision:3062; }","duration":"120.756817ms","start":"2026-04-20T17:48:00.964425Z","end":"2026-04-20T17:48:01.085182Z","steps":["trace[1201269821] 'agreement among raft nodes before linearized reading' (duration: 120.319939ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:01.085504Z","caller":"traceutil/trace.go:171","msg":"trace[1670525467] transaction","detail":"{read_only:false; response_revision:3061; number_of_response:1; }","duration":"132.269661ms","start":"2026-04-20T17:48:00.953219Z","end":"2026-04-20T17:48:01.085489Z","steps":["trace[1670525467] 'process raft request' (duration: 90.436519ms)","trace[1670525467] 'compare' (duration: 40.512668ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:48:01.085533Z","caller":"traceutil/trace.go:171","msg":"trace[139253942] transaction","detail":"{read_only:false; response_revision:3062; number_of_response:1; }","duration":"132.16657ms","start":"2026-04-20T17:48:00.953351Z","end":"2026-04-20T17:48:01.085517Z","steps":["trace[139253942] 'process raft request' (duration: 131.07386ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:01.331418Z","caller":"traceutil/trace.go:171","msg":"trace[1495167778] transaction","detail":"{read_only:false; response_revision:3063; number_of_response:1; }","duration":"233.270949ms","start":"2026-04-20T17:48:01.098124Z","end":"2026-04-20T17:48:01.331395Z","steps":["trace[1495167778] 'process raft request' (duration: 220.557212ms)","trace[1495167778] 'compare' (duration: 12.600335ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:49:00.13431Z","caller":"traceutil/trace.go:171","msg":"trace[652494921] linearizableReadLoop","detail":"{readStateIndex:3930; appliedIndex:3929; }","duration":"159.651038ms","start":"2026-04-20T17:48:59.974567Z","end":"2026-04-20T17:49:00.134218Z","steps":["trace[652494921] 'read index received' (duration: 91.40439ms)","trace[652494921] 'applied index is now lower than readState.Index' (duration: 68.245507ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:49:00.134327Z","caller":"traceutil/trace.go:171","msg":"trace[10037850] transaction","detail":"{read_only:false; response_revision:3783; number_of_response:1; }","duration":"189.872212ms","start":"2026-04-20T17:48:59.944384Z","end":"2026-04-20T17:49:00.134256Z","steps":["trace[10037850] 'process raft request' (duration: 121.721476ms)","trace[10037850] 'compare' (duration: 67.82422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:49:00.134545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.958423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T17:49:00.134749Z","caller":"traceutil/trace.go:171","msg":"trace[841168512] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3783; }","duration":"160.238498ms","start":"2026-04-20T17:48:59.97449Z","end":"2026-04-20T17:49:00.134729Z","steps":["trace[841168512] 'agreement among raft nodes before linearized reading' (duration: 159.951743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.217017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.968465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:49:06.21715Z","caller":"traceutil/trace.go:171","msg":"trace[1845918458] transaction","detail":"{read_only:false; response_revision:3815; number_of_response:1; }","duration":"288.334905ms","start":"2026-04-20T17:49:05.928793Z","end":"2026-04-20T17:49:06.217128Z","steps":["trace[1845918458] 'process raft request' (duration: 38.129196ms)","trace[1845918458] 'compare' (duration: 249.761161ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:49:06.269232Z","caller":"traceutil/trace.go:171","msg":"trace[1726888964] transaction","detail":"{read_only:false; response_revision:3816; number_of_response:1; }","duration":"340.096119ms","start":"2026-04-20T17:49:05.929115Z","end":"2026-04-20T17:49:06.269211Z","steps":["trace[1726888964] 'process raft request' (duration: 339.953317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.269374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:05.929086Z","time spent":"340.217362ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":896,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:49:06.269498Z","caller":"traceutil/trace.go:171","msg":"trace[1660129562] transaction","detail":"{read_only:false; response_revision:3817; number_of_response:1; }","duration":"340.339394ms","start":"2026-04-20T17:49:05.929114Z","end":"2026-04-20T17:49:06.269454Z","steps":["trace[1660129562] 'process raft request' (duration: 340.061079ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:06.269557Z","caller":"traceutil/trace.go:171","msg":"trace[609672057] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"340.386425ms","start":"2026-04-20T17:49:05.929144Z","end":"2026-04-20T17:49:06.26953Z","steps":["trace[609672057] 'process raft request' (duration: 340.200972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.269647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:05.929088Z","time spent":"340.487427ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":966,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:49:06.269673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:05.92912Z","time spent":"340.491917ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1483,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:49:06.269785Z","caller":"traceutil/trace.go:171","msg":"trace[208973925] transaction","detail":"{read_only:false; response_revision:3819; number_of_response:1; }","duration":"340.026659ms","start":"2026-04-20T17:49:05.929747Z","end":"2026-04-20T17:49:06.269774Z","steps":["trace[208973925] 'process raft request' (duration: 339.677502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.269855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:05.929733Z","time spent":"340.09123ms","remote":"127.0.0.1:44590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1617,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:49:06.269914Z","caller":"traceutil/trace.go:171","msg":"trace[2124442901] transaction","detail":"{read_only:false; response_revision:3821; number_of_response:1; }","duration":"336.233516ms","start":"2026-04-20T17:49:05.933669Z","end":"2026-04-20T17:49:06.269903Z","steps":["trace[2124442901] 'process raft request' (duration: 335.932731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.269969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:05.933657Z","time spent":"336.286247ms","remote":"127.0.0.1:44786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:49:06.270015Z","caller":"traceutil/trace.go:171","msg":"trace[2077444904] linearizableReadLoop","detail":"{readStateIndex:3970; appliedIndex:3963; }","duration":"328.54157ms","start":"2026-04-20T17:49:05.941452Z","end":"2026-04-20T17:49:06.269993Z","steps":["trace[2077444904] 'read index received' (duration: 25.384833ms)","trace[2077444904] 'applied index is now lower than readState.Index' (duration: 303.154417ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:49:06.270014Z","caller":"traceutil/trace.go:171","msg":"trace[485104925] transaction","detail":"{read_only:false; response_revision:3820; number_of_response:1; }","duration":"339.783444ms","start":"2026-04-20T17:49:05.93019Z","end":"2026-04-20T17:49:06.269974Z","steps":["trace[485104925] 'process raft request' (duration: 339.286334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.270161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:05.930184Z","time spent":"339.896516ms","remote":"127.0.0.1:44590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:49:06.270182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.729043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-20T17:49:06.270237Z","caller":"traceutil/trace.go:171","msg":"trace[1153197702] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:3821; }","duration":"328.805235ms","start":"2026-04-20T17:49:05.941406Z","end":"2026-04-20T17:49:06.270211Z","steps":["trace[1153197702] 'agreement among raft nodes before linearized reading' (duration: 328.670562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.270226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.677203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T17:49:06.270258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:05.941388Z","time spent":"328.865546ms","remote":"127.0.0.1:44488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-04-20T17:49:06.270294Z","caller":"traceutil/trace.go:171","msg":"trace[820428956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3821; }","duration":"112.760025ms","start":"2026-04-20T17:49:06.157496Z","end":"2026-04-20T17:49:06.270256Z","steps":["trace[820428956] 'agreement among raft nodes before linearized reading' (duration: 112.612902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.647039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.342038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:49:06.647151Z","caller":"traceutil/trace.go:171","msg":"trace[1266043952] linearizableReadLoop","detail":"{readStateIndex:3971; appliedIndex:3970; }","duration":"350.310554ms","start":"2026-04-20T17:49:06.296824Z","end":"2026-04-20T17:49:06.647135Z","steps":["trace[1266043952] 'read index received' (duration: 73.901745ms)","trace[1266043952] 'applied index is now lower than readState.Index' (duration: 276.407459ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:49:06.647255Z","caller":"traceutil/trace.go:171","msg":"trace[169561190] transaction","detail":"{read_only:false; response_revision:3822; number_of_response:1; }","duration":"372.371514ms","start":"2026-04-20T17:49:06.274841Z","end":"2026-04-20T17:49:06.647213Z","steps":["trace[169561190] 'process raft request' (duration: 95.778771ms)","trace[169561190] 'compare' (duration: 276.253696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:49:06.647362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:06.27482Z","time spent":"372.492097ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:49:06.647495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.476551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:49:06.647534Z","caller":"traceutil/trace.go:171","msg":"trace[699562625] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3822; }","duration":"245.556042ms","start":"2026-04-20T17:49:06.401967Z","end":"2026-04-20T17:49:06.647523Z","steps":["trace[699562625] 'agreement among raft nodes before linearized reading' (duration: 245.50044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.647763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.206451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T17:49:06.647814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.520379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5n6mx\" ","response":"range_response_count:1 size:20664"} {"level":"info","ts":"2026-04-20T17:49:06.647888Z","caller":"traceutil/trace.go:171","msg":"trace[1321096571] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-5n6mx; range_end:; response_count:1; response_revision:3822; }","duration":"351.09634ms","start":"2026-04-20T17:49:06.296748Z","end":"2026-04-20T17:49:06.647844Z","steps":["trace[1321096571] 'agreement among raft nodes before linearized reading' (duration: 350.463898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:06.647929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:06.296713Z","time spent":"351.205821ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20688,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5n6mx\" "} {"level":"info","ts":"2026-04-20T17:49:06.648651Z","caller":"traceutil/trace.go:171","msg":"trace[1190310578] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:3822; }","duration":"197.368444ms","start":"2026-04-20T17:49:06.450472Z","end":"2026-04-20T17:49:06.64784Z","steps":["trace[1190310578] 'agreement among raft nodes before linearized reading' (duration: 197.087439ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:50:39.92948Z","caller":"traceutil/trace.go:171","msg":"trace[1337012673] transaction","detail":"{read_only:false; response_revision:4397; number_of_response:1; }","duration":"106.719314ms","start":"2026-04-20T17:50:39.8227Z","end":"2026-04-20T17:50:39.929419Z","steps":["trace[1337012673] 'process raft request' (duration: 106.48547ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:50:39.929496Z","caller":"traceutil/trace.go:171","msg":"trace[261548989] transaction","detail":"{read_only:false; response_revision:4398; number_of_response:1; }","duration":"106.414719ms","start":"2026-04-20T17:50:39.823053Z","end":"2026-04-20T17:50:39.929468Z","steps":["trace[261548989] 'process raft request' (duration: 106.281666ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:52:45.48298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2465} {"level":"info","ts":"2026-04-20T17:52:45.546091Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2465,"took":"61.746376ms","hash":941719924,"current-db-size-bytes":42188800,"current-db-size":"42 MB","current-db-size-in-use-bytes":21372928,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-20T17:52:45.546152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":941719924,"revision":2465,"compact-revision":-1} {"level":"info","ts":"2026-04-20T17:54:25.764988Z","caller":"traceutil/trace.go:171","msg":"trace[786231183] transaction","detail":"{read_only:false; response_revision:6561; number_of_response:1; }","duration":"144.151569ms","start":"2026-04-20T17:54:25.620774Z","end":"2026-04-20T17:54:25.764926Z","steps":["trace[786231183] 'process raft request' (duration: 115.562298ms)","trace[786231183] 'compare' (duration: 28.131583ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:54:25.765841Z","caller":"traceutil/trace.go:171","msg":"trace[224012965] transaction","detail":"{read_only:false; response_revision:6562; number_of_response:1; }","duration":"140.879179ms","start":"2026-04-20T17:54:25.624944Z","end":"2026-04-20T17:54:25.765823Z","steps":["trace[224012965] 'process raft request' (duration: 140.777527ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:54:32.008875Z","caller":"traceutil/trace.go:171","msg":"trace[1750239566] linearizableReadLoop","detail":"{readStateIndex:6923; appliedIndex:6922; }","duration":"100.645722ms","start":"2026-04-20T17:54:31.908177Z","end":"2026-04-20T17:54:32.008823Z","steps":["trace[1750239566] 'read index received' (duration: 100.477619ms)","trace[1750239566] 'applied index is now lower than readState.Index' (duration: 167.023µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:54:32.008902Z","caller":"traceutil/trace.go:171","msg":"trace[1264023727] transaction","detail":"{read_only:false; response_revision:6673; number_of_response:1; }","duration":"112.544122ms","start":"2026-04-20T17:54:31.896319Z","end":"2026-04-20T17:54:32.008863Z","steps":["trace[1264023727] 'process raft request' (duration: 112.352609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:54:32.009143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.937477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qh68g\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-04-20T17:54:32.009245Z","caller":"traceutil/trace.go:171","msg":"trace[119623821] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qh68g; range_end:; response_count:1; response_revision:6673; }","duration":"101.10432ms","start":"2026-04-20T17:54:31.908123Z","end":"2026-04-20T17:54:32.009227Z","steps":["trace[119623821] 'agreement among raft nodes before linearized reading' (duration: 100.828915ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:54:32.301498Z","caller":"traceutil/trace.go:171","msg":"trace[76336944] transaction","detail":"{read_only:false; response_revision:6684; number_of_response:1; }","duration":"160.816091ms","start":"2026-04-20T17:54:32.140656Z","end":"2026-04-20T17:54:32.301473Z","steps":["trace[76336944] 'process raft request' (duration: 160.447013ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:54:32.301669Z","caller":"traceutil/trace.go:171","msg":"trace[743564398] transaction","detail":"{read_only:false; response_revision:6685; number_of_response:1; }","duration":"159.659229ms","start":"2026-04-20T17:54:32.141998Z","end":"2026-04-20T17:54:32.301657Z","steps":["trace[743564398] 'process raft request' (duration: 159.382713ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:54:32.301647Z","caller":"traceutil/trace.go:171","msg":"trace[1982230636] linearizableReadLoop","detail":"{readStateIndex:6935; appliedIndex:6932; }","duration":"135.239665ms","start":"2026-04-20T17:54:32.166381Z","end":"2026-04-20T17:54:32.30162Z","steps":["trace[1982230636] 'read index received' (duration: 134.328968ms)","trace[1982230636] 'applied index is now lower than readState.Index' (duration: 908.237µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:54:32.301783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.390399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:54:32.301864Z","caller":"traceutil/trace.go:171","msg":"trace[1227235629] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6685; }","duration":"135.50664ms","start":"2026-04-20T17:54:32.166325Z","end":"2026-04-20T17:54:32.301832Z","steps":["trace[1227235629] 'agreement among raft nodes before linearized reading' (duration: 135.371048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:54:32.301998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.5908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T17:54:32.30128Z","caller":"traceutil/trace.go:171","msg":"trace[2071915463] transaction","detail":"{read_only:false; response_revision:6683; number_of_response:1; }","duration":"173.269221ms","start":"2026-04-20T17:54:32.127948Z","end":"2026-04-20T17:54:32.301217Z","steps":["trace[2071915463] 'process raft request' (duration: 172.884204ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:54:32.302059Z","caller":"traceutil/trace.go:171","msg":"trace[275005579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6685; }","duration":"101.701022ms","start":"2026-04-20T17:54:32.200347Z","end":"2026-04-20T17:54:32.302048Z","steps":["trace[275005579] 'agreement among raft nodes before linearized reading' (duration: 101.535019ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:56:44.072929Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002101.wal"} {"level":"info","ts":"2026-04-20T17:57:08.828072Z","caller":"traceutil/trace.go:171","msg":"trace[306796418] transaction","detail":"{read_only:false; response_revision:8978; number_of_response:1; }","duration":"212.389506ms","start":"2026-04-20T17:57:08.615647Z","end":"2026-04-20T17:57:08.828036Z","steps":["trace[306796418] 'process raft request' (duration: 201.198879ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:57:08.846771Z","caller":"traceutil/trace.go:171","msg":"trace[793945722] transaction","detail":"{read_only:false; response_revision:8979; number_of_response:1; }","duration":"173.927519ms","start":"2026-04-20T17:57:08.672821Z","end":"2026-04-20T17:57:08.846748Z","steps":["trace[793945722] 'process raft request' (duration: 173.817177ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:57:27.06985Z","caller":"traceutil/trace.go:171","msg":"trace[76609046] transaction","detail":"{read_only:false; response_revision:9168; number_of_response:1; }","duration":"140.698713ms","start":"2026-04-20T17:57:26.929122Z","end":"2026-04-20T17:57:27.069821Z","steps":["trace[76609046] 'process raft request' (duration: 73.357375ms)","trace[76609046] 'compare' (duration: 67.014122ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:57:27.069888Z","caller":"traceutil/trace.go:171","msg":"trace[1068230940] transaction","detail":"{read_only:false; response_revision:9169; number_of_response:1; }","duration":"133.456712ms","start":"2026-04-20T17:57:26.936423Z","end":"2026-04-20T17:57:27.069879Z","steps":["trace[1068230940] 'process raft request' (duration: 133.305999ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:57:27.069862Z","caller":"traceutil/trace.go:171","msg":"trace[700764159] transaction","detail":"{read_only:false; response_revision:9170; number_of_response:1; }","duration":"122.802166ms","start":"2026-04-20T17:57:26.947039Z","end":"2026-04-20T17:57:27.069841Z","steps":["trace[700764159] 'process raft request' (duration: 122.757455ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:57:45.49324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5226} {"level":"info","ts":"2026-04-20T17:57:45.577126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5226,"took":"79.744049ms","hash":874393954,"current-db-size-bytes":51286016,"current-db-size":"51 MB","current-db-size-in-use-bytes":43446272,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-20T17:57:45.577185Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":874393954,"revision":5226,"compact-revision":2465} {"level":"info","ts":"2026-04-20T17:58:07.202504Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T17:58:07.215574Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T17:58:07.215943Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T18:01:53.234225Z","caller":"traceutil/trace.go:171","msg":"trace[1662850235] transaction","detail":"{read_only:false; response_revision:11913; number_of_response:1; }","duration":"108.336277ms","start":"2026-04-20T18:01:53.125829Z","end":"2026-04-20T18:01:53.234165Z","steps":["trace[1662850235] 'process raft request' (duration: 96.130058ms)","trace[1662850235] 'compare' (duration: 11.916413ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:02:27.716321Z","caller":"traceutil/trace.go:171","msg":"trace[510763951] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12241; }","duration":"163.660276ms","start":"2026-04-20T18:02:27.552607Z","end":"2026-04-20T18:02:27.716267Z","steps":["trace[510763951] 'process raft request' (duration: 163.523194ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:32.490796Z","caller":"traceutil/trace.go:171","msg":"trace[1444301198] transaction","detail":"{read_only:false; response_revision:12540; number_of_response:1; }","duration":"126.380772ms","start":"2026-04-20T18:02:32.364364Z","end":"2026-04-20T18:02:32.490745Z","steps":["trace[1444301198] 'process raft request' (duration: 96.630694ms)","trace[1444301198] 'compare' (duration: 29.555984ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:02:32.490826Z","caller":"traceutil/trace.go:171","msg":"trace[1847824431] transaction","detail":"{read_only:false; response_revision:12541; number_of_response:1; }","duration":"124.974526ms","start":"2026-04-20T18:02:32.365802Z","end":"2026-04-20T18:02:32.490776Z","steps":["trace[1847824431] 'process raft request' (duration: 124.863923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:34.810391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.334216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T18:02:34.810494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.480483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:34.810532Z","caller":"traceutil/trace.go:171","msg":"trace[2102532866] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:12570; }","duration":"138.52235ms","start":"2026-04-20T18:02:34.671986Z","end":"2026-04-20T18:02:34.810508Z","steps":["trace[2102532866] 'range keys from in-memory index tree' (duration: 138.239564ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:34.810581Z","caller":"traceutil/trace.go:171","msg":"trace[1945502781] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:12570; }","duration":"126.586606ms","start":"2026-04-20T18:02:34.683964Z","end":"2026-04-20T18:02:34.81055Z","steps":["trace[1945502781] 'range keys from in-memory index tree' (duration: 126.403152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:34.810721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.862918ms","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-04-20T18:02:34.810746Z","caller":"traceutil/trace.go:171","msg":"trace[864499618] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:12570; }","duration":"136.913499ms","start":"2026-04-20T18:02:34.673825Z","end":"2026-04-20T18:02:34.810739Z","steps":["trace[864499618] 'range keys from in-memory index tree' (duration: 136.756516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:34.810803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.744306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:34.810823Z","caller":"traceutil/trace.go:171","msg":"trace[4434726] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:12570; }","duration":"136.829678ms","start":"2026-04-20T18:02:34.673986Z","end":"2026-04-20T18:02:34.810816Z","steps":["trace[4434726] 'range keys from in-memory index tree' (duration: 136.624523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:34.810453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.334579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:34.810884Z","caller":"traceutil/trace.go:171","msg":"trace[280942422] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12570; }","duration":"136.813668ms","start":"2026-04-20T18:02:34.674055Z","end":"2026-04-20T18:02:34.810868Z","steps":["trace[280942422] 'range keys from in-memory index tree' (duration: 136.223996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:34.810924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.423782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-04-20T18:02:34.810971Z","caller":"traceutil/trace.go:171","msg":"trace[515498036] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:12570; }","duration":"136.524553ms","start":"2026-04-20T18:02:34.674434Z","end":"2026-04-20T18:02:34.810959Z","steps":["trace[515498036] 'range keys from in-memory index tree' (duration: 136.317818ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:36.647495Z","caller":"traceutil/trace.go:171","msg":"trace[989564618] transaction","detail":"{read_only:false; response_revision:12589; number_of_response:1; }","duration":"102.462673ms","start":"2026-04-20T18:02:36.544982Z","end":"2026-04-20T18:02:36.647445Z","steps":["trace[989564618] 'process raft request' (duration: 77.451853ms)","trace[989564618] 'compare' (duration: 24.790086ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:02:36.938447Z","caller":"traceutil/trace.go:171","msg":"trace[1992470557] linearizableReadLoop","detail":"{readStateIndex:12996; appliedIndex:12995; }","duration":"144.725447ms","start":"2026-04-20T18:02:36.793706Z","end":"2026-04-20T18:02:36.938431Z","steps":["trace[1992470557] 'read index received' (duration: 20.845102ms)","trace[1992470557] 'applied index is now lower than readState.Index' (duration: 123.879625ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:02:36.93853Z","caller":"traceutil/trace.go:171","msg":"trace[1342590238] transaction","detail":"{read_only:false; response_revision:12603; number_of_response:1; }","duration":"139.071961ms","start":"2026-04-20T18:02:36.79945Z","end":"2026-04-20T18:02:36.938522Z","steps":["trace[1342590238] 'process raft request' (duration: 138.931488ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:36.938502Z","caller":"traceutil/trace.go:171","msg":"trace[220730144] transaction","detail":"{read_only:false; response_revision:12602; number_of_response:1; }","duration":"148.644771ms","start":"2026-04-20T18:02:36.789809Z","end":"2026-04-20T18:02:36.938454Z","steps":["trace[220730144] 'process raft request' (duration: 113.649153ms)","trace[220730144] 'compare' (duration: 34.775363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:02:36.938722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.007392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-04-20T18:02:36.93883Z","caller":"traceutil/trace.go:171","msg":"trace[159445222] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:12603; }","duration":"145.153535ms","start":"2026-04-20T18:02:36.793647Z","end":"2026-04-20T18:02:36.9388Z","steps":["trace[159445222] 'agreement among raft nodes before linearized reading' (duration: 144.88313ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:36.959816Z","caller":"traceutil/trace.go:171","msg":"trace[419904473] transaction","detail":"{read_only:false; response_revision:12604; number_of_response:1; }","duration":"138.920158ms","start":"2026-04-20T18:02:36.820863Z","end":"2026-04-20T18:02:36.959783Z","steps":["trace[419904473] 'process raft request' (duration: 138.362297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:36.959899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.607317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:36.959987Z","caller":"traceutil/trace.go:171","msg":"trace[703619594] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12604; }","duration":"143.865651ms","start":"2026-04-20T18:02:36.816097Z","end":"2026-04-20T18:02:36.959963Z","steps":["trace[703619594] 'agreement among raft nodes before linearized reading' (duration: 143.720659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:36.96009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.233837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:36.960127Z","caller":"traceutil/trace.go:171","msg":"trace[1989341729] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:12604; }","duration":"144.314989ms","start":"2026-04-20T18:02:36.815803Z","end":"2026-04-20T18:02:36.960118Z","steps":["trace[1989341729] 'agreement among raft nodes before linearized reading' (duration: 144.248688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:36.9602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.028324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:36.960223Z","caller":"traceutil/trace.go:171","msg":"trace[126609424] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:12604; }","duration":"144.108415ms","start":"2026-04-20T18:02:36.816109Z","end":"2026-04-20T18:02:36.960218Z","steps":["trace[126609424] 'agreement among raft nodes before linearized reading' (duration: 144.073554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:36.960325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.260158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:36.960361Z","caller":"traceutil/trace.go:171","msg":"trace[462734940] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:12604; }","duration":"144.320059ms","start":"2026-04-20T18:02:36.816028Z","end":"2026-04-20T18:02:36.960348Z","steps":["trace[462734940] 'agreement among raft nodes before linearized reading' (duration: 144.272979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:36.960887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.565644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-04-20T18:02:36.960978Z","caller":"traceutil/trace.go:171","msg":"trace[2063070988] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:12604; }","duration":"144.677566ms","start":"2026-04-20T18:02:36.81628Z","end":"2026-04-20T18:02:36.960957Z","steps":["trace[2063070988] 'agreement among raft nodes before linearized reading' (duration: 144.448891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:36.960897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.872354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x8mf6\" ","response":"range_response_count:1 size:22010"} {"level":"info","ts":"2026-04-20T18:02:36.961193Z","caller":"traceutil/trace.go:171","msg":"trace[2099057343] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x8mf6; range_end:; response_count:1; response_revision:12604; }","duration":"117.150009ms","start":"2026-04-20T18:02:36.843983Z","end":"2026-04-20T18:02:36.961133Z","steps":["trace[2099057343] 'agreement among raft nodes before linearized reading' (duration: 116.778712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:36.961338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.991001ms","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-04-20T18:02:36.961381Z","caller":"traceutil/trace.go:171","msg":"trace[2044982174] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:12604; }","duration":"145.054643ms","start":"2026-04-20T18:02:36.816315Z","end":"2026-04-20T18:02:36.96137Z","steps":["trace[2044982174] 'agreement among raft nodes before linearized reading' (duration: 144.667226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:36.96151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.665389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x8mf6\" ","response":"range_response_count:1 size:22010"} {"level":"info","ts":"2026-04-20T18:02:36.961547Z","caller":"traceutil/trace.go:171","msg":"trace[41845779] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x8mf6; range_end:; response_count:1; response_revision:12604; }","duration":"117.74529ms","start":"2026-04-20T18:02:36.843793Z","end":"2026-04-20T18:02:36.961538Z","steps":["trace[41845779] 'agreement among raft nodes before linearized reading' (duration: 116.947286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:41.421112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.052874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T18:02:41.421242Z","caller":"traceutil/trace.go:171","msg":"trace[414037318] linearizableReadLoop","detail":"{readStateIndex:13066; appliedIndex:13065; }","duration":"116.874894ms","start":"2026-04-20T18:02:41.304349Z","end":"2026-04-20T18:02:41.421224Z","steps":["trace[414037318] 'read index received' (duration: 56.131µs)","trace[414037318] 'applied index is now lower than readState.Index' (duration: 116.817773ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:02:41.421474Z","caller":"traceutil/trace.go:171","msg":"trace[636603725] transaction","detail":"{read_only:false; response_revision:12668; number_of_response:1; }","duration":"199.630506ms","start":"2026-04-20T18:02:41.221828Z","end":"2026-04-20T18:02:41.421458Z","steps":["trace[636603725] 'process raft request' (duration: 23.135834ms)","trace[636603725] 'compare' (duration: 175.929392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:02:41.421738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.374333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:41.421787Z","caller":"traceutil/trace.go:171","msg":"trace[974769743] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:12668; }","duration":"117.474745ms","start":"2026-04-20T18:02:41.304297Z","end":"2026-04-20T18:02:41.421772Z","steps":["trace[974769743] 'agreement among raft nodes before linearized reading' (duration: 117.398384ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:44.044301Z","caller":"traceutil/trace.go:171","msg":"trace[71565769] transaction","detail":"{read_only:false; response_revision:12693; number_of_response:1; }","duration":"152.295059ms","start":"2026-04-20T18:02:43.891896Z","end":"2026-04-20T18:02:44.044191Z","steps":["trace[71565769] 'process raft request' (duration: 144.782538ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:44.044616Z","caller":"traceutil/trace.go:171","msg":"trace[449414099] transaction","detail":"{read_only:false; response_revision:12694; number_of_response:1; }","duration":"152.504273ms","start":"2026-04-20T18:02:43.892078Z","end":"2026-04-20T18:02:44.044582Z","steps":["trace[449414099] 'process raft request' (duration: 152.065124ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:44.044624Z","caller":"traceutil/trace.go:171","msg":"trace[1155903601] linearizableReadLoop","detail":"{readStateIndex:13095; appliedIndex:13092; }","duration":"108.723031ms","start":"2026-04-20T18:02:43.935868Z","end":"2026-04-20T18:02:44.044591Z","steps":["trace[1155903601] 'read index received' (duration: 100.867043ms)","trace[1155903601] 'applied index is now lower than readState.Index' (duration: 7.853677ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:02:44.044665Z","caller":"traceutil/trace.go:171","msg":"trace[1024416403] transaction","detail":"{read_only:false; response_revision:12695; number_of_response:1; }","duration":"152.497813ms","start":"2026-04-20T18:02:43.892123Z","end":"2026-04-20T18:02:44.04462Z","steps":["trace[1024416403] 'process raft request' (duration: 152.229728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:44.044794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.916105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T18:02:44.04483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.928964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:44.044851Z","caller":"traceutil/trace.go:171","msg":"trace[1387087652] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:12695; }","duration":"109.021216ms","start":"2026-04-20T18:02:43.935815Z","end":"2026-04-20T18:02:44.044836Z","steps":["trace[1387087652] 'agreement among raft nodes before linearized reading' (duration: 108.922934ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:44.044899Z","caller":"traceutil/trace.go:171","msg":"trace[1930499462] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:12695; }","duration":"109.045957ms","start":"2026-04-20T18:02:43.935837Z","end":"2026-04-20T18:02:44.044883Z","steps":["trace[1930499462] 'agreement among raft nodes before linearized reading' (duration: 108.924365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:44.044877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.905444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:02:44.045089Z","caller":"traceutil/trace.go:171","msg":"trace[2125352167] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12695; }","duration":"109.17598ms","start":"2026-04-20T18:02:43.935861Z","end":"2026-04-20T18:02:44.045037Z","steps":["trace[2125352167] 'agreement among raft nodes before linearized reading' (duration: 108.808373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:44.045087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.431727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-20T18:02:44.045145Z","caller":"traceutil/trace.go:171","msg":"trace[400633977] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:12695; }","duration":"106.527539ms","start":"2026-04-20T18:02:43.938605Z","end":"2026-04-20T18:02:44.045133Z","steps":["trace[400633977] 'agreement among raft nodes before linearized reading' (duration: 106.304735ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:45.505579Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9399} {"level":"info","ts":"2026-04-20T18:02:45.641636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9399,"took":"132.097231ms","hash":3666731457,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":37404672,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-20T18:02:45.641713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3666731457,"revision":9399,"compact-revision":5226} {"level":"warn","ts":"2026-04-20T18:02:54.803141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.598384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T18:02:54.803255Z","caller":"traceutil/trace.go:171","msg":"trace[1433510286] transaction","detail":"{read_only:false; response_revision:12782; number_of_response:1; }","duration":"230.158909ms","start":"2026-04-20T18:02:54.573079Z","end":"2026-04-20T18:02:54.803238Z","steps":["trace[1433510286] 'process raft request' (duration: 102.390812ms)","trace[1433510286] 'compare' (duration: 127.480702ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:02:54.855959Z","caller":"traceutil/trace.go:171","msg":"trace[1014647521] transaction","detail":"{read_only:false; response_revision:12783; number_of_response:1; }","duration":"147.898887ms","start":"2026-04-20T18:02:54.708035Z","end":"2026-04-20T18:02:54.855934Z","steps":["trace[1014647521] 'process raft request' (duration: 147.559001ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:55.340468Z","caller":"traceutil/trace.go:171","msg":"trace[63206514] linearizableReadLoop","detail":"{readStateIndex:13187; appliedIndex:13186; }","duration":"306.31806ms","start":"2026-04-20T18:02:55.034043Z","end":"2026-04-20T18:02:55.340361Z","steps":["trace[63206514] 'read index received' (duration: 306.293749ms)","trace[63206514] 'applied index is now lower than readState.Index' (duration: 23.021µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:02:55.340553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.482703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-04-20T18:02:55.340528Z","caller":"traceutil/trace.go:171","msg":"trace[1743072368] transaction","detail":"{read_only:false; response_revision:12784; number_of_response:1; }","duration":"474.343273ms","start":"2026-04-20T18:02:54.866091Z","end":"2026-04-20T18:02:55.340434Z","steps":["trace[1743072368] 'process raft request' (duration: 474.083598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.340805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:02:54.866058Z","time spent":"474.601298ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4829,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:02:55.340642Z","caller":"traceutil/trace.go:171","msg":"trace[148560662] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:12784; }","duration":"306.579524ms","start":"2026-04-20T18:02:55.034035Z","end":"2026-04-20T18:02:55.340615Z","steps":["trace[148560662] 'agreement among raft nodes before linearized reading' (duration: 306.35367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.34089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:02:55.033952Z","time spent":"306.92191ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":705,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"info","ts":"2026-04-20T18:02:55.355062Z","caller":"traceutil/trace.go:171","msg":"trace[78672023] transaction","detail":"{read_only:false; response_revision:12785; number_of_response:1; }","duration":"320.626868ms","start":"2026-04-20T18:02:55.034407Z","end":"2026-04-20T18:02:55.355034Z","steps":["trace[78672023] 'process raft request' (duration: 320.434424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.355288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.593017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T18:02:55.35523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:02:55.034367Z","time spent":"320.76351ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:02:55.35535Z","caller":"traceutil/trace.go:171","msg":"trace[1130236121] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:12786; }","duration":"320.724899ms","start":"2026-04-20T18:02:55.03461Z","end":"2026-04-20T18:02:55.355335Z","steps":["trace[1130236121] 'agreement among raft nodes before linearized reading' (duration: 320.620617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.355392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:02:55.03457Z","time spent":"320.814001ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":29,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-04-20T18:02:55.355527Z","caller":"traceutil/trace.go:171","msg":"trace[942579765] transaction","detail":"{read_only:false; response_revision:12786; number_of_response:1; }","duration":"169.666404ms","start":"2026-04-20T18:02:55.185816Z","end":"2026-04-20T18:02:55.355483Z","steps":["trace[942579765] 'process raft request' (duration: 169.175435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.355558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.095326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-20T18:02:55.355594Z","caller":"traceutil/trace.go:171","msg":"trace[294229515] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:12786; }","duration":"249.152137ms","start":"2026-04-20T18:02:55.10643Z","end":"2026-04-20T18:02:55.355582Z","steps":["trace[294229515] 'agreement among raft nodes before linearized reading' (duration: 249.015024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.355654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.564068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-20T18:02:55.355702Z","caller":"traceutil/trace.go:171","msg":"trace[1220160719] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:12786; }","duration":"319.63589ms","start":"2026-04-20T18:02:55.036054Z","end":"2026-04-20T18:02:55.355689Z","steps":["trace[1220160719] 'agreement among raft nodes before linearized reading' (duration: 319.442086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.35573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:02:55.036045Z","time spent":"319.67958ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5472,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-04-20T18:02:55.35589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.021897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T18:02:55.355914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.501296ms","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-04-20T18:02:55.355932Z","caller":"traceutil/trace.go:171","msg":"trace[413859077] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12786; }","duration":"255.101468ms","start":"2026-04-20T18:02:55.100822Z","end":"2026-04-20T18:02:55.355923Z","steps":["trace[413859077] 'agreement among raft nodes before linearized reading' (duration: 255.024557ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:55.356044Z","caller":"traceutil/trace.go:171","msg":"trace[1364718837] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:12786; }","duration":"320.563586ms","start":"2026-04-20T18:02:55.035379Z","end":"2026-04-20T18:02:55.355943Z","steps":["trace[1364718837] 'agreement among raft nodes before linearized reading' (duration: 320.338502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.356094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.919188ms","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-04-20T18:02:55.356115Z","caller":"traceutil/trace.go:171","msg":"trace[1450606922] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:12786; }","duration":"252.985149ms","start":"2026-04-20T18:02:55.103124Z","end":"2026-04-20T18:02:55.356109Z","steps":["trace[1450606922] 'agreement among raft nodes before linearized reading' (duration: 252.885727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.356122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:02:55.035371Z","time spent":"320.7396ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":692,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-04-20T18:02:55.356163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.323862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x8mf6\" ","response":"range_response_count:1 size:22010"} {"level":"warn","ts":"2026-04-20T18:02:55.355901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.060624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T18:02:55.356237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.982366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T18:02:55.356072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.004851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x8mf6\" ","response":"range_response_count:1 size:22010"} {"level":"info","ts":"2026-04-20T18:02:55.356246Z","caller":"traceutil/trace.go:171","msg":"trace[1406534063] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x8mf6; range_end:; response_count:1; response_revision:12786; }","duration":"158.469284ms","start":"2026-04-20T18:02:55.197753Z","end":"2026-04-20T18:02:55.356222Z","steps":["trace[1406534063] 'agreement among raft nodes before linearized reading' (duration: 158.210419ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:55.356268Z","caller":"traceutil/trace.go:171","msg":"trace[258099838] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12786; }","duration":"321.055766ms","start":"2026-04-20T18:02:55.035202Z","end":"2026-04-20T18:02:55.356258Z","steps":["trace[258099838] 'agreement among raft nodes before linearized reading' (duration: 321.010275ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:02:55.356276Z","caller":"traceutil/trace.go:171","msg":"trace[1674258885] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x8mf6; range_end:; response_count:1; response_revision:12786; }","duration":"146.229965ms","start":"2026-04-20T18:02:55.210036Z","end":"2026-04-20T18:02:55.356266Z","steps":["trace[1674258885] 'agreement among raft nodes before linearized reading' (duration: 145.968729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.356295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:02:55.035169Z","time spent":"321.120258ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-04-20T18:02:55.356296Z","caller":"traceutil/trace.go:171","msg":"trace[1500936008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12786; }","duration":"274.467942ms","start":"2026-04-20T18:02:55.08178Z","end":"2026-04-20T18:02:55.356248Z","steps":["trace[1500936008] 'agreement among raft nodes before linearized reading' (duration: 274.072504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.356324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.148248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-20T18:02:55.356487Z","caller":"traceutil/trace.go:171","msg":"trace[1019198960] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:12786; }","duration":"321.366282ms","start":"2026-04-20T18:02:55.035107Z","end":"2026-04-20T18:02:55.356473Z","steps":["trace[1019198960] 'agreement among raft nodes before linearized reading' (duration: 321.135158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:02:55.356525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:02:55.035081Z","time spent":"321.433494ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5472,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-04-20T18:07:29.921001Z","caller":"traceutil/trace.go:171","msg":"trace[922643257] transaction","detail":"{read_only:false; response_revision:14971; number_of_response:1; }","duration":"165.425876ms","start":"2026-04-20T18:07:29.755522Z","end":"2026-04-20T18:07:29.920948Z","steps":["trace[922643257] 'process raft request' (duration: 165.228582ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:07:29.924695Z","caller":"traceutil/trace.go:171","msg":"trace[249472854] transaction","detail":"{read_only:false; response_revision:14972; number_of_response:1; }","duration":"157.983946ms","start":"2026-04-20T18:07:29.766681Z","end":"2026-04-20T18:07:29.924665Z","steps":["trace[249472854] 'process raft request' (duration: 157.841723ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:07:30.206049Z","caller":"traceutil/trace.go:171","msg":"trace[1450113540] transaction","detail":"{read_only:false; response_revision:14973; number_of_response:1; }","duration":"113.394639ms","start":"2026-04-20T18:07:30.092632Z","end":"2026-04-20T18:07:30.206027Z","steps":["trace[1450113540] 'process raft request' (duration: 113.256296ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:07:32.449493Z","caller":"traceutil/trace.go:171","msg":"trace[1795509] transaction","detail":"{read_only:false; response_revision:14989; number_of_response:1; }","duration":"209.939421ms","start":"2026-04-20T18:07:32.239529Z","end":"2026-04-20T18:07:32.449468Z","steps":["trace[1795509] 'process raft request' (duration: 209.693607ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:07:32.506926Z","caller":"traceutil/trace.go:171","msg":"trace[1224118722] linearizableReadLoop","detail":"{readStateIndex:15461; appliedIndex:15459; }","duration":"130.794956ms","start":"2026-04-20T18:07:32.376108Z","end":"2026-04-20T18:07:32.506903Z","steps":["trace[1224118722] 'read index received' (duration: 73.50547ms)","trace[1224118722] 'applied index is now lower than readState.Index' (duration: 57.288236ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:07:32.507109Z","caller":"traceutil/trace.go:171","msg":"trace[843148889] transaction","detail":"{read_only:false; response_revision:14990; number_of_response:1; }","duration":"251.850269ms","start":"2026-04-20T18:07:32.255199Z","end":"2026-04-20T18:07:32.50705Z","steps":["trace[843148889] 'process raft request' (duration: 251.457142ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:07:32.507212Z","caller":"traceutil/trace.go:171","msg":"trace[197494900] transaction","detail":"{read_only:false; response_revision:14991; number_of_response:1; }","duration":"148.776673ms","start":"2026-04-20T18:07:32.358403Z","end":"2026-04-20T18:07:32.50718Z","steps":["trace[197494900] 'process raft request' (duration: 148.446227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:07:32.507228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.074272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T18:07:32.507155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.02158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T18:07:32.507344Z","caller":"traceutil/trace.go:171","msg":"trace[1497775809] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:14991; }","duration":"131.203964ms","start":"2026-04-20T18:07:32.37611Z","end":"2026-04-20T18:07:32.507314Z","steps":["trace[1497775809] 'agreement among raft nodes before linearized reading' (duration: 131.031721ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:07:32.507376Z","caller":"traceutil/trace.go:171","msg":"trace[2008582002] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:14991; }","duration":"131.272255ms","start":"2026-04-20T18:07:32.37608Z","end":"2026-04-20T18:07:32.507352Z","steps":["trace[2008582002] 'agreement among raft nodes before linearized reading' (duration: 130.981979ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:07:45.512017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12712} {"level":"info","ts":"2026-04-20T18:07:45.63293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12712,"took":"116.778981ms","hash":934651673,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":33714176,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T18:07:45.633007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":934651673,"revision":12712,"compact-revision":9399} {"level":"info","ts":"2026-04-20T18:09:35.95217Z","caller":"traceutil/trace.go:171","msg":"trace[31853685] transaction","detail":"{read_only:false; response_revision:16150; number_of_response:1; }","duration":"107.582932ms","start":"2026-04-20T18:09:35.844565Z","end":"2026-04-20T18:09:35.952148Z","steps":["trace[31853685] 'process raft request' (duration: 107.434369ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:09:41.466671Z","caller":"traceutil/trace.go:171","msg":"trace[1406477646] transaction","detail":"{read_only:false; response_revision:16179; number_of_response:1; }","duration":"112.78386ms","start":"2026-04-20T18:09:41.353867Z","end":"2026-04-20T18:09:41.466651Z","steps":["trace[1406477646] 'process raft request' (duration: 112.662227ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:10:24.850694Z","caller":"traceutil/trace.go:171","msg":"trace[1676958913] transaction","detail":"{read_only:false; response_revision:16618; number_of_response:1; }","duration":"336.724727ms","start":"2026-04-20T18:10:24.513944Z","end":"2026-04-20T18:10:24.850669Z","steps":["trace[1676958913] 'process raft request' (duration: 336.543224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:10:24.850944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:10:24.513924Z","time spent":"336.85454ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6717,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:10:25.028695Z","caller":"traceutil/trace.go:171","msg":"trace[1506253000] linearizableReadLoop","detail":"{readStateIndex:17140; appliedIndex:17137; }","duration":"233.793983ms","start":"2026-04-20T18:10:24.794862Z","end":"2026-04-20T18:10:25.028656Z","steps":["trace[1506253000] 'read index received' (duration: 55.632325ms)","trace[1506253000] 'applied index is now lower than readState.Index' (duration: 178.160698ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:10:25.02876Z","caller":"traceutil/trace.go:171","msg":"trace[1928671241] transaction","detail":"{read_only:false; response_revision:16619; number_of_response:1; }","duration":"468.256508ms","start":"2026-04-20T18:10:24.560455Z","end":"2026-04-20T18:10:25.028712Z","steps":["trace[1928671241] 'process raft request' (duration: 439.790464ms)","trace[1928671241] 'compare' (duration: 28.200489ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:10:25.028761Z","caller":"traceutil/trace.go:171","msg":"trace[1572210253] transaction","detail":"{read_only:false; response_revision:16620; number_of_response:1; }","duration":"467.531626ms","start":"2026-04-20T18:10:24.561195Z","end":"2026-04-20T18:10:25.028726Z","steps":["trace[1572210253] 'process raft request' (duration: 467.408353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:10:25.028925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.367445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:10:25.028969Z","caller":"traceutil/trace.go:171","msg":"trace[1661579528] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:16620; }","duration":"233.499557ms","start":"2026-04-20T18:10:24.795458Z","end":"2026-04-20T18:10:25.028957Z","steps":["trace[1661579528] 'agreement among raft nodes before linearized reading' (duration: 233.363844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:10:25.029033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:10:24.56042Z","time spent":"468.452452ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T18:10:25.029039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.988517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-20T18:10:25.029072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:10:24.561185Z","time spent":"467.76309ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:10:25.029168Z","caller":"traceutil/trace.go:171","msg":"trace[307995669] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:16620; }","duration":"234.304193ms","start":"2026-04-20T18:10:24.794833Z","end":"2026-04-20T18:10:25.029137Z","steps":["trace[307995669] 'agreement among raft nodes before linearized reading' (duration: 233.925816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:10:25.029283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.715961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-20T18:10:25.029294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.342334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-20T18:10:25.029338Z","caller":"traceutil/trace.go:171","msg":"trace[1820228252] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:16620; }","duration":"233.812583ms","start":"2026-04-20T18:10:24.795511Z","end":"2026-04-20T18:10:25.029324Z","steps":["trace[1820228252] 'agreement among raft nodes before linearized reading' (duration: 233.718481ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:10:25.02934Z","caller":"traceutil/trace.go:171","msg":"trace[1272662696] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:16620; }","duration":"233.431956ms","start":"2026-04-20T18:10:24.795898Z","end":"2026-04-20T18:10:25.02933Z","steps":["trace[1272662696] 'agreement among raft nodes before linearized reading' (duration: 233.296183ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:10:28.445167Z","caller":"traceutil/trace.go:171","msg":"trace[472325420] transaction","detail":"{read_only:false; response_revision:16637; number_of_response:1; }","duration":"232.56673ms","start":"2026-04-20T18:10:28.212569Z","end":"2026-04-20T18:10:28.445136Z","steps":["trace[472325420] 'process raft request' (duration: 232.373607ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:10:28.917477Z","caller":"traceutil/trace.go:171","msg":"trace[1003292052] transaction","detail":"{read_only:false; response_revision:16641; number_of_response:1; }","duration":"317.224081ms","start":"2026-04-20T18:10:28.600225Z","end":"2026-04-20T18:10:28.917449Z","steps":["trace[1003292052] 'process raft request' (duration: 317.17881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:10:28.917639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:10:28.600214Z","time spent":"317.344843ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:10:28.917735Z","caller":"traceutil/trace.go:171","msg":"trace[1886695903] transaction","detail":"{read_only:false; response_revision:16639; number_of_response:1; }","duration":"321.93168ms","start":"2026-04-20T18:10:28.595779Z","end":"2026-04-20T18:10:28.91771Z","steps":["trace[1886695903] 'process raft request' (duration: 261.269289ms)","trace[1886695903] 'compare' (duration: 60.08398ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:10:28.917735Z","caller":"traceutil/trace.go:171","msg":"trace[1570972592] transaction","detail":"{read_only:false; response_revision:16640; number_of_response:1; }","duration":"318.849052ms","start":"2026-04-20T18:10:28.598867Z","end":"2026-04-20T18:10:28.917716Z","steps":["trace[1570972592] 'process raft request' (duration: 318.432414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:10:28.917844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:10:28.595756Z","time spent":"322.027271ms","remote":"127.0.0.1:44334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T18:10:28.917847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:10:28.598847Z","time spent":"318.938323ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:10:49.453334Z","caller":"traceutil/trace.go:171","msg":"trace[1731366691] linearizableReadLoop","detail":"{readStateIndex:17278; appliedIndex:17277; }","duration":"122.839739ms","start":"2026-04-20T18:10:49.330449Z","end":"2026-04-20T18:10:49.453289Z","steps":["trace[1731366691] 'read index received' (duration: 122.691996ms)","trace[1731366691] 'applied index is now lower than readState.Index' (duration: 146.842µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:10:49.453407Z","caller":"traceutil/trace.go:171","msg":"trace[1571152115] transaction","detail":"{read_only:false; response_revision:16753; number_of_response:1; }","duration":"172.097935ms","start":"2026-04-20T18:10:49.281263Z","end":"2026-04-20T18:10:49.453361Z","steps":["trace[1571152115] 'process raft request' (duration: 171.885041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:10:49.453489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.021502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:10:49.453522Z","caller":"traceutil/trace.go:171","msg":"trace[1875292270] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:16753; }","duration":"123.096574ms","start":"2026-04-20T18:10:49.330416Z","end":"2026-04-20T18:10:49.453512Z","steps":["trace[1875292270] 'agreement among raft nodes before linearized reading' (duration: 123.026302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:10:49.453687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.158314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:10:49.453781Z","caller":"traceutil/trace.go:171","msg":"trace[1554612466] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:16753; }","duration":"123.292477ms","start":"2026-04-20T18:10:49.33047Z","end":"2026-04-20T18:10:49.453763Z","steps":["trace[1554612466] 'agreement among raft nodes before linearized reading' (duration: 123.145155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:10:49.4539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.452901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"warn","ts":"2026-04-20T18:10:49.4539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.849088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:10:49.453973Z","caller":"traceutil/trace.go:171","msg":"trace[1136620349] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:16753; }","duration":"122.537913ms","start":"2026-04-20T18:10:49.331409Z","end":"2026-04-20T18:10:49.453947Z","steps":["trace[1136620349] 'agreement among raft nodes before linearized reading' (duration: 122.321669ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:10:49.453998Z","caller":"traceutil/trace.go:171","msg":"trace[1517965443] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:16753; }","duration":"122.967111ms","start":"2026-04-20T18:10:49.33101Z","end":"2026-04-20T18:10:49.453977Z","steps":["trace[1517965443] 'agreement among raft nodes before linearized reading' (duration: 122.823879ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:12:45.520278Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15152} {"level":"info","ts":"2026-04-20T18:12:45.636754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15152,"took":"112.436124ms","hash":3106466961,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":36261888,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-20T18:12:45.636825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3106466961,"revision":15152,"compact-revision":12712} {"level":"info","ts":"2026-04-20T18:14:42.05635Z","caller":"traceutil/trace.go:171","msg":"trace[1340412051] linearizableReadLoop","detail":"{readStateIndex:19982; appliedIndex:19981; }","duration":"239.993959ms","start":"2026-04-20T18:14:41.816317Z","end":"2026-04-20T18:14:42.056311Z","steps":["trace[1340412051] 'read index received' (duration: 213.891458ms)","trace[1340412051] 'applied index is now lower than readState.Index' (duration: 26.101601ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:14:42.056421Z","caller":"traceutil/trace.go:171","msg":"trace[944992071] transaction","detail":"{read_only:false; response_revision:19382; number_of_response:1; }","duration":"321.875007ms","start":"2026-04-20T18:14:41.734504Z","end":"2026-04-20T18:14:42.056379Z","steps":["trace[944992071] 'process raft request' (duration: 295.685444ms)","trace[944992071] 'compare' (duration: 26.015589ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:14:42.056611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.262544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-20T18:14:42.056642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:14:41.734485Z","time spent":"322.03964ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:14:42.056698Z","caller":"traceutil/trace.go:171","msg":"trace[742967109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19382; }","duration":"240.418347ms","start":"2026-04-20T18:14:41.816261Z","end":"2026-04-20T18:14:42.056679Z","steps":["trace[742967109] 'agreement among raft nodes before linearized reading' (duration: 240.151782ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:14:42.258764Z","caller":"traceutil/trace.go:171","msg":"trace[1944958816] transaction","detail":"{read_only:false; response_revision:19384; number_of_response:1; }","duration":"230.899948ms","start":"2026-04-20T18:14:42.027838Z","end":"2026-04-20T18:14:42.258738Z","steps":["trace[1944958816] 'process raft request' (duration: 230.850217ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:14:42.259006Z","caller":"traceutil/trace.go:171","msg":"trace[1516955234] transaction","detail":"{read_only:false; response_revision:19383; number_of_response:1; }","duration":"286.808058ms","start":"2026-04-20T18:14:41.972156Z","end":"2026-04-20T18:14:42.258964Z","steps":["trace[1516955234] 'process raft request' (duration: 286.398121ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:14:42.259245Z","caller":"traceutil/trace.go:171","msg":"trace[593037281] linearizableReadLoop","detail":"{readStateIndex:19984; appliedIndex:19984; }","duration":"202.774549ms","start":"2026-04-20T18:14:42.056425Z","end":"2026-04-20T18:14:42.259199Z","steps":["trace[593037281] 'read index received' (duration: 202.757029ms)","trace[593037281] 'applied index is now lower than readState.Index' (duration: 15.15µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:14:42.259514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.996872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-04-20T18:14:42.259568Z","caller":"traceutil/trace.go:171","msg":"trace[1855045971] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:19384; }","duration":"270.085754ms","start":"2026-04-20T18:14:41.989467Z","end":"2026-04-20T18:14:42.259553Z","steps":["trace[1855045971] 'agreement among raft nodes before linearized reading' (duration: 269.87503ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:14:42.260353Z","caller":"traceutil/trace.go:171","msg":"trace[1009406891] transaction","detail":"{read_only:false; response_revision:19385; number_of_response:1; }","duration":"199.747473ms","start":"2026-04-20T18:14:42.060592Z","end":"2026-04-20T18:14:42.260339Z","steps":["trace[1009406891] 'process raft request' (duration: 199.676092ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:14:43.562812Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T18:14:43.56828Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-20T18:14:43.568519Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-20T18:17:45.526335Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17935} {"level":"info","ts":"2026-04-20T18:17:45.656112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17935,"took":"124.249619ms","hash":1704353602,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":42819584,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-20T18:17:45.6562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1704353602,"revision":17935,"compact-revision":15152} {"level":"info","ts":"2026-04-20T18:18:04.123876Z","caller":"traceutil/trace.go:171","msg":"trace[934839627] transaction","detail":"{read_only:false; response_revision:21442; number_of_response:1; }","duration":"100.172625ms","start":"2026-04-20T18:18:04.023657Z","end":"2026-04-20T18:18:04.12383Z","steps":["trace[934839627] 'process raft request' (duration: 100.015372ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:05.168053Z","caller":"traceutil/trace.go:171","msg":"trace[1042013277] transaction","detail":"{read_only:false; response_revision:21446; number_of_response:1; }","duration":"123.373422ms","start":"2026-04-20T18:18:05.044642Z","end":"2026-04-20T18:18:05.168015Z","steps":["trace[1042013277] 'process raft request' (duration: 110.055012ms)","trace[1042013277] 'compare' (duration: 13.080906ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:18:05.17056Z","caller":"traceutil/trace.go:171","msg":"trace[1560646541] transaction","detail":"{read_only:false; response_revision:21447; number_of_response:1; }","duration":"122.538105ms","start":"2026-04-20T18:18:05.047992Z","end":"2026-04-20T18:18:05.170531Z","steps":["trace[1560646541] 'process raft request' (duration: 122.121088ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:24.463294Z","caller":"traceutil/trace.go:171","msg":"trace[1887631302] linearizableReadLoop","detail":"{readStateIndex:22251; appliedIndex:22250; }","duration":"109.47958ms","start":"2026-04-20T18:18:24.353786Z","end":"2026-04-20T18:18:24.463266Z","steps":["trace[1887631302] 'read index received' (duration: 109.330847ms)","trace[1887631302] 'applied index is now lower than readState.Index' (duration: 147.453µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:18:24.463373Z","caller":"traceutil/trace.go:171","msg":"trace[1988408364] transaction","detail":"{read_only:false; response_revision:21593; number_of_response:1; }","duration":"245.398118ms","start":"2026-04-20T18:18:24.217924Z","end":"2026-04-20T18:18:24.463322Z","steps":["trace[1988408364] 'process raft request' (duration: 245.179654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:18:24.769073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.260646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T18:18:24.769164Z","caller":"traceutil/trace.go:171","msg":"trace[465337091] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:21593; }","duration":"415.375988ms","start":"2026-04-20T18:18:24.353767Z","end":"2026-04-20T18:18:24.769143Z","steps":["trace[465337091] 'agreement among raft nodes before linearized reading' (duration: 109.834657ms)","trace[465337091] 'count revisions from in-memory index tree' (duration: 305.364988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:18:24.769208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:18:24.353713Z","time spent":"415.4825ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":8,"response size":32,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-20T18:18:24.769865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.258727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T18:18:24.769976Z","caller":"traceutil/trace.go:171","msg":"trace[1960203857] linearizableReadLoop","detail":"{readStateIndex:22252; appliedIndex:22251; }","duration":"306.562811ms","start":"2026-04-20T18:18:24.463387Z","end":"2026-04-20T18:18:24.769949Z","steps":["trace[1960203857] 'read index received' (duration: 155.122731ms)","trace[1960203857] 'applied index is now lower than readState.Index' (duration: 151.43782ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:18:24.770086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.620936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:18:24.770107Z","caller":"traceutil/trace.go:171","msg":"trace[17095372] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:21594; }","duration":"166.646618ms","start":"2026-04-20T18:18:24.603451Z","end":"2026-04-20T18:18:24.770098Z","steps":["trace[17095372] 'agreement among raft nodes before linearized reading' (duration: 166.598757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:18:24.770126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.783876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:18:24.770206Z","caller":"traceutil/trace.go:171","msg":"trace[512441093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21594; }","duration":"297.896397ms","start":"2026-04-20T18:18:24.472286Z","end":"2026-04-20T18:18:24.770183Z","steps":["trace[512441093] 'agreement among raft nodes before linearized reading' (duration: 297.767385ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:24.770131Z","caller":"traceutil/trace.go:171","msg":"trace[1946113284] transaction","detail":"{read_only:false; response_revision:21594; number_of_response:1; }","duration":"353.416481ms","start":"2026-04-20T18:18:24.416617Z","end":"2026-04-20T18:18:24.770033Z","steps":["trace[1946113284] 'process raft request' (duration: 201.93227ms)","trace[1946113284] 'compare' (duration: 150.877879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:18:24.770262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.755662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18614"} {"level":"info","ts":"2026-04-20T18:18:24.770388Z","caller":"traceutil/trace.go:171","msg":"trace[784307804] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:21594; }","duration":"325.944206ms","start":"2026-04-20T18:18:24.444421Z","end":"2026-04-20T18:18:24.770365Z","steps":["trace[784307804] 'agreement among raft nodes before linearized reading' (duration: 325.590689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:18:24.770439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:18:24.444396Z","time spent":"326.032367ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18638,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-04-20T18:18:24.770484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:18:24.416494Z","time spent":"353.84191ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T18:18:25.089602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.647272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T18:18:25.089757Z","caller":"traceutil/trace.go:171","msg":"trace[235383829] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:21594; }","duration":"128.875885ms","start":"2026-04-20T18:18:24.960857Z","end":"2026-04-20T18:18:25.089733Z","steps":["trace[235383829] 'count revisions from in-memory index tree' (duration: 128.526809ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:25.503032Z","caller":"traceutil/trace.go:171","msg":"trace[514429492] transaction","detail":"{read_only:false; response_revision:21595; number_of_response:1; }","duration":"408.746453ms","start":"2026-04-20T18:18:25.094259Z","end":"2026-04-20T18:18:25.503005Z","steps":["trace[514429492] 'process raft request' (duration: 408.57963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:18:25.503188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:18:25.094235Z","time spent":"408.872186ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:18:25.53512Z","caller":"traceutil/trace.go:171","msg":"trace[862518438] transaction","detail":"{read_only:false; response_revision:21596; number_of_response:1; }","duration":"429.571146ms","start":"2026-04-20T18:18:25.105532Z","end":"2026-04-20T18:18:25.535103Z","steps":["trace[862518438] 'process raft request' (duration: 429.417713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:18:25.535217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:18:25.105504Z","time spent":"429.660807ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:18:25.535363Z","caller":"traceutil/trace.go:171","msg":"trace[1161827872] transaction","detail":"{read_only:false; response_revision:21597; number_of_response:1; }","duration":"428.24495ms","start":"2026-04-20T18:18:25.107088Z","end":"2026-04-20T18:18:25.535333Z","steps":["trace[1161827872] 'process raft request' (duration: 427.975235ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:25.535468Z","caller":"traceutil/trace.go:171","msg":"trace[1302257959] linearizableReadLoop","detail":"{readStateIndex:22255; appliedIndex:22252; }","duration":"408.912646ms","start":"2026-04-20T18:18:25.126528Z","end":"2026-04-20T18:18:25.53544Z","steps":["trace[1302257959] 'read index received' (duration: 376.361353ms)","trace[1302257959] 'applied index is now lower than readState.Index' (duration: 32.550233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:18:25.535522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:18:25.107074Z","time spent":"428.360202ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:18:25.535563Z","caller":"traceutil/trace.go:171","msg":"trace[1968672105] transaction","detail":"{read_only:false; response_revision:21598; number_of_response:1; }","duration":"299.465136ms","start":"2026-04-20T18:18:25.236043Z","end":"2026-04-20T18:18:25.535508Z","steps":["trace[1968672105] 'process raft request' (duration: 299.236722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:18:25.535651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.142802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-20T18:18:25.535651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.13034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T18:18:25.535697Z","caller":"traceutil/trace.go:171","msg":"trace[352216514] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:21598; }","duration":"289.247983ms","start":"2026-04-20T18:18:25.246432Z","end":"2026-04-20T18:18:25.53568Z","steps":["trace[352216514] 'agreement among raft nodes before linearized reading' (duration: 289.149902ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:25.535725Z","caller":"traceutil/trace.go:171","msg":"trace[1393936399] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:21598; }","duration":"409.243583ms","start":"2026-04-20T18:18:25.12646Z","end":"2026-04-20T18:18:25.535703Z","steps":["trace[1393936399] 'agreement among raft nodes before linearized reading' (duration: 409.06906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:18:25.535745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.580496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-20T18:18:25.535772Z","caller":"traceutil/trace.go:171","msg":"trace[1088009625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21598; }","duration":"275.687779ms","start":"2026-04-20T18:18:25.260075Z","end":"2026-04-20T18:18:25.535763Z","steps":["trace[1088009625] 'agreement among raft nodes before linearized reading' (duration: 275.582637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:18:25.535773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:18:25.126443Z","time spent":"409.320094ms","remote":"127.0.0.1:35906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":39,"response size":32,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-04-20T18:18:25.699318Z","caller":"traceutil/trace.go:171","msg":"trace[33259598] transaction","detail":"{read_only:false; response_revision:21600; number_of_response:1; }","duration":"158.771627ms","start":"2026-04-20T18:18:25.54052Z","end":"2026-04-20T18:18:25.699292Z","steps":["trace[33259598] 'process raft request' (duration: 158.549853ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:25.699579Z","caller":"traceutil/trace.go:171","msg":"trace[271839632] transaction","detail":"{read_only:false; response_revision:21601; number_of_response:1; }","duration":"158.2931ms","start":"2026-04-20T18:18:25.541278Z","end":"2026-04-20T18:18:25.699571Z","steps":["trace[271839632] 'process raft request' (duration: 157.972293ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:25.699698Z","caller":"traceutil/trace.go:171","msg":"trace[1830406798] transaction","detail":"{read_only:false; response_revision:21602; number_of_response:1; }","duration":"157.723629ms","start":"2026-04-20T18:18:25.541966Z","end":"2026-04-20T18:18:25.69969Z","steps":["trace[1830406798] 'process raft request' (duration: 157.564216ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:25.699838Z","caller":"traceutil/trace.go:171","msg":"trace[322069377] transaction","detail":"{read_only:false; response_revision:21603; number_of_response:1; }","duration":"136.313276ms","start":"2026-04-20T18:18:25.563511Z","end":"2026-04-20T18:18:25.699824Z","steps":["trace[322069377] 'process raft request' (duration: 136.155643ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:25.699855Z","caller":"traceutil/trace.go:171","msg":"trace[902760951] linearizableReadLoop","detail":"{readStateIndex:22260; appliedIndex:22257; }","duration":"152.865218ms","start":"2026-04-20T18:18:25.546964Z","end":"2026-04-20T18:18:25.699829Z","steps":["trace[902760951] 'read index received' (duration: 39.159327ms)","trace[902760951] 'applied index is now lower than readState.Index' (duration: 113.704931ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:18:25.700064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.084112ms","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-04-20T18:18:25.700107Z","caller":"traceutil/trace.go:171","msg":"trace[992591030] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21603; }","duration":"153.144603ms","start":"2026-04-20T18:18:25.546953Z","end":"2026-04-20T18:18:25.700097Z","steps":["trace[992591030] 'agreement among raft nodes before linearized reading' (duration: 152.9636ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:18:50.718023Z","caller":"traceutil/trace.go:171","msg":"trace[93794099] transaction","detail":"{read_only:false; response_revision:21797; number_of_response:1; }","duration":"126.120954ms","start":"2026-04-20T18:18:50.591884Z","end":"2026-04-20T18:18:50.718005Z","steps":["trace[93794099] 'process raft request' (duration: 125.977981ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:06.90104Z","caller":"traceutil/trace.go:171","msg":"trace[150183927] transaction","detail":"{read_only:false; response_revision:21943; number_of_response:1; }","duration":"111.778934ms","start":"2026-04-20T18:19:06.789223Z","end":"2026-04-20T18:19:06.901002Z","steps":["trace[150183927] 'process raft request' (duration: 111.56594ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:07.156697Z","caller":"traceutil/trace.go:171","msg":"trace[1804015653] transaction","detail":"{read_only:false; response_revision:21944; number_of_response:1; }","duration":"234.071185ms","start":"2026-04-20T18:19:06.922598Z","end":"2026-04-20T18:19:07.156669Z","steps":["trace[1804015653] 'process raft request' (duration: 202.543432ms)","trace[1804015653] 'compare' (duration: 31.241078ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:19:07.193049Z","caller":"traceutil/trace.go:171","msg":"trace[1897202785] transaction","detail":"{read_only:false; response_revision:21945; number_of_response:1; }","duration":"264.429207ms","start":"2026-04-20T18:19:06.928598Z","end":"2026-04-20T18:19:07.193027Z","steps":["trace[1897202785] 'process raft request' (duration: 263.811785ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:07.193585Z","caller":"traceutil/trace.go:171","msg":"trace[1663960585] transaction","detail":"{read_only:false; response_revision:21946; number_of_response:1; }","duration":"180.199062ms","start":"2026-04-20T18:19:07.013345Z","end":"2026-04-20T18:19:07.193544Z","steps":["trace[1663960585] 'process raft request' (duration: 179.189642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:19:26.137244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.630669ms","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-04-20T18:19:26.137244Z","caller":"traceutil/trace.go:171","msg":"trace[480182006] transaction","detail":"{read_only:false; response_revision:22089; number_of_response:1; }","duration":"219.147234ms","start":"2026-04-20T18:19:25.918043Z","end":"2026-04-20T18:19:26.13719Z","steps":["trace[480182006] 'process raft request' (duration: 196.441047ms)","trace[480182006] 'compare' (duration: 22.544824ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:19:26.137323Z","caller":"traceutil/trace.go:171","msg":"trace[2035079190] linearizableReadLoop","detail":"{readStateIndex:22760; appliedIndex:22759; }","duration":"141.139366ms","start":"2026-04-20T18:19:25.996144Z","end":"2026-04-20T18:19:26.137283Z","steps":["trace[2035079190] 'read index received' (duration: 118.415898ms)","trace[2035079190] 'applied index is now lower than readState.Index' (duration: 22.717947ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:19:26.137356Z","caller":"traceutil/trace.go:171","msg":"trace[1735239691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22088; }","duration":"223.780762ms","start":"2026-04-20T18:19:25.913554Z","end":"2026-04-20T18:19:26.137335Z","steps":["trace[1735239691] 'range keys from in-memory index tree' (duration: 223.455466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:19:26.13753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.390971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-20T18:19:26.137825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.463644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T18:19:26.137874Z","caller":"traceutil/trace.go:171","msg":"trace[1079482473] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:22090; }","duration":"114.546886ms","start":"2026-04-20T18:19:26.02331Z","end":"2026-04-20T18:19:26.137856Z","steps":["trace[1079482473] 'agreement among raft nodes before linearized reading' (duration: 114.453034ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:26.137854Z","caller":"traceutil/trace.go:171","msg":"trace[1783115597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22090; }","duration":"141.699486ms","start":"2026-04-20T18:19:25.996091Z","end":"2026-04-20T18:19:26.137791Z","steps":["trace[1783115597] 'agreement among raft nodes before linearized reading' (duration: 141.36396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:19:26.13793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.839378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-20T18:19:26.137952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.850212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T18:19:26.138026Z","caller":"traceutil/trace.go:171","msg":"trace[1968520185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22090; }","duration":"134.98804ms","start":"2026-04-20T18:19:26.003012Z","end":"2026-04-20T18:19:26.138Z","steps":["trace[1968520185] 'agreement among raft nodes before linearized reading' (duration: 134.766396ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:26.138038Z","caller":"traceutil/trace.go:171","msg":"trace[1571801401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22090; }","duration":"138.966655ms","start":"2026-04-20T18:19:25.999046Z","end":"2026-04-20T18:19:26.138013Z","steps":["trace[1571801401] 'agreement among raft nodes before linearized reading' (duration: 138.71925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:19:26.406767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.552685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T18:19:26.406894Z","caller":"traceutil/trace.go:171","msg":"trace[866690233] transaction","detail":"{read_only:false; response_revision:22091; number_of_response:1; }","duration":"265.558418ms","start":"2026-04-20T18:19:26.141315Z","end":"2026-04-20T18:19:26.406874Z","steps":["trace[866690233] 'process raft request' (duration: 132.79789ms)","trace[866690233] 'compare' (duration: 132.415082ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:19:26.407408Z","caller":"traceutil/trace.go:171","msg":"trace[938796288] transaction","detail":"{read_only:false; response_revision:22094; number_of_response:1; }","duration":"265.329583ms","start":"2026-04-20T18:19:26.142065Z","end":"2026-04-20T18:19:26.407394Z","steps":["trace[938796288] 'process raft request' (duration: 265.1822ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:26.407404Z","caller":"traceutil/trace.go:171","msg":"trace[1152656820] transaction","detail":"{read_only:false; response_revision:22092; number_of_response:1; }","duration":"265.374844ms","start":"2026-04-20T18:19:26.141999Z","end":"2026-04-20T18:19:26.407373Z","steps":["trace[1152656820] 'process raft request' (duration: 265.139669ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:26.407446Z","caller":"traceutil/trace.go:171","msg":"trace[1655189525] transaction","detail":"{read_only:false; response_revision:22093; number_of_response:1; }","duration":"265.435485ms","start":"2026-04-20T18:19:26.141996Z","end":"2026-04-20T18:19:26.407432Z","steps":["trace[1655189525] 'process raft request' (duration: 265.207081ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:26.407632Z","caller":"traceutil/trace.go:171","msg":"trace[168842083] transaction","detail":"{read_only:false; response_revision:22095; number_of_response:1; }","duration":"155.779901ms","start":"2026-04-20T18:19:26.251825Z","end":"2026-04-20T18:19:26.407605Z","steps":["trace[168842083] 'process raft request' (duration: 155.532216ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:26.417711Z","caller":"traceutil/trace.go:171","msg":"trace[363953340] transaction","detail":"{read_only:false; response_revision:22096; number_of_response:1; }","duration":"125.879619ms","start":"2026-04-20T18:19:26.291819Z","end":"2026-04-20T18:19:26.417699Z","steps":["trace[363953340] 'process raft request' (duration: 125.587844ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:40.285662Z","caller":"traceutil/trace.go:171","msg":"trace[953434661] transaction","detail":"{read_only:false; response_revision:22219; number_of_response:1; }","duration":"103.1118ms","start":"2026-04-20T18:19:40.182526Z","end":"2026-04-20T18:19:40.285638Z","steps":["trace[953434661] 'process raft request' (duration: 102.946527ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:19:40.399653Z","caller":"traceutil/trace.go:171","msg":"trace[1210865601] transaction","detail":"{read_only:false; response_revision:22220; number_of_response:1; }","duration":"211.400759ms","start":"2026-04-20T18:19:40.188231Z","end":"2026-04-20T18:19:40.399632Z","steps":["trace[1210865601] 'process raft request' (duration: 209.910941ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:03.988875Z","caller":"traceutil/trace.go:171","msg":"trace[1645016408] transaction","detail":"{read_only:false; response_revision:22408; number_of_response:1; }","duration":"107.531604ms","start":"2026-04-20T18:20:03.881317Z","end":"2026-04-20T18:20:03.988849Z","steps":["trace[1645016408] 'process raft request' (duration: 107.29016ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:04.044725Z","caller":"traceutil/trace.go:171","msg":"trace[2028499229] transaction","detail":"{read_only:false; response_revision:22409; number_of_response:1; }","duration":"105.852482ms","start":"2026-04-20T18:20:03.938839Z","end":"2026-04-20T18:20:04.044692Z","steps":["trace[2028499229] 'process raft request' (duration: 105.592798ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:04.047231Z","caller":"traceutil/trace.go:171","msg":"trace[994308255] transaction","detail":"{read_only:false; response_revision:22410; number_of_response:1; }","duration":"106.69017ms","start":"2026-04-20T18:20:03.940505Z","end":"2026-04-20T18:20:04.047195Z","steps":["trace[994308255] 'process raft request' (duration: 104.171422ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:04.047509Z","caller":"traceutil/trace.go:171","msg":"trace[961861012] transaction","detail":"{read_only:false; response_revision:22411; number_of_response:1; }","duration":"104.225983ms","start":"2026-04-20T18:20:03.943257Z","end":"2026-04-20T18:20:04.047483Z","steps":["trace[961861012] 'process raft request' (duration: 103.871086ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:04.047675Z","caller":"traceutil/trace.go:171","msg":"trace[301083877] linearizableReadLoop","detail":"{readStateIndex:23088; appliedIndex:23086; }","duration":"121.595379ms","start":"2026-04-20T18:20:03.926059Z","end":"2026-04-20T18:20:04.047655Z","steps":["trace[301083877] 'read index received' (duration: 62.637469ms)","trace[301083877] 'applied index is now lower than readState.Index' (duration: 58.95643ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:20:04.047911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.745392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T18:20:04.048306Z","caller":"traceutil/trace.go:171","msg":"trace[1906812796] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:22412; }","duration":"122.260181ms","start":"2026-04-20T18:20:03.926021Z","end":"2026-04-20T18:20:04.048281Z","steps":["trace[1906812796] 'agreement among raft nodes before linearized reading' (duration: 121.715711ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:08.161019Z","caller":"traceutil/trace.go:171","msg":"trace[678193300] transaction","detail":"{read_only:false; response_revision:22445; number_of_response:1; }","duration":"104.901094ms","start":"2026-04-20T18:20:08.056099Z","end":"2026-04-20T18:20:08.161Z","steps":["trace[678193300] 'process raft request' (duration: 104.71745ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:08.161388Z","caller":"traceutil/trace.go:171","msg":"trace[102629867] transaction","detail":"{read_only:false; response_revision:22447; number_of_response:1; }","duration":"100.027572ms","start":"2026-04-20T18:20:08.061331Z","end":"2026-04-20T18:20:08.161358Z","steps":["trace[102629867] 'process raft request' (duration: 99.869529ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:08.16143Z","caller":"traceutil/trace.go:171","msg":"trace[752505554] transaction","detail":"{read_only:false; response_revision:22446; number_of_response:1; }","duration":"101.231115ms","start":"2026-04-20T18:20:08.060174Z","end":"2026-04-20T18:20:08.161405Z","steps":["trace[752505554] 'process raft request' (duration: 100.782917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:40.381853Z","caller":"traceutil/trace.go:171","msg":"trace[2136549725] transaction","detail":"{read_only:false; response_revision:22695; number_of_response:1; }","duration":"100.277108ms","start":"2026-04-20T18:20:40.281488Z","end":"2026-04-20T18:20:40.381765Z","steps":["trace[2136549725] 'process raft request' (duration: 81.80902ms)","trace[2136549725] 'compare' (duration: 18.164473ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:20:40.440396Z","caller":"traceutil/trace.go:171","msg":"trace[1997878123] transaction","detail":"{read_only:false; response_revision:22696; number_of_response:1; }","duration":"158.685467ms","start":"2026-04-20T18:20:40.28168Z","end":"2026-04-20T18:20:40.440365Z","steps":["trace[1997878123] 'process raft request' (duration: 158.451843ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:40.440694Z","caller":"traceutil/trace.go:171","msg":"trace[259497687] transaction","detail":"{read_only:false; response_revision:22697; number_of_response:1; }","duration":"158.792599ms","start":"2026-04-20T18:20:40.281854Z","end":"2026-04-20T18:20:40.440647Z","steps":["trace[259497687] 'process raft request' (duration: 158.455323ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:40.643698Z","caller":"traceutil/trace.go:171","msg":"trace[2078148691] transaction","detail":"{read_only:false; response_revision:22698; number_of_response:1; }","duration":"149.596515ms","start":"2026-04-20T18:20:40.494075Z","end":"2026-04-20T18:20:40.643671Z","steps":["trace[2078148691] 'process raft request' (duration: 149.411271ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:40.873352Z","caller":"traceutil/trace.go:171","msg":"trace[1598927825] linearizableReadLoop","detail":"{readStateIndex:23389; appliedIndex:23387; }","duration":"140.246459ms","start":"2026-04-20T18:20:40.733078Z","end":"2026-04-20T18:20:40.873325Z","steps":["trace[1598927825] 'read index received' (duration: 66.537832ms)","trace[1598927825] 'applied index is now lower than readState.Index' (duration: 73.707717ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:20:40.873642Z","caller":"traceutil/trace.go:171","msg":"trace[2011305055] transaction","detail":"{read_only:false; response_revision:22700; number_of_response:1; }","duration":"211.674333ms","start":"2026-04-20T18:20:40.661919Z","end":"2026-04-20T18:20:40.873594Z","steps":["trace[2011305055] 'process raft request' (duration: 137.717412ms)","trace[2011305055] 'compare' (duration: 73.394781ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:20:40.873691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.787623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T18:20:40.873758Z","caller":"traceutil/trace.go:171","msg":"trace[1442384857] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:22701; }","duration":"120.918647ms","start":"2026-04-20T18:20:40.752821Z","end":"2026-04-20T18:20:40.87374Z","steps":["trace[1442384857] 'agreement among raft nodes before linearized reading' (duration: 120.770263ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:40.874104Z","caller":"traceutil/trace.go:171","msg":"trace[694210779] transaction","detail":"{read_only:false; response_revision:22701; number_of_response:1; }","duration":"207.699898ms","start":"2026-04-20T18:20:40.666395Z","end":"2026-04-20T18:20:40.874095Z","steps":["trace[694210779] 'process raft request' (duration: 206.867622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:20:40.874995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.8925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-20T18:20:40.875056Z","caller":"traceutil/trace.go:171","msg":"trace[84226995] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:22701; }","duration":"142.019273ms","start":"2026-04-20T18:20:40.733022Z","end":"2026-04-20T18:20:40.875042Z","steps":["trace[84226995] 'agreement among raft nodes before linearized reading' (duration: 140.496524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:20:41.146893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.846916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T18:20:41.147016Z","caller":"traceutil/trace.go:171","msg":"trace[1111106161] transaction","detail":"{read_only:false; response_revision:22702; number_of_response:1; }","duration":"269.39967ms","start":"2026-04-20T18:20:40.877594Z","end":"2026-04-20T18:20:41.146994Z","steps":["trace[1111106161] 'process raft request' (duration: 124.312759ms)","trace[1111106161] 'compare' (duration: 144.611572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:20:41.425523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.387333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T18:20:41.426086Z","caller":"traceutil/trace.go:171","msg":"trace[862162279] transaction","detail":"{read_only:false; response_revision:22703; number_of_response:1; }","duration":"547.433174ms","start":"2026-04-20T18:20:40.878631Z","end":"2026-04-20T18:20:41.426064Z","steps":["trace[862162279] 'process raft request' (duration: 382.411208ms)","trace[862162279] 'compare' (duration: 164.261081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:20:41.426165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:20:40.878608Z","time spent":"547.519885ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:20:41.426146Z","caller":"traceutil/trace.go:171","msg":"trace[29405812] transaction","detail":"{read_only:false; response_revision:22704; number_of_response:1; }","duration":"547.448844ms","start":"2026-04-20T18:20:40.878651Z","end":"2026-04-20T18:20:41.4261Z","steps":["trace[29405812] 'process raft request' (duration: 546.977815ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:20:41.426096Z","caller":"traceutil/trace.go:171","msg":"trace[983960405] transaction","detail":"{read_only:false; response_revision:22705; number_of_response:1; }","duration":"547.025866ms","start":"2026-04-20T18:20:40.878994Z","end":"2026-04-20T18:20:41.42602Z","steps":["trace[983960405] 'process raft request' (duration: 546.69062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:20:41.426397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:20:40.878618Z","time spent":"547.624077ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T18:20:41.426651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:20:40.878983Z","time spent":"547.470264ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T18:21:12.604017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.667662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T18:21:12.604142Z","caller":"traceutil/trace.go:171","msg":"trace[410068385] transaction","detail":"{read_only:false; response_revision:22929; number_of_response:1; }","duration":"244.020052ms","start":"2026-04-20T18:21:12.360102Z","end":"2026-04-20T18:21:12.604122Z","steps":["trace[410068385] 'process raft request' (duration: 115.163337ms)","trace[410068385] 'compare' (duration: 128.534909ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:21:12.615666Z","caller":"traceutil/trace.go:171","msg":"trace[1379528641] transaction","detail":"{read_only:false; response_revision:22930; number_of_response:1; }","duration":"112.589869ms","start":"2026-04-20T18:21:12.503046Z","end":"2026-04-20T18:21:12.615636Z","steps":["trace[1379528641] 'process raft request' (duration: 112.442326ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:12.837116Z","caller":"traceutil/trace.go:171","msg":"trace[495944940] transaction","detail":"{read_only:false; response_revision:22931; number_of_response:1; }","duration":"272.782414ms","start":"2026-04-20T18:21:12.564319Z","end":"2026-04-20T18:21:12.837102Z","steps":["trace[495944940] 'process raft request' (duration: 272.530959ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:12.837057Z","caller":"traceutil/trace.go:171","msg":"trace[2096705116] transaction","detail":"{read_only:false; response_revision:22932; number_of_response:1; }","duration":"265.69359ms","start":"2026-04-20T18:21:12.571341Z","end":"2026-04-20T18:21:12.837035Z","steps":["trace[2096705116] 'process raft request' (duration: 265.658689ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:12.974511Z","caller":"traceutil/trace.go:171","msg":"trace[350818986] transaction","detail":"{read_only:false; response_revision:22934; number_of_response:1; }","duration":"217.878241ms","start":"2026-04-20T18:21:12.756613Z","end":"2026-04-20T18:21:12.974491Z","steps":["trace[350818986] 'process raft request' (duration: 217.792698ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:12.974565Z","caller":"traceutil/trace.go:171","msg":"trace[43456991] transaction","detail":"{read_only:false; response_revision:22933; number_of_response:1; }","duration":"218.39915ms","start":"2026-04-20T18:21:12.756115Z","end":"2026-04-20T18:21:12.974515Z","steps":["trace[43456991] 'process raft request' (duration: 152.400748ms)","trace[43456991] 'compare' (duration: 65.587744ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:21:13.138204Z","caller":"traceutil/trace.go:171","msg":"trace[1020473507] transaction","detail":"{read_only:false; response_revision:22935; number_of_response:1; }","duration":"156.477715ms","start":"2026-04-20T18:21:12.981691Z","end":"2026-04-20T18:21:13.138169Z","steps":["trace[1020473507] 'process raft request' (duration: 155.518617ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:13.236342Z","caller":"traceutil/trace.go:171","msg":"trace[2076113844] linearizableReadLoop","detail":"{readStateIndex:23630; appliedIndex:23628; }","duration":"247.162621ms","start":"2026-04-20T18:21:12.98915Z","end":"2026-04-20T18:21:13.236312Z","steps":["trace[2076113844] 'read index received' (duration: 148.131568ms)","trace[2076113844] 'applied index is now lower than readState.Index' (duration: 99.030003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:21:13.23652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.350315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T18:21:13.236567Z","caller":"traceutil/trace.go:171","msg":"trace[1523222704] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:22937; }","duration":"247.440227ms","start":"2026-04-20T18:21:12.989114Z","end":"2026-04-20T18:21:13.236554Z","steps":["trace[1523222704] 'agreement among raft nodes before linearized reading' (duration: 247.347525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:21:13.236659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.779559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:21:13.236631Z","caller":"traceutil/trace.go:171","msg":"trace[498833360] transaction","detail":"{read_only:false; response_revision:22937; number_of_response:1; }","duration":"233.054486ms","start":"2026-04-20T18:21:13.003474Z","end":"2026-04-20T18:21:13.236529Z","steps":["trace[498833360] 'process raft request' (duration: 232.785501ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:13.236728Z","caller":"traceutil/trace.go:171","msg":"trace[1237491080] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:22937; }","duration":"234.884871ms","start":"2026-04-20T18:21:13.001817Z","end":"2026-04-20T18:21:13.236702Z","steps":["trace[1237491080] 'agreement among raft nodes before linearized reading' (duration: 234.79842ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:13.23638Z","caller":"traceutil/trace.go:171","msg":"trace[1373821882] transaction","detail":"{read_only:false; response_revision:22936; number_of_response:1; }","duration":"253.854448ms","start":"2026-04-20T18:21:12.982501Z","end":"2026-04-20T18:21:13.236355Z","steps":["trace[1373821882] 'process raft request' (duration: 253.620314ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:41.329992Z","caller":"traceutil/trace.go:171","msg":"trace[1462758406] transaction","detail":"{read_only:false; response_revision:23138; number_of_response:1; }","duration":"372.499941ms","start":"2026-04-20T18:21:40.957461Z","end":"2026-04-20T18:21:41.329961Z","steps":["trace[1462758406] 'process raft request' (duration: 372.341757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:21:41.330397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:21:40.957433Z","time spent":"372.674964ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:21:41.33173Z","caller":"traceutil/trace.go:171","msg":"trace[7549743] transaction","detail":"{read_only:false; response_revision:23139; number_of_response:1; }","duration":"354.44847ms","start":"2026-04-20T18:21:40.977267Z","end":"2026-04-20T18:21:41.331716Z","steps":["trace[7549743] 'process raft request' (duration: 353.910031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:21:41.33192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:21:40.977228Z","time spent":"354.548943ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:21:41.332255Z","caller":"traceutil/trace.go:171","msg":"trace[1907958935] transaction","detail":"{read_only:false; response_revision:23140; number_of_response:1; }","duration":"252.719536ms","start":"2026-04-20T18:21:41.079523Z","end":"2026-04-20T18:21:41.332242Z","steps":["trace[1907958935] 'process raft request' (duration: 251.713188ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:41.332622Z","caller":"traceutil/trace.go:171","msg":"trace[1438587946] transaction","detail":"{read_only:false; response_revision:23141; number_of_response:1; }","duration":"251.85755ms","start":"2026-04-20T18:21:41.080747Z","end":"2026-04-20T18:21:41.332605Z","steps":["trace[1438587946] 'process raft request' (duration: 250.524765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:21:49.942154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.84771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T18:21:49.942393Z","caller":"traceutil/trace.go:171","msg":"trace[41723863] transaction","detail":"{read_only:false; response_revision:23208; number_of_response:1; }","duration":"185.627799ms","start":"2026-04-20T18:21:49.756687Z","end":"2026-04-20T18:21:49.942315Z","steps":["trace[41723863] 'process raft request' (duration: 79.468233ms)","trace[41723863] 'compare' (duration: 105.697548ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:21:49.942399Z","caller":"traceutil/trace.go:171","msg":"trace[271183944] linearizableReadLoop","detail":"{readStateIndex:23911; appliedIndex:23909; }","duration":"142.371106ms","start":"2026-04-20T18:21:49.800008Z","end":"2026-04-20T18:21:49.942379Z","steps":["trace[271183944] 'read index received' (duration: 36.156039ms)","trace[271183944] 'applied index is now lower than readState.Index' (duration: 106.213997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:21:49.942481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.459739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:21:49.9425Z","caller":"traceutil/trace.go:171","msg":"trace[1638589137] transaction","detail":"{read_only:false; response_revision:23209; number_of_response:1; }","duration":"180.801449ms","start":"2026-04-20T18:21:49.761668Z","end":"2026-04-20T18:21:49.942469Z","steps":["trace[1638589137] 'process raft request' (duration: 180.626836ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:49.942533Z","caller":"traceutil/trace.go:171","msg":"trace[404605016] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:23209; }","duration":"142.55269ms","start":"2026-04-20T18:21:49.799963Z","end":"2026-04-20T18:21:49.942516Z","steps":["trace[404605016] 'agreement among raft nodes before linearized reading' (duration: 142.469328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:21:50.162763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.282124ms","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-04-20T18:21:50.162839Z","caller":"traceutil/trace.go:171","msg":"trace[1440066233] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23209; }","duration":"118.389567ms","start":"2026-04-20T18:21:50.044435Z","end":"2026-04-20T18:21:50.162825Z","steps":["trace[1440066233] 'range keys from in-memory index tree' (duration: 118.128321ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:55.914635Z","caller":"traceutil/trace.go:171","msg":"trace[1991672231] linearizableReadLoop","detail":"{readStateIndex:23952; appliedIndex:23951; }","duration":"131.772107ms","start":"2026-04-20T18:21:55.78283Z","end":"2026-04-20T18:21:55.914603Z","steps":["trace[1991672231] 'read index received' (duration: 131.432281ms)","trace[1991672231] 'applied index is now lower than readState.Index' (duration: 338.506µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:21:55.91509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.207235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-20T18:21:55.915154Z","caller":"traceutil/trace.go:171","msg":"trace[1688939145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23248; }","duration":"132.318167ms","start":"2026-04-20T18:21:55.782816Z","end":"2026-04-20T18:21:55.915134Z","steps":["trace[1688939145] 'agreement among raft nodes before linearized reading' (duration: 131.93261ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:55.915354Z","caller":"traceutil/trace.go:171","msg":"trace[1418872699] transaction","detail":"{read_only:false; response_revision:23248; number_of_response:1; }","duration":"133.299586ms","start":"2026-04-20T18:21:55.782039Z","end":"2026-04-20T18:21:55.915339Z","steps":["trace[1418872699] 'process raft request' (duration: 132.341378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:56.059064Z","caller":"traceutil/trace.go:171","msg":"trace[803217898] transaction","detail":"{read_only:false; response_revision:23249; number_of_response:1; }","duration":"138.83237ms","start":"2026-04-20T18:21:55.920166Z","end":"2026-04-20T18:21:56.058998Z","steps":["trace[803217898] 'process raft request' (duration: 130.672346ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:56.205969Z","caller":"traceutil/trace.go:171","msg":"trace[1827557161] transaction","detail":"{read_only:false; response_revision:23251; number_of_response:1; }","duration":"142.287275ms","start":"2026-04-20T18:21:56.063642Z","end":"2026-04-20T18:21:56.20593Z","steps":["trace[1827557161] 'process raft request' (duration: 122.791599ms)","trace[1827557161] 'compare' (duration: 19.14423ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:21:58.717304Z","caller":"traceutil/trace.go:171","msg":"trace[1333437686] transaction","detail":"{read_only:false; response_revision:23269; number_of_response:1; }","duration":"114.647315ms","start":"2026-04-20T18:21:58.602631Z","end":"2026-04-20T18:21:58.717278Z","steps":["trace[1333437686] 'process raft request' (duration: 114.547614ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:21:58.717335Z","caller":"traceutil/trace.go:171","msg":"trace[676355204] transaction","detail":"{read_only:false; response_revision:23268; number_of_response:1; }","duration":"167.619111ms","start":"2026-04-20T18:21:58.549663Z","end":"2026-04-20T18:21:58.717282Z","steps":["trace[676355204] 'process raft request' (duration: 112.564576ms)","trace[676355204] 'compare' (duration: 54.757739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:22:02.361902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.92192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T18:22:02.361983Z","caller":"traceutil/trace.go:171","msg":"trace[1867073556] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:23294; }","duration":"398.051093ms","start":"2026-04-20T18:22:01.963914Z","end":"2026-04-20T18:22:02.361965Z","steps":["trace[1867073556] 'count revisions from in-memory index tree' (duration: 397.846649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:02.362032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:01.963899Z","time spent":"398.121744ms","remote":"127.0.0.1:60328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":32,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-20T18:22:02.362535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.728623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T18:22:02.362601Z","caller":"traceutil/trace.go:171","msg":"trace[1286295182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23294; }","duration":"134.836976ms","start":"2026-04-20T18:22:02.22775Z","end":"2026-04-20T18:22:02.362587Z","steps":["trace[1286295182] 'range keys from in-memory index tree' (duration: 134.57008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:02.362728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.222179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-20T18:22:02.362799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.199549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:22:02.362827Z","caller":"traceutil/trace.go:171","msg":"trace[234695037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23294; }","duration":"279.395093ms","start":"2026-04-20T18:22:02.083405Z","end":"2026-04-20T18:22:02.3628Z","steps":["trace[234695037] 'range keys from in-memory index tree' (duration: 278.963544ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:02.362844Z","caller":"traceutil/trace.go:171","msg":"trace[420188438] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:23294; }","duration":"146.28038ms","start":"2026-04-20T18:22:02.21655Z","end":"2026-04-20T18:22:02.36283Z","steps":["trace[420188438] 'count revisions from in-memory index tree' (duration: 146.103507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:02.363038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.412439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T18:22:02.363179Z","caller":"traceutil/trace.go:171","msg":"trace[988752119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23294; }","duration":"215.584723ms","start":"2026-04-20T18:22:02.147561Z","end":"2026-04-20T18:22:02.363146Z","steps":["trace[988752119] 'range keys from in-memory index tree' (duration: 215.232906ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:05.539773Z","caller":"traceutil/trace.go:171","msg":"trace[393207481] transaction","detail":"{read_only:false; response_revision:23318; number_of_response:1; }","duration":"127.172461ms","start":"2026-04-20T18:22:05.412568Z","end":"2026-04-20T18:22:05.53974Z","steps":["trace[393207481] 'process raft request' (duration: 127.001868ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:05.589453Z","caller":"traceutil/trace.go:171","msg":"trace[1545452759] linearizableReadLoop","detail":"{readStateIndex:24029; appliedIndex:24023; }","duration":"142.801655ms","start":"2026-04-20T18:22:05.446636Z","end":"2026-04-20T18:22:05.589438Z","steps":["trace[1545452759] 'read index received' (duration: 92.963529ms)","trace[1545452759] 'applied index is now lower than readState.Index' (duration: 49.837476ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:22:05.589469Z","caller":"traceutil/trace.go:171","msg":"trace[1868466771] transaction","detail":"{read_only:false; response_revision:23319; number_of_response:1; }","duration":"175.415407ms","start":"2026-04-20T18:22:05.414006Z","end":"2026-04-20T18:22:05.589422Z","steps":["trace[1868466771] 'process raft request' (duration: 150.762995ms)","trace[1868466771] 'compare' (duration: 24.433578ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:22:05.589539Z","caller":"traceutil/trace.go:171","msg":"trace[1714591315] transaction","detail":"{read_only:false; response_revision:23322; number_of_response:1; }","duration":"160.12677ms","start":"2026-04-20T18:22:05.429406Z","end":"2026-04-20T18:22:05.589533Z","steps":["trace[1714591315] 'process raft request' (duration: 159.975768ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:05.589588Z","caller":"traceutil/trace.go:171","msg":"trace[1491176695] transaction","detail":"{read_only:false; response_revision:23321; number_of_response:1; }","duration":"164.273287ms","start":"2026-04-20T18:22:05.425263Z","end":"2026-04-20T18:22:05.589536Z","steps":["trace[1491176695] 'process raft request' (duration: 164.101714ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:05.589662Z","caller":"traceutil/trace.go:171","msg":"trace[997739590] transaction","detail":"{read_only:false; response_revision:23320; number_of_response:1; }","duration":"164.42651ms","start":"2026-04-20T18:22:05.425193Z","end":"2026-04-20T18:22:05.58962Z","steps":["trace[997739590] 'process raft request' (duration: 164.137485ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:05.589733Z","caller":"traceutil/trace.go:171","msg":"trace[961024030] transaction","detail":"{read_only:false; response_revision:23323; number_of_response:1; }","duration":"159.726873ms","start":"2026-04-20T18:22:05.429975Z","end":"2026-04-20T18:22:05.589702Z","steps":["trace[961024030] 'process raft request' (duration: 159.437797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:05.589807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.056891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:22:05.589901Z","caller":"traceutil/trace.go:171","msg":"trace[1218919042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23323; }","duration":"117.193743ms","start":"2026-04-20T18:22:05.472681Z","end":"2026-04-20T18:22:05.589875Z","steps":["trace[1218919042] 'agreement among raft nodes before linearized reading' (duration: 117.02545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:05.589963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.03808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:22:05.589994Z","caller":"traceutil/trace.go:171","msg":"trace[235093626] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23323; }","duration":"143.392367ms","start":"2026-04-20T18:22:05.446593Z","end":"2026-04-20T18:22:05.589985Z","steps":["trace[235093626] 'agreement among raft nodes before linearized reading' (duration: 142.998108ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:16.71153Z","caller":"traceutil/trace.go:171","msg":"trace[1188706871] transaction","detail":"{read_only:false; response_revision:23400; number_of_response:1; }","duration":"116.381408ms","start":"2026-04-20T18:22:16.595124Z","end":"2026-04-20T18:22:16.711505Z","steps":["trace[1188706871] 'process raft request' (duration: 116.209015ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:16.778104Z","caller":"traceutil/trace.go:171","msg":"trace[252390259] transaction","detail":"{read_only:false; response_revision:23402; number_of_response:1; }","duration":"182.634683ms","start":"2026-04-20T18:22:16.595441Z","end":"2026-04-20T18:22:16.778076Z","steps":["trace[252390259] 'process raft request' (duration: 182.577372ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:16.778155Z","caller":"traceutil/trace.go:171","msg":"trace[546416696] transaction","detail":"{read_only:false; response_revision:23401; number_of_response:1; }","duration":"182.845847ms","start":"2026-04-20T18:22:16.595279Z","end":"2026-04-20T18:22:16.778125Z","steps":["trace[546416696] 'process raft request' (duration: 182.522571ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:16.88752Z","caller":"traceutil/trace.go:171","msg":"trace[390585571] transaction","detail":"{read_only:false; response_revision:23403; number_of_response:1; }","duration":"115.157235ms","start":"2026-04-20T18:22:16.772343Z","end":"2026-04-20T18:22:16.8875Z","steps":["trace[390585571] 'process raft request' (duration: 115.036423ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:21.864329Z","caller":"traceutil/trace.go:171","msg":"trace[1352212120] transaction","detail":"{read_only:false; response_revision:23436; number_of_response:1; }","duration":"192.121862ms","start":"2026-04-20T18:22:21.672173Z","end":"2026-04-20T18:22:21.864295Z","steps":["trace[1352212120] 'process raft request' (duration: 191.947109ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:22.033118Z","caller":"traceutil/trace.go:171","msg":"trace[775431002] transaction","detail":"{read_only:false; response_revision:23438; number_of_response:1; }","duration":"353.487885ms","start":"2026-04-20T18:22:21.679611Z","end":"2026-04-20T18:22:22.033099Z","steps":["trace[775431002] 'process raft request' (duration: 353.427594ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:22.033143Z","caller":"traceutil/trace.go:171","msg":"trace[115276604] transaction","detail":"{read_only:false; response_revision:23437; number_of_response:1; }","duration":"354.082256ms","start":"2026-04-20T18:22:21.679021Z","end":"2026-04-20T18:22:22.033103Z","steps":["trace[115276604] 'process raft request' (duration: 353.869373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:22.03324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:21.679601Z","time spent":"353.582437ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T18:22:22.033323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:21.679Z","time spent":"354.221689ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:22:22.033398Z","caller":"traceutil/trace.go:171","msg":"trace[2015589133] transaction","detail":"{read_only:false; response_revision:23439; number_of_response:1; }","duration":"353.392654ms","start":"2026-04-20T18:22:21.679998Z","end":"2026-04-20T18:22:22.033391Z","steps":["trace[2015589133] 'process raft request' (duration: 353.072278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:22.033436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:21.679965Z","time spent":"353.450284ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:22:22.033442Z","caller":"traceutil/trace.go:171","msg":"trace[422920628] linearizableReadLoop","detail":"{readStateIndex:24150; appliedIndex:24144; }","duration":"348.636073ms","start":"2026-04-20T18:22:21.684785Z","end":"2026-04-20T18:22:22.033422Z","steps":["trace[422920628] 'read index received' (duration: 179.408532ms)","trace[422920628] 'applied index is now lower than readState.Index' (duration: 169.226451ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:22:22.033489Z","caller":"traceutil/trace.go:171","msg":"trace[854987414] transaction","detail":"{read_only:false; response_revision:23440; number_of_response:1; }","duration":"352.19363ms","start":"2026-04-20T18:22:21.681278Z","end":"2026-04-20T18:22:22.033472Z","steps":["trace[854987414] 'process raft request' (duration: 351.969076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:22.033546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:21.681271Z","time spent":"352.246631ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T18:22:22.03356Z","caller":"traceutil/trace.go:171","msg":"trace[46480014] transaction","detail":"{read_only:false; response_revision:23441; number_of_response:1; }","duration":"352.232571ms","start":"2026-04-20T18:22:21.681314Z","end":"2026-04-20T18:22:22.033547Z","steps":["trace[46480014] 'process raft request' (duration: 352.049137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:22.033675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.913145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:22:22.033711Z","caller":"traceutil/trace.go:171","msg":"trace[1414841834] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:23441; }","duration":"269.997316ms","start":"2026-04-20T18:22:21.763702Z","end":"2026-04-20T18:22:22.0337Z","steps":["trace[1414841834] 'agreement among raft nodes before linearized reading' (duration: 269.936424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:22.033758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:21.681302Z","time spent":"352.286531ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T18:22:22.034015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.239496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-20T18:22:22.034047Z","caller":"traceutil/trace.go:171","msg":"trace[1401215878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23441; }","duration":"349.271587ms","start":"2026-04-20T18:22:21.684767Z","end":"2026-04-20T18:22:22.034038Z","steps":["trace[1401215878] 'agreement among raft nodes before linearized reading' (duration: 348.91639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:22.034067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:21.684718Z","time spent":"349.344478ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T18:22:22.237526Z","caller":"traceutil/trace.go:171","msg":"trace[267716808] linearizableReadLoop","detail":"{readStateIndex:24151; appliedIndex:24150; }","duration":"162.340642ms","start":"2026-04-20T18:22:22.075157Z","end":"2026-04-20T18:22:22.237497Z","steps":["trace[267716808] 'read index received' (duration: 98.552123ms)","trace[267716808] 'applied index is now lower than readState.Index' (duration: 63.786969ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:22:22.237741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.566856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-20T18:22:22.237778Z","caller":"traceutil/trace.go:171","msg":"trace[1123899292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23442; }","duration":"162.691288ms","start":"2026-04-20T18:22:22.075075Z","end":"2026-04-20T18:22:22.237766Z","steps":["trace[1123899292] 'agreement among raft nodes before linearized reading' (duration: 162.529185ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:22.237765Z","caller":"traceutil/trace.go:171","msg":"trace[341070900] transaction","detail":"{read_only:false; response_revision:23442; number_of_response:1; }","duration":"198.217165ms","start":"2026-04-20T18:22:22.0395Z","end":"2026-04-20T18:22:22.237717Z","steps":["trace[341070900] 'process raft request' (duration: 134.204512ms)","trace[341070900] 'compare' (duration: 63.639176ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:22:27.093845Z","caller":"traceutil/trace.go:171","msg":"trace[835080332] transaction","detail":"{read_only:false; response_revision:23474; number_of_response:1; }","duration":"236.943725ms","start":"2026-04-20T18:22:26.856878Z","end":"2026-04-20T18:22:27.093822Z","steps":["trace[835080332] 'process raft request' (duration: 215.203406ms)","trace[835080332] 'compare' (duration: 21.406643ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T18:22:27.354069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.047064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T18:22:27.354364Z","caller":"traceutil/trace.go:171","msg":"trace[1909439712] transaction","detail":"{read_only:false; response_revision:23477; number_of_response:1; }","duration":"260.535498ms","start":"2026-04-20T18:22:27.093808Z","end":"2026-04-20T18:22:27.354344Z","steps":["trace[1909439712] 'process raft request' (duration: 260.491377ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:27.354517Z","caller":"traceutil/trace.go:171","msg":"trace[637951567] transaction","detail":"{read_only:false; response_revision:23475; number_of_response:1; }","duration":"497.504943ms","start":"2026-04-20T18:22:26.856973Z","end":"2026-04-20T18:22:27.354478Z","steps":["trace[637951567] 'process raft request' (duration: 365.94793ms)","trace[637951567] 'compare' (duration: 130.778389ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:22:27.354535Z","caller":"traceutil/trace.go:171","msg":"trace[789049669] transaction","detail":"{read_only:false; response_revision:23476; number_of_response:1; }","duration":"437.324311ms","start":"2026-04-20T18:22:26.917168Z","end":"2026-04-20T18:22:27.354492Z","steps":["trace[789049669] 'process raft request' (duration: 437.003625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:27.354693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:26.85695Z","time spent":"497.657576ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T18:22:27.354701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:26.917149Z","time spent":"437.466684ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T18:22:27.354696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.764139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:22:27.354542Z","caller":"traceutil/trace.go:171","msg":"trace[1516184003] linearizableReadLoop","detail":"{readStateIndex:24186; appliedIndex:24183; }","duration":"421.582016ms","start":"2026-04-20T18:22:26.932915Z","end":"2026-04-20T18:22:27.354497Z","steps":["trace[1516184003] 'read index received' (duration: 139.249708ms)","trace[1516184003] 'applied index is now lower than readState.Index' (duration: 282.327278ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:22:27.35476Z","caller":"traceutil/trace.go:171","msg":"trace[155012442] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:23477; }","duration":"421.878641ms","start":"2026-04-20T18:22:26.932867Z","end":"2026-04-20T18:22:27.354746Z","steps":["trace[155012442] 'agreement among raft nodes before linearized reading' (duration: 421.732769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:27.354908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:26.932854Z","time spent":"421.946442ms","remote":"127.0.0.1:60440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-04-20T18:22:27.354983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.518284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-20T18:22:27.354972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.924168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:22:27.355048Z","caller":"traceutil/trace.go:171","msg":"trace[857357140] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:23477; }","duration":"213.621236ms","start":"2026-04-20T18:22:27.141409Z","end":"2026-04-20T18:22:27.35503Z","steps":["trace[857357140] 'agreement among raft nodes before linearized reading' (duration: 213.479713ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:27.35506Z","caller":"traceutil/trace.go:171","msg":"trace[1951795451] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:23477; }","duration":"342.05628ms","start":"2026-04-20T18:22:27.012982Z","end":"2026-04-20T18:22:27.355038Z","steps":["trace[1951795451] 'agreement among raft nodes before linearized reading' (duration: 341.910497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:27.355106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T18:22:27.012965Z","time spent":"342.130452ms","remote":"127.0.0.1:36978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-04-20T18:22:32.666011Z","caller":"traceutil/trace.go:171","msg":"trace[433982326] transaction","detail":"{read_only:false; response_revision:23516; number_of_response:1; }","duration":"126.468128ms","start":"2026-04-20T18:22:32.539514Z","end":"2026-04-20T18:22:32.665982Z","steps":["trace[433982326] 'process raft request' (duration: 126.193123ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:38.885891Z","caller":"traceutil/trace.go:171","msg":"trace[962272102] transaction","detail":"{read_only:false; response_revision:23567; number_of_response:1; }","duration":"133.866567ms","start":"2026-04-20T18:22:38.751994Z","end":"2026-04-20T18:22:38.885861Z","steps":["trace[962272102] 'process raft request' (duration: 49.753896ms)","trace[962272102] 'compare' (duration: 83.987889ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:22:39.278791Z","caller":"traceutil/trace.go:171","msg":"trace[531011259] transaction","detail":"{read_only:false; response_revision:23569; number_of_response:1; }","duration":"123.266907ms","start":"2026-04-20T18:22:39.155496Z","end":"2026-04-20T18:22:39.278763Z","steps":["trace[531011259] 'process raft request' (duration: 123.085304ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:39.576812Z","caller":"traceutil/trace.go:171","msg":"trace[1233086472] transaction","detail":"{read_only:false; response_revision:23571; number_of_response:1; }","duration":"183.191104ms","start":"2026-04-20T18:22:39.393589Z","end":"2026-04-20T18:22:39.57678Z","steps":["trace[1233086472] 'process raft request' (duration: 112.076297ms)","trace[1233086472] 'compare' (duration: 70.980405ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:22:39.587113Z","caller":"traceutil/trace.go:171","msg":"trace[2097172840] transaction","detail":"{read_only:false; response_revision:23572; number_of_response:1; }","duration":"192.683852ms","start":"2026-04-20T18:22:39.394396Z","end":"2026-04-20T18:22:39.58708Z","steps":["trace[2097172840] 'process raft request' (duration: 192.477947ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:39.587366Z","caller":"traceutil/trace.go:171","msg":"trace[1998610333] linearizableReadLoop","detail":"{readStateIndex:24287; appliedIndex:24283; }","duration":"176.474518ms","start":"2026-04-20T18:22:39.410866Z","end":"2026-04-20T18:22:39.587341Z","steps":["trace[1998610333] 'read index received' (duration: 94.811902ms)","trace[1998610333] 'applied index is now lower than readState.Index' (duration: 81.661716ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T18:22:39.587415Z","caller":"traceutil/trace.go:171","msg":"trace[1410210159] transaction","detail":"{read_only:false; response_revision:23574; number_of_response:1; }","duration":"180.785209ms","start":"2026-04-20T18:22:39.40662Z","end":"2026-04-20T18:22:39.587405Z","steps":["trace[1410210159] 'process raft request' (duration: 180.605876ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:39.587428Z","caller":"traceutil/trace.go:171","msg":"trace[80765999] transaction","detail":"{read_only:false; response_revision:23573; number_of_response:1; }","duration":"185.179681ms","start":"2026-04-20T18:22:39.40222Z","end":"2026-04-20T18:22:39.5874Z","steps":["trace[80765999] 'process raft request' (duration: 184.779944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:39.587598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.720262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T18:22:39.587644Z","caller":"traceutil/trace.go:171","msg":"trace[219832905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23574; }","duration":"176.805383ms","start":"2026-04-20T18:22:39.410823Z","end":"2026-04-20T18:22:39.587629Z","steps":["trace[219832905] 'agreement among raft nodes before linearized reading' (duration: 176.659791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T18:22:39.587763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.411569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T18:22:39.587956Z","caller":"traceutil/trace.go:171","msg":"trace[130089218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23574; }","duration":"115.572283ms","start":"2026-04-20T18:22:39.472237Z","end":"2026-04-20T18:22:39.587809Z","steps":["trace[130089218] 'agreement among raft nodes before linearized reading' (duration: 115.41139ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T18:22:45.53354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21179} {"level":"info","ts":"2026-04-20T18:22:45.685876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21179,"took":"147.819219ms","hash":3924186380,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":33181696,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-20T18:22:45.685953Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3924186380,"revision":21179,"compact-revision":17935} {"level":"info","ts":"2026-04-20T18:27:45.541268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23631} {"level":"info","ts":"2026-04-20T18:27:45.676843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23631,"took":"132.000591ms","hash":2123447314,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":31875072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-20T18:27:45.676921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2123447314,"revision":23631,"compact-revision":21179} {"level":"info","ts":"2026-04-20T18:28:02.531727Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006890.wal"} {"level":"info","ts":"2026-04-20T18:32:45.548153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25865} {"level":"info","ts":"2026-04-20T18:32:45.681245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25865,"took":"124.694824ms","hash":1742763354,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":31924224,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-20T18:32:45.681334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1742763354,"revision":25865,"compact-revision":23631} {"level":"info","ts":"2026-04-20T18:35:00.124356Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T18:35:00.132754Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-20T18:35:00.132843Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}