{"level":"warn","ts":"2026-04-15T18:45:30.316319Z","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-15T18:45:30.316404Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-15T18:45:30.316473Z","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-15T18:45:30.316497Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-15T18:45:30.316521Z","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-15T18:45:30.318399Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-04-15T18:45:30.318719Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-15T18:45:30.324655Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.341934ms"} {"level":"info","ts":"2026-04-15T18:45:30.332117Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-04-15T18:45:30.332197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T18:45:30.332231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-04-15T18:45:30.332249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T18:45:30.332266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-04-15T18:45:30.332314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-04-15T18:45:30.345317Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T18:45:30.349922Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T18:45:30.351598Z","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-15T18:45:30.354755Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T18:45:30.354932Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T18:45:30.355117Z","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-15T18:45:30.355203Z","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-15T18:45:30.355215Z","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-15T18:45:30.355854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-04-15T18:45:30.355966Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-15T18:45:30.359078Z","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-15T18:45:30.359245Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-15T18:45:30.359288Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-15T18:45:30.359312Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T18:45:30.359352Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T18:45:30.632782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T18:45:30.632862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T18:45:30.632888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-04-15T18:45:30.633045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-04-15T18:45:30.633159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-15T18:45:30.633178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-04-15T18:45:30.633199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-15T18:45:30.634125Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T18:45:30.634145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T18:45:30.63411Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T18:45:30.634318Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T18:45:30.634459Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T18:45:30.634545Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T18:45:30.635196Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T18:45:30.635338Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T18:45:30.635378Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T18:45:30.636391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-04-15T18:45:30.63767Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-15T18:45:43.387009Z","caller":"traceutil/trace.go:171","msg":"trace[303951627] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"241.968635ms","start":"2026-04-15T18:45:43.145016Z","end":"2026-04-15T18:45:43.386984Z","steps":["trace[303951627] 'process raft request' (duration: 241.826322ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:46:07.967114Z","caller":"traceutil/trace.go:171","msg":"trace[229312827] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"143.305542ms","start":"2026-04-15T18:46:07.823784Z","end":"2026-04-15T18:46:07.967089Z","steps":["trace[229312827] 'process raft request' (duration: 143.138568ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:46:08.111042Z","caller":"traceutil/trace.go:171","msg":"trace[807911519] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"117.262839ms","start":"2026-04-15T18:46:07.993737Z","end":"2026-04-15T18:46:08.111Z","steps":["trace[807911519] 'process raft request' (duration: 116.497547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:46:08.728103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.054736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-15T18:46:08.728269Z","caller":"traceutil/trace.go:171","msg":"trace[1860074119] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"492.420235ms","start":"2026-04-15T18:46:08.235825Z","end":"2026-04-15T18:46:08.728245Z","steps":["trace[1860074119] 'read index received' (duration: 152.456215ms)","trace[1860074119] 'applied index is now lower than readState.Index' (duration: 339.96225ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:46:08.728309Z","caller":"traceutil/trace.go:171","msg":"trace[756712196] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"550.112807ms","start":"2026-04-15T18:46:08.178157Z","end":"2026-04-15T18:46:08.72827Z","steps":["trace[756712196] 'process raft request' (duration: 210.149567ms)","trace[756712196] 'compare' (duration: 338.817339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T18:46:08.728533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T18:46:08.178135Z","time spent":"550.265762ms","remote":"127.0.0.1:55142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T18:46:08.728618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.773684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-15T18:46:08.728674Z","caller":"traceutil/trace.go:171","msg":"trace[1194420748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"492.879837ms","start":"2026-04-15T18:46:08.235776Z","end":"2026-04-15T18:46:08.728655Z","steps":["trace[1194420748] 'agreement among raft nodes before linearized reading' (duration: 492.601429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:46:08.728675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.789622ms","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-15T18:46:08.72874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T18:46:08.235756Z","time spent":"492.97131ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T18:46:08.728757Z","caller":"traceutil/trace.go:171","msg":"trace[2004012917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"125.939577ms","start":"2026-04-15T18:46:08.602797Z","end":"2026-04-15T18:46:08.728737Z","steps":["trace[2004012917] 'agreement among raft nodes before linearized reading' (duration: 125.602688ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:46:08.910847Z","caller":"traceutil/trace.go:171","msg":"trace[768068775] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"174.669483ms","start":"2026-04-15T18:46:08.736151Z","end":"2026-04-15T18:46:08.91082Z","steps":["trace[768068775] 'read index received' (duration: 32.505231ms)","trace[768068775] 'applied index is now lower than readState.Index' (duration: 142.163272ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:46:08.910985Z","caller":"traceutil/trace.go:171","msg":"trace[160150273] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"175.840636ms","start":"2026-04-15T18:46:08.735133Z","end":"2026-04-15T18:46:08.910973Z","steps":["trace[160150273] 'process raft request' (duration: 141.71051ms)","trace[160150273] 'compare' (duration: 33.808548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T18:46:08.911223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.034224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-15T18:46:08.911318Z","caller":"traceutil/trace.go:171","msg":"trace[450718792] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:625; }","duration":"175.230929ms","start":"2026-04-15T18:46:08.736067Z","end":"2026-04-15T18:46:08.911298Z","steps":["trace[450718792] 'agreement among raft nodes before linearized reading' (duration: 175.009043ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:46:10.046393Z","caller":"traceutil/trace.go:171","msg":"trace[958570438] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"101.590198ms","start":"2026-04-15T18:46:09.944783Z","end":"2026-04-15T18:46:10.046373Z","steps":["trace[958570438] 'process raft request' (duration: 101.451524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:46:25.103402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.759097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T18:46:25.103535Z","caller":"traceutil/trace.go:171","msg":"trace[1345651133] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"193.167211ms","start":"2026-04-15T18:46:24.91035Z","end":"2026-04-15T18:46:25.103517Z","steps":["trace[1345651133] 'read index received' (duration: 84.997924ms)","trace[1345651133] 'applied index is now lower than readState.Index' (duration: 108.167797ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:46:25.103623Z","caller":"traceutil/trace.go:171","msg":"trace[1226416520] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"252.685644ms","start":"2026-04-15T18:46:24.850929Z","end":"2026-04-15T18:46:25.103615Z","steps":["trace[1226416520] 'process raft request' (duration: 144.479805ms)","trace[1226416520] 'compare' (duration: 107.582432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T18:46:25.10394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.420151ms","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-15T18:46:25.104051Z","caller":"traceutil/trace.go:171","msg":"trace[549109555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"117.555336ms","start":"2026-04-15T18:46:24.986465Z","end":"2026-04-15T18:46:25.10402Z","steps":["trace[549109555] 'agreement among raft nodes before linearized reading' (duration: 117.263147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:46:25.104015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.636135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-drfvp\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-15T18:46:25.10394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.138072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-15T18:46:25.104321Z","caller":"traceutil/trace.go:171","msg":"trace[1837583712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"106.604686ms","start":"2026-04-15T18:46:24.997702Z","end":"2026-04-15T18:46:25.104306Z","steps":["trace[1837583712] 'agreement among raft nodes before linearized reading' (duration: 106.06706ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:46:25.104135Z","caller":"traceutil/trace.go:171","msg":"trace[785582488] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-drfvp; range_end:; response_count:1; response_revision:787; }","duration":"193.809889ms","start":"2026-04-15T18:46:24.910291Z","end":"2026-04-15T18:46:25.104101Z","steps":["trace[785582488] 'agreement among raft nodes before linearized reading' (duration: 193.477039ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:47:39.99859Z","caller":"traceutil/trace.go:171","msg":"trace[1571639142] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"184.252508ms","start":"2026-04-15T18:47:39.814316Z","end":"2026-04-15T18:47:39.998568Z","steps":["trace[1571639142] 'read index received' (duration: 184.171645ms)","trace[1571639142] 'applied index is now lower than readState.Index' (duration: 79.673µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:47:39.998684Z","caller":"traceutil/trace.go:171","msg":"trace[1274065754] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"265.291984ms","start":"2026-04-15T18:47:39.733349Z","end":"2026-04-15T18:47:39.998641Z","steps":["trace[1274065754] 'process raft request' (duration: 265.043447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:47:39.998757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.428502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T18:47:39.998786Z","caller":"traceutil/trace.go:171","msg":"trace[1974976922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"184.529525ms","start":"2026-04-15T18:47:39.814248Z","end":"2026-04-15T18:47:39.998778Z","steps":["trace[1974976922] 'agreement among raft nodes before linearized reading' (duration: 184.457073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:47:40.458493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.787664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eef3e4b0-1b75-4abb-ae9a-e63c16d5a3bd\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-15T18:47:40.458588Z","caller":"traceutil/trace.go:171","msg":"trace[1917321946] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eef3e4b0-1b75-4abb-ae9a-e63c16d5a3bd; range_end:; response_count:1; response_revision:1860; }","duration":"150.931948ms","start":"2026-04-15T18:47:40.307635Z","end":"2026-04-15T18:47:40.458567Z","steps":["trace[1917321946] 'range keys from in-memory index tree' (duration: 150.544256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:47:52.862774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.598135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-15T18:47:52.862851Z","caller":"traceutil/trace.go:171","msg":"trace[1805257195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1962; }","duration":"204.737258ms","start":"2026-04-15T18:47:52.658099Z","end":"2026-04-15T18:47:52.862836Z","steps":["trace[1805257195] 'range keys from in-memory index tree' (duration: 204.400519ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:48:31.906541Z","caller":"traceutil/trace.go:171","msg":"trace[1968064301] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"124.478638ms","start":"2026-04-15T18:48:31.782029Z","end":"2026-04-15T18:48:31.906508Z","steps":["trace[1968064301] 'process raft request' (duration: 124.18991ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:49:25.473111Z","caller":"traceutil/trace.go:171","msg":"trace[607854795] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"125.549991ms","start":"2026-04-15T18:49:25.347527Z","end":"2026-04-15T18:49:25.473077Z","steps":["trace[607854795] 'process raft request' (duration: 125.339336ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:49:59.60743Z","caller":"traceutil/trace.go:171","msg":"trace[1194928192] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"132.35915ms","start":"2026-04-15T18:49:59.475033Z","end":"2026-04-15T18:49:59.607393Z","steps":["trace[1194928192] 'process raft request' (duration: 132.096312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:49:59.942507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.128046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T18:49:59.943102Z","caller":"traceutil/trace.go:171","msg":"trace[962663260] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"224.719721ms","start":"2026-04-15T18:49:59.718357Z","end":"2026-04-15T18:49:59.943076Z","steps":["trace[962663260] 'process raft request' (duration: 224.288039ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:49:59.943113Z","caller":"traceutil/trace.go:171","msg":"trace[701876006] transaction","detail":"{read_only:false; response_revision:2855; number_of_response:1; }","duration":"288.015105ms","start":"2026-04-15T18:49:59.655015Z","end":"2026-04-15T18:49:59.94303Z","steps":["trace[701876006] 'process raft request' (duration: 33.223001ms)","trace[701876006] 'compare' (duration: 253.735685ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:52:35.300969Z","caller":"traceutil/trace.go:171","msg":"trace[94297908] linearizableReadLoop","detail":"{readStateIndex:4019; appliedIndex:4018; }","duration":"119.204471ms","start":"2026-04-15T18:52:35.181726Z","end":"2026-04-15T18:52:35.30093Z","steps":["trace[94297908] 'read index received' (duration: 119.020946ms)","trace[94297908] 'applied index is now lower than readState.Index' (duration: 181.965µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:52:35.301096Z","caller":"traceutil/trace.go:171","msg":"trace[1279818928] transaction","detail":"{read_only:false; response_revision:3876; number_of_response:1; }","duration":"210.840402ms","start":"2026-04-15T18:52:35.090213Z","end":"2026-04-15T18:52:35.301054Z","steps":["trace[1279818928] 'process raft request' (duration: 210.535293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:52:35.301327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.025126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-15T18:52:35.30137Z","caller":"traceutil/trace.go:171","msg":"trace[809635055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3876; }","duration":"119.109779ms","start":"2026-04-15T18:52:35.182246Z","end":"2026-04-15T18:52:35.301356Z","steps":["trace[809635055] 'agreement among raft nodes before linearized reading' (duration: 118.935974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:52:35.301475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.051723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-15T18:52:35.30181Z","caller":"traceutil/trace.go:171","msg":"trace[856035611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3876; }","duration":"111.488936ms","start":"2026-04-15T18:52:35.190296Z","end":"2026-04-15T18:52:35.301785Z","steps":["trace[856035611] 'agreement among raft nodes before linearized reading' (duration: 110.927359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:52:35.301997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.212551ms","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-15T18:52:35.302142Z","caller":"traceutil/trace.go:171","msg":"trace[1631286129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3876; }","duration":"120.444867ms","start":"2026-04-15T18:52:35.181671Z","end":"2026-04-15T18:52:35.302115Z","steps":["trace[1631286129] 'agreement among raft nodes before linearized reading' (duration: 119.393887ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:54:00.87134Z","caller":"traceutil/trace.go:171","msg":"trace[1822533839] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"107.243393ms","start":"2026-04-15T18:54:00.764076Z","end":"2026-04-15T18:54:00.871319Z","steps":["trace[1822533839] 'process raft request' (duration: 107.122439ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:54:03.424004Z","caller":"traceutil/trace.go:171","msg":"trace[994707670] linearizableReadLoop","detail":"{readStateIndex:5264; appliedIndex:5262; }","duration":"138.042548ms","start":"2026-04-15T18:54:03.285932Z","end":"2026-04-15T18:54:03.423975Z","steps":["trace[994707670] 'read index received' (duration: 2.212842ms)","trace[994707670] 'applied index is now lower than readState.Index' (duration: 135.828896ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:54:03.424175Z","caller":"traceutil/trace.go:171","msg":"trace[1224487411] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"142.900085ms","start":"2026-04-15T18:54:03.281219Z","end":"2026-04-15T18:54:03.424119Z","steps":["trace[1224487411] 'process raft request' (duration: 52.444764ms)","trace[1224487411] 'compare' (duration: 90.180444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T18:54:03.424385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.401508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-f6p82\" ","response":"range_response_count:1 size:4949"} {"level":"info","ts":"2026-04-15T18:54:03.424493Z","caller":"traceutil/trace.go:171","msg":"trace[372530912] range","detail":"{range_begin:/registry/pods/monitoring/vector-f6p82; range_end:; response_count:1; response_revision:5077; }","duration":"138.570693ms","start":"2026-04-15T18:54:03.285897Z","end":"2026-04-15T18:54:03.424468Z","steps":["trace[372530912] 'agreement among raft nodes before linearized reading' (duration: 138.210043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:54:03.424644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.108753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777421"} {"level":"info","ts":"2026-04-15T18:54:03.424693Z","caller":"traceutil/trace.go:171","msg":"trace[620054628] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5077; }","duration":"105.205356ms","start":"2026-04-15T18:54:03.319475Z","end":"2026-04-15T18:54:03.424681Z","steps":["trace[620054628] 'agreement among raft nodes before linearized reading' (duration: 104.681231ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:54:03.770327Z","caller":"traceutil/trace.go:171","msg":"trace[415434776] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5264; }","duration":"326.788042ms","start":"2026-04-15T18:54:03.4435Z","end":"2026-04-15T18:54:03.770288Z","steps":["trace[415434776] 'read index received' (duration: 326.609217ms)","trace[415434776] 'applied index is now lower than readState.Index' (duration: 177.515µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:54:03.770461Z","caller":"traceutil/trace.go:171","msg":"trace[452943734] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"337.270187ms","start":"2026-04-15T18:54:03.433153Z","end":"2026-04-15T18:54:03.770423Z","steps":["trace[452943734] 'process raft request' (duration: 336.956518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:54:03.770604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T18:54:03.433102Z","time spent":"337.431752ms","remote":"127.0.0.1:55092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5837,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T18:54:03.770674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.158293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-185653ed-dc01-42df-a12a-5c214319131c\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-15T18:54:03.770768Z","caller":"traceutil/trace.go:171","msg":"trace[350715802] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-185653ed-dc01-42df-a12a-5c214319131c; range_end:; response_count:1; response_revision:5078; }","duration":"327.322657ms","start":"2026-04-15T18:54:03.443424Z","end":"2026-04-15T18:54:03.770747Z","steps":["trace[350715802] 'agreement among raft nodes before linearized reading' (duration: 327.001618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:54:03.77082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T18:54:03.443395Z","time spent":"327.41275ms","remote":"127.0.0.1:55092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3622,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-185653ed-dc01-42df-a12a-5c214319131c\" "} {"level":"warn","ts":"2026-04-15T18:54:03.776222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.111482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-04-15T18:54:03.776195Z","caller":"traceutil/trace.go:171","msg":"trace[1476138805] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"146.578398ms","start":"2026-04-15T18:54:03.629583Z","end":"2026-04-15T18:54:03.776161Z","steps":["trace[1476138805] 'process raft request' (duration: 146.378992ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:54:03.776281Z","caller":"traceutil/trace.go:171","msg":"trace[576762969] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5079; }","duration":"258.239226ms","start":"2026-04-15T18:54:03.518028Z","end":"2026-04-15T18:54:03.776267Z","steps":["trace[576762969] 'agreement among raft nodes before linearized reading' (duration: 258.065421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:54:03.776645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.373009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777421"} {"level":"info","ts":"2026-04-15T18:54:03.776724Z","caller":"traceutil/trace.go:171","msg":"trace[1348867770] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5079; }","duration":"164.506842ms","start":"2026-04-15T18:54:03.6122Z","end":"2026-04-15T18:54:03.776707Z","steps":["trace[1348867770] 'agreement among raft nodes before linearized reading' (duration: 164.012318ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:54:05.476869Z","caller":"traceutil/trace.go:171","msg":"trace[28732117] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"101.219834ms","start":"2026-04-15T18:54:05.375615Z","end":"2026-04-15T18:54:05.476835Z","steps":["trace[28732117] 'process raft request' (duration: 17.555563ms)","trace[28732117] 'compare' (duration: 83.480916ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:54:05.483311Z","caller":"traceutil/trace.go:171","msg":"trace[930229830] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"106.432211ms","start":"2026-04-15T18:54:05.376854Z","end":"2026-04-15T18:54:05.483286Z","steps":["trace[930229830] 'process raft request' (duration: 106.331638ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:54:05.483382Z","caller":"traceutil/trace.go:171","msg":"trace[242743400] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"106.525853ms","start":"2026-04-15T18:54:05.376843Z","end":"2026-04-15T18:54:05.483369Z","steps":["trace[242743400] 'process raft request' (duration: 106.249025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:54:22.069847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.341967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T18:54:22.069942Z","caller":"traceutil/trace.go:171","msg":"trace[215307474] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5418; }","duration":"154.47469ms","start":"2026-04-15T18:54:21.915446Z","end":"2026-04-15T18:54:22.069921Z","steps":["trace[215307474] 'count revisions from in-memory index tree' (duration: 154.271145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:54:22.069955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.162848ms","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-15T18:54:22.070041Z","caller":"traceutil/trace.go:171","msg":"trace[1416740608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5418; }","duration":"166.314013ms","start":"2026-04-15T18:54:21.903705Z","end":"2026-04-15T18:54:22.070019Z","steps":["trace[1416740608] 'range keys from in-memory index tree' (duration: 165.970644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:54:22.07015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.930346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T18:54:22.070263Z","caller":"traceutil/trace.go:171","msg":"trace[588652009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5418; }","duration":"138.09876ms","start":"2026-04-15T18:54:21.932141Z","end":"2026-04-15T18:54:22.070239Z","steps":["trace[588652009] 'range keys from in-memory index tree' (duration: 137.715439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T18:54:22.070469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.350843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-15T18:54:22.07052Z","caller":"traceutil/trace.go:171","msg":"trace[1284384323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5418; }","duration":"135.417675ms","start":"2026-04-15T18:54:21.935091Z","end":"2026-04-15T18:54:22.070509Z","steps":["trace[1284384323] 'range keys from in-memory index tree' (duration: 134.632683ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:54:22.217858Z","caller":"traceutil/trace.go:171","msg":"trace[679271408] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"142.881074ms","start":"2026-04-15T18:54:22.074947Z","end":"2026-04-15T18:54:22.217828Z","steps":["trace[679271408] 'process raft request' (duration: 100.785521ms)","trace[679271408] 'compare' (duration: 41.952799ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T18:54:22.218061Z","caller":"traceutil/trace.go:171","msg":"trace[1951732027] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"142.975367ms","start":"2026-04-15T18:54:22.07503Z","end":"2026-04-15T18:54:22.218006Z","steps":["trace[1951732027] 'process raft request' (duration: 142.759521ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T18:55:30.89524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3030} {"level":"info","ts":"2026-04-15T18:55:30.984007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3030,"took":"86.902761ms","hash":2532711896,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31494144,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-15T18:55:30.984099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2532711896,"revision":3030,"compact-revision":-1} {"level":"info","ts":"2026-04-15T19:00:30.902155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6117} {"level":"info","ts":"2026-04-15T19:00:30.999024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6117,"took":"95.172007ms","hash":3757496688,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":21213184,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-15T19:00:30.9991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3757496688,"revision":6117,"compact-revision":3030} {"level":"info","ts":"2026-04-15T19:00:36.858275Z","caller":"traceutil/trace.go:171","msg":"trace[1038910008] linearizableReadLoop","detail":"{readStateIndex:8631; appliedIndex:8630; }","duration":"265.502689ms","start":"2026-04-15T19:00:36.592746Z","end":"2026-04-15T19:00:36.858249Z","steps":["trace[1038910008] 'read index received' (duration: 265.262492ms)","trace[1038910008] 'applied index is now lower than readState.Index' (duration: 239.407µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:00:36.858313Z","caller":"traceutil/trace.go:171","msg":"trace[654014713] transaction","detail":"{read_only:false; response_revision:8342; number_of_response:1; }","duration":"277.193017ms","start":"2026-04-15T19:00:36.581099Z","end":"2026-04-15T19:00:36.858293Z","steps":["trace[654014713] 'process raft request' (duration: 276.984661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:00:36.858452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.668078ms","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:7"} {"level":"info","ts":"2026-04-15T19:00:36.858496Z","caller":"traceutil/trace.go:171","msg":"trace[862810915] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:8342; }","duration":"134.772571ms","start":"2026-04-15T19:00:36.723708Z","end":"2026-04-15T19:00:36.85848Z","steps":["trace[862810915] 'agreement among raft nodes before linearized reading' (duration: 134.684939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:00:36.858503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.733335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9pj64\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-04-15T19:00:36.858642Z","caller":"traceutil/trace.go:171","msg":"trace[1667124812] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9pj64; range_end:; response_count:1; response_revision:8342; }","duration":"265.91596ms","start":"2026-04-15T19:00:36.592704Z","end":"2026-04-15T19:00:36.85862Z","steps":["trace[1667124812] 'agreement among raft nodes before linearized reading' (duration: 265.677383ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:00:48.621536Z","caller":"traceutil/trace.go:171","msg":"trace[1472467892] linearizableReadLoop","detail":"{readStateIndex:8690; appliedIndex:8689; }","duration":"416.101095ms","start":"2026-04-15T19:00:48.2054Z","end":"2026-04-15T19:00:48.621501Z","steps":["trace[1472467892] 'read index received' (duration: 415.989712ms)","trace[1472467892] 'applied index is now lower than readState.Index' (duration: 109.553µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:00:48.621628Z","caller":"traceutil/trace.go:171","msg":"trace[2077239200] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"419.462439ms","start":"2026-04-15T19:00:48.202132Z","end":"2026-04-15T19:00:48.621594Z","steps":["trace[2077239200] 'process raft request' (duration: 419.159721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:00:48.621803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.370923ms","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-15T19:00:48.621806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:00:48.202114Z","time spent":"419.589103ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:00:48.621855Z","caller":"traceutil/trace.go:171","msg":"trace[2028897855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8399; }","duration":"416.490816ms","start":"2026-04-15T19:00:48.205353Z","end":"2026-04-15T19:00:48.621844Z","steps":["trace[2028897855] 'agreement among raft nodes before linearized reading' (duration: 416.292031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:00:48.621901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:00:48.205332Z","time spent":"416.556118ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-15T19:00:48.665051Z","caller":"traceutil/trace.go:171","msg":"trace[994482843] transaction","detail":"{read_only:false; response_revision:8400; number_of_response:1; }","duration":"244.167878ms","start":"2026-04-15T19:00:48.42085Z","end":"2026-04-15T19:00:48.665018Z","steps":["trace[994482843] 'process raft request' (duration: 243.814109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:00:48.665419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.814746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-15T19:00:48.665445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.481747ms","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-15T19:00:48.6655Z","caller":"traceutil/trace.go:171","msg":"trace[1219423688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8400; }","duration":"459.959829ms","start":"2026-04-15T19:00:48.205522Z","end":"2026-04-15T19:00:48.665482Z","steps":["trace[1219423688] 'agreement among raft nodes before linearized reading' (duration: 459.437914ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:00:48.66555Z","caller":"traceutil/trace.go:171","msg":"trace[1751960751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8400; }","duration":"324.649493ms","start":"2026-04-15T19:00:48.340875Z","end":"2026-04-15T19:00:48.665525Z","steps":["trace[1751960751] 'agreement among raft nodes before linearized reading' (duration: 324.176088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:00:48.665553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:00:48.205495Z","time spent":"460.046692ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T19:00:48.665597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:00:48.34085Z","time spent":"324.739504ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-15T19:01:16.898017Z","caller":"traceutil/trace.go:171","msg":"trace[1990154761] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"141.177802ms","start":"2026-04-15T19:01:16.756816Z","end":"2026-04-15T19:01:16.897994Z","steps":["trace[1990154761] 'process raft request' (duration: 140.958066ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:01:16.899141Z","caller":"traceutil/trace.go:171","msg":"trace[923213004] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"141.727007ms","start":"2026-04-15T19:01:16.757396Z","end":"2026-04-15T19:01:16.899123Z","steps":["trace[923213004] 'process raft request' (duration: 141.45171ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:01:17.027975Z","caller":"traceutil/trace.go:171","msg":"trace[744390612] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"123.038651ms","start":"2026-04-15T19:01:16.904905Z","end":"2026-04-15T19:01:17.027943Z","steps":["trace[744390612] 'process raft request' (duration: 122.808295ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:01:17.028879Z","caller":"traceutil/trace.go:171","msg":"trace[1007237181] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8906; }","duration":"111.065184ms","start":"2026-04-15T19:01:16.917798Z","end":"2026-04-15T19:01:17.028863Z","steps":["trace[1007237181] 'read index received' (duration: 109.905071ms)","trace[1007237181] 'applied index is now lower than readState.Index' (duration: 1.159263ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:01:17.029149Z","caller":"traceutil/trace.go:171","msg":"trace[815821372] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"124.040369ms","start":"2026-04-15T19:01:16.905069Z","end":"2026-04-15T19:01:17.02911Z","steps":["trace[815821372] 'process raft request' (duration: 123.68436ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:01:17.029299Z","caller":"traceutil/trace.go:171","msg":"trace[673670171] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"123.595857ms","start":"2026-04-15T19:01:16.905693Z","end":"2026-04-15T19:01:17.029289Z","steps":["trace[673670171] 'process raft request' (duration: 123.122913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:01:17.029468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.644981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:01:17.029503Z","caller":"traceutil/trace.go:171","msg":"trace[2112101255] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8609; }","duration":"111.727223ms","start":"2026-04-15T19:01:16.917766Z","end":"2026-04-15T19:01:17.029493Z","steps":["trace[2112101255] 'agreement among raft nodes before linearized reading' (duration: 111.650751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:01:17.029678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.447976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:01:17.02971Z","caller":"traceutil/trace.go:171","msg":"trace[1117133688] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8609; }","duration":"100.518918ms","start":"2026-04-15T19:01:16.92918Z","end":"2026-04-15T19:01:17.029699Z","steps":["trace[1117133688] 'agreement among raft nodes before linearized reading' (duration: 100.467996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:01:29.604757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.948708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T19:01:29.604903Z","caller":"traceutil/trace.go:171","msg":"trace[50810019] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8716; }","duration":"192.147996ms","start":"2026-04-15T19:01:29.412725Z","end":"2026-04-15T19:01:29.604873Z","steps":["trace[50810019] 'count revisions from in-memory index tree' (duration: 191.848706ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:01:30.299205Z","caller":"traceutil/trace.go:171","msg":"trace[585033877] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"102.37889ms","start":"2026-04-15T19:01:30.196805Z","end":"2026-04-15T19:01:30.299184Z","steps":["trace[585033877] 'process raft request' (duration: 102.249366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:01:30.481673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.482422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210989"} {"level":"info","ts":"2026-04-15T19:01:30.481761Z","caller":"traceutil/trace.go:171","msg":"trace[811345749] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8723; }","duration":"172.633186ms","start":"2026-04-15T19:01:30.309108Z","end":"2026-04-15T19:01:30.481742Z","steps":["trace[811345749] 'range keys from in-memory index tree' (duration: 171.565596ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:01:30.818121Z","caller":"traceutil/trace.go:171","msg":"trace[1134090421] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"235.536776ms","start":"2026-04-15T19:01:30.582553Z","end":"2026-04-15T19:01:30.818089Z","steps":["trace[1134090421] 'process raft request' (duration: 235.221287ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:02:02.749517Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000248b.wal"} {"level":"info","ts":"2026-04-15T19:03:14.255596Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-15T19:03:14.261644Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-15T19:03:14.261745Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-15T19:05:30.908117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8180} {"level":"info","ts":"2026-04-15T19:05:30.970491Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8180,"took":"60.097869ms","hash":694508042,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":29421568,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-15T19:05:30.970562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":694508042,"revision":8180,"compact-revision":6117} {"level":"info","ts":"2026-04-15T19:06:11.592301Z","caller":"traceutil/trace.go:171","msg":"trace[960388995] transaction","detail":"{read_only:false; response_revision:11209; number_of_response:1; }","duration":"116.124002ms","start":"2026-04-15T19:06:11.476144Z","end":"2026-04-15T19:06:11.592268Z","steps":["trace[960388995] 'process raft request' (duration: 101.473341ms)","trace[960388995] 'compare' (duration: 14.392994ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:06:33.836345Z","caller":"traceutil/trace.go:171","msg":"trace[1994177893] transaction","detail":"{read_only:false; response_revision:11332; number_of_response:1; }","duration":"252.554882ms","start":"2026-04-15T19:06:33.583748Z","end":"2026-04-15T19:06:33.836303Z","steps":["trace[1994177893] 'process raft request' (duration: 252.196132ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:10:30.914337Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10785} {"level":"info","ts":"2026-04-15T19:10:30.995518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10785,"took":"78.870969ms","hash":386171625,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":26476544,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-15T19:10:30.995595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":386171625,"revision":10785,"compact-revision":8180} {"level":"info","ts":"2026-04-15T19:13:07.936152Z","caller":"traceutil/trace.go:171","msg":"trace[1787854995] linearizableReadLoop","detail":"{readStateIndex:14440; appliedIndex:14439; }","duration":"103.649544ms","start":"2026-04-15T19:13:07.83247Z","end":"2026-04-15T19:13:07.93612Z","steps":["trace[1787854995] 'read index received' (duration: 103.48111ms)","trace[1787854995] 'applied index is now lower than readState.Index' (duration: 167.224µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:13:07.936724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.22437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T19:13:07.936461Z","caller":"traceutil/trace.go:171","msg":"trace[1907054999] transaction","detail":"{read_only:false; response_revision:13966; number_of_response:1; }","duration":"259.769779ms","start":"2026-04-15T19:13:07.676678Z","end":"2026-04-15T19:13:07.936448Z","steps":["trace[1907054999] 'process raft request' (duration: 259.304046ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:13:07.936814Z","caller":"traceutil/trace.go:171","msg":"trace[1602856109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13966; }","duration":"104.360343ms","start":"2026-04-15T19:13:07.832432Z","end":"2026-04-15T19:13:07.936792Z","steps":["trace[1602856109] 'agreement among raft nodes before linearized reading' (duration: 104.143808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:13:17.126257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.920838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:13:17.126566Z","caller":"traceutil/trace.go:171","msg":"trace[1014159747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14134; }","duration":"313.290778ms","start":"2026-04-15T19:13:16.813237Z","end":"2026-04-15T19:13:17.126528Z","steps":["trace[1014159747] 'range keys from in-memory index tree' (duration: 312.814074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:13:17.126651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:13:16.813208Z","time spent":"313.421731ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-15T19:13:17.12667Z","caller":"traceutil/trace.go:171","msg":"trace[983706460] linearizableReadLoop","detail":"{readStateIndex:14612; appliedIndex:14611; }","duration":"131.228717ms","start":"2026-04-15T19:13:16.995409Z","end":"2026-04-15T19:13:17.126638Z","steps":["trace[983706460] 'read index received' (duration: 110.255756ms)","trace[983706460] 'applied index is now lower than readState.Index' (duration: 20.971541ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:13:17.12669Z","caller":"traceutil/trace.go:171","msg":"trace[503505370] transaction","detail":"{read_only:false; response_revision:14135; number_of_response:1; }","duration":"276.698967ms","start":"2026-04-15T19:13:16.849958Z","end":"2026-04-15T19:13:17.126657Z","steps":["trace[503505370] 'process raft request' (duration: 255.721457ms)","trace[503505370] 'compare' (duration: 20.053004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:13:17.126871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.436772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T19:13:17.12691Z","caller":"traceutil/trace.go:171","msg":"trace[223580137] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:14135; }","duration":"131.545786ms","start":"2026-04-15T19:13:16.99535Z","end":"2026-04-15T19:13:17.126896Z","steps":["trace[223580137] 'agreement among raft nodes before linearized reading' (duration: 131.381951ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:14:56.017455Z","caller":"traceutil/trace.go:171","msg":"trace[1416931046] transaction","detail":"{read_only:false; response_revision:15217; number_of_response:1; }","duration":"128.762758ms","start":"2026-04-15T19:14:55.888661Z","end":"2026-04-15T19:14:56.017424Z","steps":["trace[1416931046] 'process raft request' (duration: 128.559333ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:15:30.92245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12865} {"level":"info","ts":"2026-04-15T19:15:30.997943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12865,"took":"72.932819ms","hash":3053997936,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30253056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-15T19:15:30.998013Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3053997936,"revision":12865,"compact-revision":10785} {"level":"info","ts":"2026-04-15T19:20:30.929952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15438} {"level":"info","ts":"2026-04-15T19:20:31.026121Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15438,"took":"92.855434ms","hash":3628074910,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":32083968,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-15T19:20:31.026337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3628074910,"revision":15438,"compact-revision":12865} {"level":"warn","ts":"2026-04-15T19:20:41.293346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.854522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-d22mq\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-04-15T19:20:41.293427Z","caller":"traceutil/trace.go:171","msg":"trace[1235360139] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-d22mq; range_end:; response_count:1; response_revision:17870; }","duration":"110.943794ms","start":"2026-04-15T19:20:41.182465Z","end":"2026-04-15T19:20:41.293409Z","steps":["trace[1235360139] 'range keys from in-memory index tree' (duration: 110.681447ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:23:47.047734Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-15T19:23:47.057353Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-15T19:23:47.057452Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-15T19:25:30.936505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17780} {"level":"info","ts":"2026-04-15T19:25:31.032046Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17780,"took":"91.921411ms","hash":4202702560,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":34123776,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-15T19:25:31.032112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4202702560,"revision":17780,"compact-revision":15438} {"level":"info","ts":"2026-04-15T19:26:10.655896Z","caller":"traceutil/trace.go:171","msg":"trace[417181380] linearizableReadLoop","detail":"{readStateIndex:21046; appliedIndex:21045; }","duration":"188.574641ms","start":"2026-04-15T19:26:10.467296Z","end":"2026-04-15T19:26:10.655871Z","steps":["trace[417181380] 'read index received' (duration: 188.474589ms)","trace[417181380] 'applied index is now lower than readState.Index' (duration: 99.252µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:26:10.656015Z","caller":"traceutil/trace.go:171","msg":"trace[921038126] transaction","detail":"{read_only:false; response_revision:20365; number_of_response:1; }","duration":"199.714469ms","start":"2026-04-15T19:26:10.456257Z","end":"2026-04-15T19:26:10.655971Z","steps":["trace[921038126] 'process raft request' (duration: 199.448302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:26:10.656232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.912192ms","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-15T19:26:10.656344Z","caller":"traceutil/trace.go:171","msg":"trace[1285885361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20365; }","duration":"189.076617ms","start":"2026-04-15T19:26:10.467248Z","end":"2026-04-15T19:26:10.656325Z","steps":["trace[1285885361] 'agreement among raft nodes before linearized reading' (duration: 188.759217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:26:10.955691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.154383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-15T19:26:10.95581Z","caller":"traceutil/trace.go:171","msg":"trace[1062526765] linearizableReadLoop","detail":"{readStateIndex:21047; appliedIndex:21046; }","duration":"299.821458ms","start":"2026-04-15T19:26:10.655976Z","end":"2026-04-15T19:26:10.955797Z","steps":["trace[1062526765] 'read index received' (duration: 163.50963ms)","trace[1062526765] 'applied index is now lower than readState.Index' (duration: 136.310998ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:26:10.955872Z","caller":"traceutil/trace.go:171","msg":"trace[1892303702] transaction","detail":"{read_only:false; response_revision:20366; number_of_response:1; }","duration":"451.220502ms","start":"2026-04-15T19:26:10.504609Z","end":"2026-04-15T19:26:10.955829Z","steps":["trace[1892303702] 'process raft request' (duration: 314.866663ms)","trace[1892303702] 'compare' (duration: 135.70122ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:26:10.956002Z","caller":"traceutil/trace.go:171","msg":"trace[807785550] transaction","detail":"{read_only:false; response_revision:20367; number_of_response:1; }","duration":"296.092603ms","start":"2026-04-15T19:26:10.65988Z","end":"2026-04-15T19:26:10.955973Z","steps":["trace[807785550] 'process raft request' (duration: 295.891887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:26:10.956051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.435727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-15T19:26:10.956043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:26:10.504586Z","time spent":"451.354826ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:26:10.956091Z","caller":"traceutil/trace.go:171","msg":"trace[746740331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20366; }","duration":"362.51941ms","start":"2026-04-15T19:26:10.59356Z","end":"2026-04-15T19:26:10.956079Z","steps":["trace[746740331] 'agreement among raft nodes before linearized reading' (duration: 362.293454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:26:10.956097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.506339ms","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-15T19:26:10.956115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:26:10.593535Z","time spent":"362.573861ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-15T19:26:10.956141Z","caller":"traceutil/trace.go:171","msg":"trace[1852035721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20366; }","duration":"362.597842ms","start":"2026-04-15T19:26:10.593529Z","end":"2026-04-15T19:26:10.956127Z","steps":["trace[1852035721] 'agreement among raft nodes before linearized reading' (duration: 362.336085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:26:10.956196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:26:10.593506Z","time spent":"362.652193ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T19:26:10.994769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.379912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:26:10.994857Z","caller":"traceutil/trace.go:171","msg":"trace[1865343971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20368; }","duration":"181.537467ms","start":"2026-04-15T19:26:10.813302Z","end":"2026-04-15T19:26:10.994839Z","steps":["trace[1865343971] 'agreement among raft nodes before linearized reading' (duration: 181.385943ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:26:10.994898Z","caller":"traceutil/trace.go:171","msg":"trace[1071326736] transaction","detail":"{read_only:false; response_revision:20368; number_of_response:1; }","duration":"167.089928ms","start":"2026-04-15T19:26:10.827784Z","end":"2026-04-15T19:26:10.994874Z","steps":["trace[1071326736] 'process raft request' (duration: 166.753589ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:26:11.076423Z","caller":"traceutil/trace.go:171","msg":"trace[1542834180] transaction","detail":"{read_only:false; response_revision:20370; number_of_response:1; }","duration":"115.170124ms","start":"2026-04-15T19:26:10.961229Z","end":"2026-04-15T19:26:11.076399Z","steps":["trace[1542834180] 'process raft request' (duration: 115.121383ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:26:11.076801Z","caller":"traceutil/trace.go:171","msg":"trace[1325279164] transaction","detail":"{read_only:false; response_revision:20369; number_of_response:1; }","duration":"116.12879ms","start":"2026-04-15T19:26:10.960634Z","end":"2026-04-15T19:26:11.076763Z","steps":["trace[1325279164] 'process raft request' (duration: 113.529268ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:26:11.463622Z","caller":"traceutil/trace.go:171","msg":"trace[993635663] transaction","detail":"{read_only:false; response_revision:20371; number_of_response:1; }","duration":"201.626423ms","start":"2026-04-15T19:26:11.261967Z","end":"2026-04-15T19:26:11.463594Z","steps":["trace[993635663] 'process raft request' (duration: 201.461348ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:26:11.745353Z","caller":"traceutil/trace.go:171","msg":"trace[1571642287] transaction","detail":"{read_only:false; response_revision:20373; number_of_response:1; }","duration":"390.43036ms","start":"2026-04-15T19:26:11.354891Z","end":"2026-04-15T19:26:11.745321Z","steps":["trace[1571642287] 'process raft request' (duration: 390.333027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:26:11.745556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:26:11.354863Z","time spent":"390.589484ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:26:11.746051Z","caller":"traceutil/trace.go:171","msg":"trace[1717445023] transaction","detail":"{read_only:false; response_revision:20372; number_of_response:1; }","duration":"473.205339ms","start":"2026-04-15T19:26:11.272781Z","end":"2026-04-15T19:26:11.745986Z","steps":["trace[1717445023] 'process raft request' (duration: 472.212721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:26:11.746238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:26:11.272753Z","time spent":"473.376143ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:30:03.745663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.408441ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:30:03.7458Z","caller":"traceutil/trace.go:171","msg":"trace[220223174] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:22702; }","duration":"134.557815ms","start":"2026-04-15T19:30:03.611223Z","end":"2026-04-15T19:30:03.745781Z","steps":["trace[220223174] 'range keys from in-memory index tree' (duration: 134.388161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:30:03.746155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.863453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-15T19:30:03.746381Z","caller":"traceutil/trace.go:171","msg":"trace[1915626203] transaction","detail":"{read_only:false; response_revision:22704; number_of_response:1; }","duration":"308.475061ms","start":"2026-04-15T19:30:03.437885Z","end":"2026-04-15T19:30:03.74636Z","steps":["trace[1915626203] 'process raft request' (duration: 308.394589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:30:03.746467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:30:03.437876Z","time spent":"308.549603ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:30:03.746861Z","caller":"traceutil/trace.go:171","msg":"trace[1563974813] transaction","detail":"{read_only:false; response_revision:22703; number_of_response:1; }","duration":"370.524758ms","start":"2026-04-15T19:30:03.376319Z","end":"2026-04-15T19:30:03.746844Z","steps":["trace[1563974813] 'process raft request' (duration: 78.873423ms)","trace[1563974813] 'compare' (duration: 290.348319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:30:03.746941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:30:03.376301Z","time spent":"370.597641ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:30:03.747004Z","caller":"traceutil/trace.go:171","msg":"trace[947706647] linearizableReadLoop","detail":"{readStateIndex:23461; appliedIndex:23460; }","duration":"318.397406ms","start":"2026-04-15T19:30:03.428588Z","end":"2026-04-15T19:30:03.746985Z","steps":["trace[947706647] 'read index received' (duration: 26.618328ms)","trace[947706647] 'applied index is now lower than readState.Index' (duration: 291.777118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:30:03.747398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.656765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:30:03.747457Z","caller":"traceutil/trace.go:171","msg":"trace[1396874715] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:22704; }","duration":"317.724267ms","start":"2026-04-15T19:30:03.429717Z","end":"2026-04-15T19:30:03.747442Z","steps":["trace[1396874715] 'agreement among raft nodes before linearized reading' (duration: 317.628754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:30:03.74746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-15T19:30:03.747502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:30:03.429713Z","time spent":"317.777158ms","remote":"127.0.0.1:37026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-15T19:30:03.747547Z","caller":"traceutil/trace.go:171","msg":"trace[336772002] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:22704; }","duration":"211.058274ms","start":"2026-04-15T19:30:03.53647Z","end":"2026-04-15T19:30:03.747528Z","steps":["trace[336772002] 'agreement among raft nodes before linearized reading' (duration: 210.90163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:30:03.747598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.37453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager-7698bf4999-rlwvm\" ","response":"range_response_count:1 size:5252"} {"level":"warn","ts":"2026-04-15T19:30:03.747676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.075405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmcontrolplanetemplates.controlplane.cluster.x-k8s.io\" ","response":"range_response_count:1 size:111290"} {"level":"info","ts":"2026-04-15T19:30:03.747722Z","caller":"traceutil/trace.go:171","msg":"trace[2092862143] range","detail":"{range_begin:/registry/pods/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager-7698bf4999-rlwvm; range_end:; response_count:1; response_revision:22704; }","duration":"134.504634ms","start":"2026-04-15T19:30:03.613172Z","end":"2026-04-15T19:30:03.747677Z","steps":["trace[2092862143] 'agreement among raft nodes before linearized reading' (duration: 134.178365ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:30:03.747736Z","caller":"traceutil/trace.go:171","msg":"trace[316950322] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmcontrolplanetemplates.controlplane.cluster.x-k8s.io; range_end:; response_count:1; response_revision:22704; }","duration":"319.155447ms","start":"2026-04-15T19:30:03.428563Z","end":"2026-04-15T19:30:03.747718Z","steps":["trace[316950322] 'agreement among raft nodes before linearized reading' (duration: 318.558011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:30:03.747779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:30:03.42855Z","time spent":"319.219009ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":1,"response size":111311,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmcontrolplanetemplates.controlplane.cluster.x-k8s.io\" "} {"level":"info","ts":"2026-04-15T19:30:30.94277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20163} {"level":"info","ts":"2026-04-15T19:30:31.055711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20163,"took":"108.088572ms","hash":861003896,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":41005056,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-04-15T19:30:31.055783Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":861003896,"revision":20163,"compact-revision":17780} {"level":"info","ts":"2026-04-15T19:33:31.223775Z","caller":"traceutil/trace.go:171","msg":"trace[681024555] linearizableReadLoop","detail":"{readStateIndex:25651; appliedIndex:25649; }","duration":"140.033749ms","start":"2026-04-15T19:33:31.083708Z","end":"2026-04-15T19:33:31.223742Z","steps":["trace[681024555] 'read index received' (duration: 15.32297ms)","trace[681024555] 'applied index is now lower than readState.Index' (duration: 124.709369ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:31.223832Z","caller":"traceutil/trace.go:171","msg":"trace[869145659] transaction","detail":"{read_only:false; response_revision:24838; number_of_response:1; }","duration":"155.448229ms","start":"2026-04-15T19:33:31.068347Z","end":"2026-04-15T19:33:31.223796Z","steps":["trace[869145659] 'process raft request' (duration: 155.209983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:31.224049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.312526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:33:31.224209Z","caller":"traceutil/trace.go:171","msg":"trace[1623855677] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:24838; }","duration":"140.532462ms","start":"2026-04-15T19:33:31.083654Z","end":"2026-04-15T19:33:31.224187Z","steps":["trace[1623855677] 'agreement among raft nodes before linearized reading' (duration: 140.263965ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:31.359146Z","caller":"traceutil/trace.go:171","msg":"trace[550913226] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"239.503789ms","start":"2026-04-15T19:33:31.119613Z","end":"2026-04-15T19:33:31.359117Z","steps":["trace[550913226] 'process raft request' (duration: 234.386069ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:31.359385Z","caller":"traceutil/trace.go:171","msg":"trace[2001117187] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"180.078344ms","start":"2026-04-15T19:33:31.17928Z","end":"2026-04-15T19:33:31.359358Z","steps":["trace[2001117187] 'process raft request' (duration: 179.709954ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:31.359198Z","caller":"traceutil/trace.go:171","msg":"trace[144022855] linearizableReadLoop","detail":"{readStateIndex:25653; appliedIndex:25651; }","duration":"135.29333ms","start":"2026-04-15T19:33:31.223873Z","end":"2026-04-15T19:33:31.359167Z","steps":["trace[144022855] 'read index received' (duration: 130.227982ms)","trace[144022855] 'applied index is now lower than readState.Index' (duration: 5.062848ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:33:31.359706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.810172ms","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-15T19:33:31.359713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.196416ms","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-15T19:33:31.359765Z","caller":"traceutil/trace.go:171","msg":"trace[1635542865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24840; }","duration":"173.923165ms","start":"2026-04-15T19:33:31.185827Z","end":"2026-04-15T19:33:31.35975Z","steps":["trace[1635542865] 'agreement among raft nodes before linearized reading' (duration: 173.673658ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:31.359806Z","caller":"traceutil/trace.go:171","msg":"trace[2039617604] 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:24840; }","duration":"177.399471ms","start":"2026-04-15T19:33:31.182383Z","end":"2026-04-15T19:33:31.359783Z","steps":["trace[2039617604] 'agreement among raft nodes before linearized reading' (duration: 177.190165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:31.359975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.502287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:33:31.360216Z","caller":"traceutil/trace.go:171","msg":"trace[1087395260] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:24840; }","duration":"243.980231ms","start":"2026-04-15T19:33:31.116211Z","end":"2026-04-15T19:33:31.360191Z","steps":["trace[1087395260] 'agreement among raft nodes before linearized reading' (duration: 243.368994ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:31.534655Z","caller":"traceutil/trace.go:171","msg":"trace[891655274] linearizableReadLoop","detail":"{readStateIndex:25657; appliedIndex:25653; }","duration":"152.340445ms","start":"2026-04-15T19:33:31.382279Z","end":"2026-04-15T19:33:31.534619Z","steps":["trace[891655274] 'read index received' (duration: 82.891816ms)","trace[891655274] 'applied index is now lower than readState.Index' (duration: 69.446959ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:31.534865Z","caller":"traceutil/trace.go:171","msg":"trace[796937674] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"236.426764ms","start":"2026-04-15T19:33:31.298411Z","end":"2026-04-15T19:33:31.534838Z","steps":["trace[796937674] 'process raft request' (duration: 236.026893ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:31.534952Z","caller":"traceutil/trace.go:171","msg":"trace[384877249] transaction","detail":"{read_only:false; response_revision:24843; number_of_response:1; }","duration":"201.111688ms","start":"2026-04-15T19:33:31.333809Z","end":"2026-04-15T19:33:31.53492Z","steps":["trace[384877249] 'process raft request' (duration: 200.705097ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:31.534972Z","caller":"traceutil/trace.go:171","msg":"trace[1449398149] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"310.223972ms","start":"2026-04-15T19:33:31.224721Z","end":"2026-04-15T19:33:31.534945Z","steps":["trace[1449398149] 'process raft request' (duration: 240.435363ms)","trace[1449398149] 'compare' (duration: 69.063619ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:33:31.535371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:31.224703Z","time spent":"310.600911ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:31.535022Z","caller":"traceutil/trace.go:171","msg":"trace[1663699861] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"170.122051ms","start":"2026-04-15T19:33:31.364857Z","end":"2026-04-15T19:33:31.534979Z","steps":["trace[1663699861] 'process raft request' (duration: 169.711329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:31.53505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.755806ms","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-15T19:33:31.535433Z","caller":"traceutil/trace.go:171","msg":"trace[1521647399] 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:24844; }","duration":"153.230389ms","start":"2026-04-15T19:33:31.382193Z","end":"2026-04-15T19:33:31.535424Z","steps":["trace[1521647399] 'agreement among raft nodes before linearized reading' (duration: 152.780316ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:31.679949Z","caller":"traceutil/trace.go:171","msg":"trace[1964835675] transaction","detail":"{read_only:false; response_revision:24845; number_of_response:1; }","duration":"140.121321ms","start":"2026-04-15T19:33:31.539793Z","end":"2026-04-15T19:33:31.679914Z","steps":["trace[1964835675] 'process raft request' (duration: 109.701189ms)","trace[1964835675] 'compare' (duration: 29.770415ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:31.680033Z","caller":"traceutil/trace.go:171","msg":"trace[1564872334] transaction","detail":"{read_only:false; response_revision:24846; number_of_response:1; }","duration":"140.004848ms","start":"2026-04-15T19:33:31.540002Z","end":"2026-04-15T19:33:31.680007Z","steps":["trace[1564872334] 'process raft request' (duration: 139.576886ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:32.082575Z","caller":"traceutil/trace.go:171","msg":"trace[233529626] transaction","detail":"{read_only:false; response_revision:24848; number_of_response:1; }","duration":"136.228255ms","start":"2026-04-15T19:33:31.946321Z","end":"2026-04-15T19:33:32.08255Z","steps":["trace[233529626] 'process raft request' (duration: 136.03508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:32.497433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.439785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-04-15T19:33:32.497514Z","caller":"traceutil/trace.go:171","msg":"trace[1301483598] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:24849; }","duration":"206.558338ms","start":"2026-04-15T19:33:32.290936Z","end":"2026-04-15T19:33:32.497494Z","steps":["trace[1301483598] 'range keys from in-memory index tree' (duration: 206.27376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:32.497858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.543409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130341"} {"level":"info","ts":"2026-04-15T19:33:32.497894Z","caller":"traceutil/trace.go:171","msg":"trace[1772137611] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24849; }","duration":"198.612451ms","start":"2026-04-15T19:33:32.299271Z","end":"2026-04-15T19:33:32.497884Z","steps":["trace[1772137611] 'range keys from in-memory index tree' (duration: 198.374544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:32.498159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.263563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-15T19:33:32.498227Z","caller":"traceutil/trace.go:171","msg":"trace[198883063] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24849; }","duration":"144.396208ms","start":"2026-04-15T19:33:32.353812Z","end":"2026-04-15T19:33:32.498208Z","steps":["trace[198883063] 'range keys from in-memory index tree' (duration: 144.070459ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:32.776075Z","caller":"traceutil/trace.go:171","msg":"trace[1634076858] linearizableReadLoop","detail":"{readStateIndex:25663; appliedIndex:25662; }","duration":"254.440825ms","start":"2026-04-15T19:33:32.521599Z","end":"2026-04-15T19:33:32.77604Z","steps":["trace[1634076858] 'read index received' (duration: 254.223669ms)","trace[1634076858] 'applied index is now lower than readState.Index' (duration: 215.966µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:32.776131Z","caller":"traceutil/trace.go:171","msg":"trace[2128688348] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"264.246665ms","start":"2026-04-15T19:33:32.511844Z","end":"2026-04-15T19:33:32.776091Z","steps":["trace[2128688348] 'process raft request' (duration: 263.986178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:32.776311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.693243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130341"} {"level":"info","ts":"2026-04-15T19:33:32.77637Z","caller":"traceutil/trace.go:171","msg":"trace[1023383739] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24850; }","duration":"254.817537ms","start":"2026-04-15T19:33:32.521534Z","end":"2026-04-15T19:33:32.776351Z","steps":["trace[1023383739] 'agreement among raft nodes before linearized reading' (duration: 254.631702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:33.060111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.589216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-15T19:33:33.06034Z","caller":"traceutil/trace.go:171","msg":"trace[259566741] linearizableReadLoop","detail":"{readStateIndex:25665; appliedIndex:25663; }","duration":"284.17595ms","start":"2026-04-15T19:33:32.776146Z","end":"2026-04-15T19:33:33.060322Z","steps":["trace[259566741] 'read index received' (duration: 110.319787ms)","trace[259566741] 'applied index is now lower than readState.Index' (duration: 173.855333ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:33.06038Z","caller":"traceutil/trace.go:171","msg":"trace[1919597213] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"466.175406ms","start":"2026-04-15T19:33:32.594165Z","end":"2026-04-15T19:33:33.06034Z","steps":["trace[1919597213] 'process raft request' (duration: 292.253771ms)","trace[1919597213] 'compare' (duration: 173.243717ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:33.060493Z","caller":"traceutil/trace.go:171","msg":"trace[129828985] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"465.551829ms","start":"2026-04-15T19:33:32.594928Z","end":"2026-04-15T19:33:33.06048Z","steps":["trace[129828985] 'process raft request' (duration: 465.294652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:33.060609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:32.594128Z","time spent":"466.33729ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:33.061177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:32.594893Z","time spent":"465.669572ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:33.061249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.097582ms","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-15T19:33:33.061329Z","caller":"traceutil/trace.go:171","msg":"trace[138061981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24852; }","duration":"158.221445ms","start":"2026-04-15T19:33:32.903086Z","end":"2026-04-15T19:33:33.061307Z","steps":["trace[138061981] 'agreement among raft nodes before linearized reading' (duration: 157.962298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:33.061514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.044107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-04-15T19:33:33.061676Z","caller":"traceutil/trace.go:171","msg":"trace[2081405383] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:24852; }","duration":"539.248283ms","start":"2026-04-15T19:33:32.52239Z","end":"2026-04-15T19:33:33.061639Z","steps":["trace[2081405383] 'agreement among raft nodes before linearized reading' (duration: 538.638807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:33.061748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:32.522376Z","time spent":"539.358226ms","remote":"127.0.0.1:55004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-04-15T19:33:40.561856Z","caller":"traceutil/trace.go:171","msg":"trace[621806280] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"409.552278ms","start":"2026-04-15T19:33:40.15226Z","end":"2026-04-15T19:33:40.561812Z","steps":["trace[621806280] 'process raft request' (duration: 409.318912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:40.562109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:40.152236Z","time spent":"409.751264ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:41.216455Z","caller":"traceutil/trace.go:171","msg":"trace[1720935722] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"130.425835ms","start":"2026-04-15T19:33:41.085992Z","end":"2026-04-15T19:33:41.216417Z","steps":["trace[1720935722] 'process raft request' (duration: 130.200259ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:41.2173Z","caller":"traceutil/trace.go:171","msg":"trace[1163641442] linearizableReadLoop","detail":"{readStateIndex:25729; appliedIndex:25727; }","duration":"101.2696ms","start":"2026-04-15T19:33:41.11599Z","end":"2026-04-15T19:33:41.217259Z","steps":["trace[1163641442] 'read index received' (duration: 100.20266ms)","trace[1163641442] 'applied index is now lower than readState.Index' (duration: 1.06584ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:41.217376Z","caller":"traceutil/trace.go:171","msg":"trace[175452410] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"129.029378ms","start":"2026-04-15T19:33:41.088316Z","end":"2026-04-15T19:33:41.217345Z","steps":["trace[175452410] 'process raft request' (duration: 128.755961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:41.217544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.539837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T19:33:41.217592Z","caller":"traceutil/trace.go:171","msg":"trace[1189202162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24914; }","duration":"101.64251ms","start":"2026-04-15T19:33:41.115933Z","end":"2026-04-15T19:33:41.217575Z","steps":["trace[1189202162] 'agreement among raft nodes before linearized reading' (duration: 101.464945ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:41.577051Z","caller":"traceutil/trace.go:171","msg":"trace[1868517746] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"321.47525ms","start":"2026-04-15T19:33:41.255555Z","end":"2026-04-15T19:33:41.57703Z","steps":["trace[1868517746] 'process raft request' (duration: 321.256064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:41.577238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:41.255522Z","time spent":"321.632994ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:41.59829Z","caller":"traceutil/trace.go:171","msg":"trace[827036913] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"227.033368ms","start":"2026-04-15T19:33:41.371223Z","end":"2026-04-15T19:33:41.598257Z","steps":["trace[827036913] 'process raft request' (duration: 226.79498ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:41.598401Z","caller":"traceutil/trace.go:171","msg":"trace[1861404049] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"200.77269ms","start":"2026-04-15T19:33:41.39761Z","end":"2026-04-15T19:33:41.598382Z","steps":["trace[1861404049] 'process raft request' (duration: 200.591035ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:41.881596Z","caller":"traceutil/trace.go:171","msg":"trace[71538354] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"302.376558ms","start":"2026-04-15T19:33:41.57919Z","end":"2026-04-15T19:33:41.881567Z","steps":["trace[71538354] 'process raft request' (duration: 301.301848ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:41.881631Z","caller":"traceutil/trace.go:171","msg":"trace[653939516] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"302.176292ms","start":"2026-04-15T19:33:41.57943Z","end":"2026-04-15T19:33:41.881606Z","steps":["trace[653939516] 'process raft request' (duration: 302.011437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:41.881759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:41.579149Z","time spent":"302.520681ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:41.881781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:41.579419Z","time spent":"302.272685ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:41.881922Z","caller":"traceutil/trace.go:171","msg":"trace[1688751589] linearizableReadLoop","detail":"{readStateIndex:25737; appliedIndex:25734; }","duration":"283.562833ms","start":"2026-04-15T19:33:41.598349Z","end":"2026-04-15T19:33:41.881912Z","steps":["trace[1688751589] 'read index received' (duration: 282.041541ms)","trace[1688751589] 'applied index is now lower than readState.Index' (duration: 1.520222ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:41.881989Z","caller":"traceutil/trace.go:171","msg":"trace[965222830] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"301.871764ms","start":"2026-04-15T19:33:41.580106Z","end":"2026-04-15T19:33:41.881978Z","steps":["trace[965222830] 'process raft request' (duration: 301.453502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:41.882032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:41.58008Z","time spent":"301.924555ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:41.882159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.861526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T19:33:41.882208Z","caller":"traceutil/trace.go:171","msg":"trace[15055218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24922; }","duration":"142.967499ms","start":"2026-04-15T19:33:41.739228Z","end":"2026-04-15T19:33:41.882195Z","steps":["trace[15055218] 'agreement among raft nodes before linearized reading' (duration: 142.795884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:41.882342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.185313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-15T19:33:41.88241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.276046ms","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-15T19:33:41.882449Z","caller":"traceutil/trace.go:171","msg":"trace[461783536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24922; }","duration":"294.345848ms","start":"2026-04-15T19:33:41.588094Z","end":"2026-04-15T19:33:41.88244Z","steps":["trace[461783536] 'agreement among raft nodes before linearized reading' (duration: 294.247446ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:41.882357Z","caller":"traceutil/trace.go:171","msg":"trace[880658089] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:24922; }","duration":"106.233655ms","start":"2026-04-15T19:33:41.776117Z","end":"2026-04-15T19:33:41.88235Z","steps":["trace[880658089] 'agreement among raft nodes before linearized reading' (duration: 106.193554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:41.88286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.987452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-15T19:33:41.882877Z","caller":"traceutil/trace.go:171","msg":"trace[1298667714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24922; }","duration":"142.021014ms","start":"2026-04-15T19:33:41.740849Z","end":"2026-04-15T19:33:41.88287Z","steps":["trace[1298667714] 'agreement among raft nodes before linearized reading' (duration: 141.782207ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:42.032943Z","caller":"traceutil/trace.go:171","msg":"trace[1730889000] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"144.861091ms","start":"2026-04-15T19:33:41.888046Z","end":"2026-04-15T19:33:42.032907Z","steps":["trace[1730889000] 'process raft request' (duration: 134.777245ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:42.033317Z","caller":"traceutil/trace.go:171","msg":"trace[462981061] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"145.17603ms","start":"2026-04-15T19:33:41.88811Z","end":"2026-04-15T19:33:42.033286Z","steps":["trace[462981061] 'process raft request' (duration: 144.737538ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:42.033325Z","caller":"traceutil/trace.go:171","msg":"trace[553755638] linearizableReadLoop","detail":"{readStateIndex:25740; appliedIndex:25737; }","duration":"142.703692ms","start":"2026-04-15T19:33:41.890595Z","end":"2026-04-15T19:33:42.033299Z","steps":["trace[553755638] 'read index received' (duration: 132.189124ms)","trace[553755638] 'applied index is now lower than readState.Index' (duration: 10.513378ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:33:42.033567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.940328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1de7bd21-fb1c-425a-a72d-6bfa77667ff9\" ","response":"range_response_count:1 size:1436"} {"level":"info","ts":"2026-04-15T19:33:42.033627Z","caller":"traceutil/trace.go:171","msg":"trace[937027293] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1de7bd21-fb1c-425a-a72d-6bfa77667ff9; range_end:; response_count:1; response_revision:24925; }","duration":"143.046202ms","start":"2026-04-15T19:33:41.890567Z","end":"2026-04-15T19:33:42.033614Z","steps":["trace[937027293] 'agreement among raft nodes before linearized reading' (duration: 142.817855ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:42.033896Z","caller":"traceutil/trace.go:171","msg":"trace[806027084] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"145.726775ms","start":"2026-04-15T19:33:41.888153Z","end":"2026-04-15T19:33:42.03388Z","steps":["trace[806027084] 'process raft request' (duration: 144.965464ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:42.674286Z","caller":"traceutil/trace.go:171","msg":"trace[984576358] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"102.055961ms","start":"2026-04-15T19:33:42.572206Z","end":"2026-04-15T19:33:42.674262Z","steps":["trace[984576358] 'process raft request' (duration: 101.899916ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:43.583894Z","caller":"traceutil/trace.go:171","msg":"trace[566069712] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"363.060826ms","start":"2026-04-15T19:33:43.220816Z","end":"2026-04-15T19:33:43.583877Z","steps":["trace[566069712] 'process raft request' (duration: 362.928062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:43.58403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:43.220801Z","time spent":"363.143868ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:43.644847Z","caller":"traceutil/trace.go:171","msg":"trace[101547216] linearizableReadLoop","detail":"{readStateIndex:25744; appliedIndex:25742; }","duration":"421.293849ms","start":"2026-04-15T19:33:43.22353Z","end":"2026-04-15T19:33:43.644824Z","steps":["trace[101547216] 'read index received' (duration: 360.099995ms)","trace[101547216] 'applied index is now lower than readState.Index' (duration: 61.191763ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:43.644848Z","caller":"traceutil/trace.go:171","msg":"trace[1768967287] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"422.06162ms","start":"2026-04-15T19:33:43.222728Z","end":"2026-04-15T19:33:43.64479Z","steps":["trace[1768967287] 'process raft request' (duration: 420.641021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:43.644994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.432852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-15T19:33:43.645076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.125886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T19:33:43.645091Z","caller":"traceutil/trace.go:171","msg":"trace[265455344] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24931; }","duration":"421.567645ms","start":"2026-04-15T19:33:43.223502Z","end":"2026-04-15T19:33:43.64507Z","steps":["trace[265455344] 'agreement among raft nodes before linearized reading' (duration: 421.421711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:43.645094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:43.222712Z","time spent":"422.217634ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:43.645154Z","caller":"traceutil/trace.go:171","msg":"trace[1215345229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24931; }","duration":"405.209998ms","start":"2026-04-15T19:33:43.239905Z","end":"2026-04-15T19:33:43.645115Z","steps":["trace[1215345229] 'agreement among raft nodes before linearized reading' (duration: 405.051384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:43.645156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:43.223492Z","time spent":"421.652578ms","remote":"127.0.0.1:55184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-04-15T19:33:43.645175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.060341ms","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-15T19:33:43.645154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.279248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:33:43.645203Z","caller":"traceutil/trace.go:171","msg":"trace[1454277119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24931; }","duration":"395.128394ms","start":"2026-04-15T19:33:43.250066Z","end":"2026-04-15T19:33:43.645195Z","steps":["trace[1454277119] 'agreement among raft nodes before linearized reading' (duration: 395.047141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:43.64523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:43.250043Z","time spent":"395.182794ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-15T19:33:43.645244Z","caller":"traceutil/trace.go:171","msg":"trace[1266142343] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24931; }","duration":"357.458543ms","start":"2026-04-15T19:33:43.287764Z","end":"2026-04-15T19:33:43.645223Z","steps":["trace[1266142343] 'agreement among raft nodes before linearized reading' (duration: 357.260048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:43.645292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:43.287736Z","time spent":"357.544875ms","remote":"127.0.0.1:57734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-04-15T19:33:43.645196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:43.239891Z","time spent":"405.29494ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T19:33:43.645086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.569446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:33:43.645843Z","caller":"traceutil/trace.go:171","msg":"trace[1811743355] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:24931; }","duration":"140.341617ms","start":"2026-04-15T19:33:43.50547Z","end":"2026-04-15T19:33:43.645811Z","steps":["trace[1811743355] 'agreement among raft nodes before linearized reading' (duration: 139.584127ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:53.972001Z","caller":"traceutil/trace.go:171","msg":"trace[266251904] linearizableReadLoop","detail":"{readStateIndex:25822; appliedIndex:25821; }","duration":"311.877407ms","start":"2026-04-15T19:33:53.660104Z","end":"2026-04-15T19:33:53.971981Z","steps":["trace[266251904] 'read index received' (duration: 311.818996ms)","trace[266251904] 'applied index is now lower than readState.Index' (duration: 57.791µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:53.972127Z","caller":"traceutil/trace.go:171","msg":"trace[323872162] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"358.81651ms","start":"2026-04-15T19:33:53.613276Z","end":"2026-04-15T19:33:53.972092Z","steps":["trace[323872162] 'process raft request' (duration: 358.574024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:53.972182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.062403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:33:53.972215Z","caller":"traceutil/trace.go:171","msg":"trace[855975747] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25005; }","duration":"312.114344ms","start":"2026-04-15T19:33:53.660092Z","end":"2026-04-15T19:33:53.972206Z","steps":["trace[855975747] 'agreement among raft nodes before linearized reading' (duration: 312.017512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:53.972245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.660047Z","time spent":"312.191426ms","remote":"127.0.0.1:34380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":27,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-04-15T19:33:53.972421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.613248Z","time spent":"359.074197ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:54.472141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880292833486750,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T19:33:54.554687Z","caller":"traceutil/trace.go:171","msg":"trace[1184299801] linearizableReadLoop","detail":"{readStateIndex:25828; appliedIndex:25822; }","duration":"582.574808ms","start":"2026-04-15T19:33:53.972088Z","end":"2026-04-15T19:33:54.554663Z","steps":["trace[1184299801] 'read index received' (duration: 548.202948ms)","trace[1184299801] 'applied index is now lower than readState.Index' (duration: 34.37059ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:54.554827Z","caller":"traceutil/trace.go:171","msg":"trace[536917079] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"872.086895ms","start":"2026-04-15T19:33:53.682728Z","end":"2026-04-15T19:33:54.554815Z","steps":["trace[536917079] 'process raft request' (duration: 837.639432ms)","trace[536917079] 'compare' (duration: 33.779464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:33:54.554941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.682701Z","time spent":"872.154307ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:54.554923Z","caller":"traceutil/trace.go:171","msg":"trace[49018897] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"624.286549ms","start":"2026-04-15T19:33:53.930608Z","end":"2026-04-15T19:33:54.554895Z","steps":["trace[49018897] 'process raft request' (duration: 624.011691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.555294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.930596Z","time spent":"624.390992ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:54.555398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.510344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:33:54.555438Z","caller":"traceutil/trace.go:171","msg":"trace[1849033382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25011; }","duration":"741.620327ms","start":"2026-04-15T19:33:53.813808Z","end":"2026-04-15T19:33:54.555428Z","steps":["trace[1849033382] 'agreement among raft nodes before linearized reading' (duration: 741.542895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.555475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.813777Z","time spent":"741.689299ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-15T19:33:54.555579Z","caller":"traceutil/trace.go:171","msg":"trace[779386593] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"872.792544ms","start":"2026-04-15T19:33:53.68277Z","end":"2026-04-15T19:33:54.555563Z","steps":["trace[779386593] 'process raft request' (duration: 871.646072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.555667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.682747Z","time spent":"872.874876ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:54.555697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.529623ms","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-15T19:33:54.555726Z","caller":"traceutil/trace.go:171","msg":"trace[2062768786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25011; }","duration":"858.624656ms","start":"2026-04-15T19:33:53.697092Z","end":"2026-04-15T19:33:54.555717Z","steps":["trace[2062768786] 'agreement among raft nodes before linearized reading' (duration: 858.515753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.555761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.697072Z","time spent":"858.681338ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-15T19:33:54.555817Z","caller":"traceutil/trace.go:171","msg":"trace[851474913] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"872.053552ms","start":"2026-04-15T19:33:53.683749Z","end":"2026-04-15T19:33:54.555803Z","steps":["trace[851474913] 'process raft request' (duration: 870.764657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.555879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.683744Z","time spent":"872.104905ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:54.555944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.869423ms","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-15T19:33:54.555975Z","caller":"traceutil/trace.go:171","msg":"trace[124485338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25011; }","duration":"858.936255ms","start":"2026-04-15T19:33:53.697027Z","end":"2026-04-15T19:33:54.555964Z","steps":["trace[124485338] 'agreement among raft nodes before linearized reading' (duration: 858.849742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.697002Z","time spent":"858.991987ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-15T19:33:54.556052Z","caller":"traceutil/trace.go:171","msg":"trace[1625407655] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"626.945722ms","start":"2026-04-15T19:33:53.929096Z","end":"2026-04-15T19:33:54.556042Z","steps":["trace[1625407655] 'process raft request' (duration: 625.462452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.556107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.929059Z","time spent":"627.018604ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:54.556142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.859229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:33:54.556171Z","caller":"traceutil/trace.go:171","msg":"trace[157214234] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25011; }","duration":"577.938931ms","start":"2026-04-15T19:33:53.978221Z","end":"2026-04-15T19:33:54.55616Z","steps":["trace[157214234] 'agreement among raft nodes before linearized reading' (duration: 577.89137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.5562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.978205Z","time spent":"577.987663ms","remote":"127.0.0.1:55106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-04-15T19:33:54.556275Z","caller":"traceutil/trace.go:171","msg":"trace[440441787] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"626.444738ms","start":"2026-04-15T19:33:53.929819Z","end":"2026-04-15T19:33:54.556264Z","steps":["trace[440441787] 'process raft request' (duration: 624.771653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.556338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:53.929793Z","time spent":"626.515439ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:54.55656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.373348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:33:54.556609Z","caller":"traceutil/trace.go:171","msg":"trace[1951325684] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25011; }","duration":"456.44402ms","start":"2026-04-15T19:33:54.100151Z","end":"2026-04-15T19:33:54.556595Z","steps":["trace[1951325684] 'agreement among raft nodes before linearized reading' (duration: 456.364858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.556643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.100138Z","time spent":"456.496672ms","remote":"127.0.0.1:34252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-04-15T19:33:54.556898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.766179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-15T19:33:54.556937Z","caller":"traceutil/trace.go:171","msg":"trace[509961856] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:25011; }","duration":"456.82582ms","start":"2026-04-15T19:33:54.100102Z","end":"2026-04-15T19:33:54.556928Z","steps":["trace[509961856] 'agreement among raft nodes before linearized reading' (duration: 456.682116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.556966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.10009Z","time spent":"456.867262ms","remote":"127.0.0.1:54954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-04-15T19:33:54.55721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.912669ms","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-15T19:33:54.557254Z","caller":"traceutil/trace.go:171","msg":"trace[826581037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25011; }","duration":"472.95822ms","start":"2026-04-15T19:33:54.084285Z","end":"2026-04-15T19:33:54.557243Z","steps":["trace[826581037] 'agreement among raft nodes before linearized reading' (duration: 472.798396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.557283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.084266Z","time spent":"473.010273ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T19:33:54.557463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.276328ms","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-15T19:33:54.557488Z","caller":"traceutil/trace.go:171","msg":"trace[1872887949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25011; }","duration":"470.32935ms","start":"2026-04-15T19:33:54.087151Z","end":"2026-04-15T19:33:54.55748Z","steps":["trace[1872887949] 'agreement among raft nodes before linearized reading' (duration: 470.249088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.557516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.087143Z","time spent":"470.36742ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T19:33:54.557514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.255683ms","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-15T19:33:54.557559Z","caller":"traceutil/trace.go:171","msg":"trace[1672216433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25011; }","duration":"472.339745ms","start":"2026-04-15T19:33:54.085207Z","end":"2026-04-15T19:33:54.557547Z","steps":["trace[1672216433] 'agreement among raft nodes before linearized reading' (duration: 472.225082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:54.557594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.085185Z","time spent":"472.397826ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T19:33:55.285761Z","caller":"traceutil/trace.go:171","msg":"trace[1871282881] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"724.931351ms","start":"2026-04-15T19:33:54.560804Z","end":"2026-04-15T19:33:55.285735Z","steps":["trace[1871282881] 'process raft request' (duration: 715.289336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:55.285946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.560786Z","time spent":"725.067344ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:55.286155Z","caller":"traceutil/trace.go:171","msg":"trace[1289964822] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"725.052043ms","start":"2026-04-15T19:33:54.561078Z","end":"2026-04-15T19:33:55.28613Z","steps":["trace[1289964822] 'process raft request' (duration: 724.590251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:55.286302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.561067Z","time spent":"725.162717ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:55.286651Z","caller":"traceutil/trace.go:171","msg":"trace[952375534] linearizableReadLoop","detail":"{readStateIndex:25834; appliedIndex:25828; }","duration":"722.603837ms","start":"2026-04-15T19:33:54.564035Z","end":"2026-04-15T19:33:55.286639Z","steps":["trace[952375534] 'read index received' (duration: 113.751241ms)","trace[952375534] 'applied index is now lower than readState.Index' (duration: 608.851896ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:55.28665Z","caller":"traceutil/trace.go:171","msg":"trace[1941773208] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"725.230639ms","start":"2026-04-15T19:33:54.56137Z","end":"2026-04-15T19:33:55.286601Z","steps":["trace[1941773208] 'process raft request' (duration: 724.665054ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:55.286726Z","caller":"traceutil/trace.go:171","msg":"trace[1255179407] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"722.876864ms","start":"2026-04-15T19:33:54.563842Z","end":"2026-04-15T19:33:55.286719Z","steps":["trace[1255179407] 'process raft request' (duration: 722.767281ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:55.28674Z","caller":"traceutil/trace.go:171","msg":"trace[1845726659] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"723.131461ms","start":"2026-04-15T19:33:54.56358Z","end":"2026-04-15T19:33:55.286711Z","steps":["trace[1845726659] 'process raft request' (duration: 722.947946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:55.286792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.56383Z","time spent":"722.915875ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:55.286806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.56136Z","time spent":"725.363342ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:55.286821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.563562Z","time spent":"723.218213ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:55.287133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.060399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:33:55.287227Z","caller":"traceutil/trace.go:171","msg":"trace[2043938880] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25016; }","duration":"723.194372ms","start":"2026-04-15T19:33:54.564008Z","end":"2026-04-15T19:33:55.287202Z","steps":["trace[2043938880] 'agreement among raft nodes before linearized reading' (duration: 722.713399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:55.287483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.563999Z","time spent":"723.46356ms","remote":"127.0.0.1:55184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-04-15T19:33:55.297454Z","caller":"traceutil/trace.go:171","msg":"trace[1194668200] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"576.459341ms","start":"2026-04-15T19:33:54.720981Z","end":"2026-04-15T19:33:55.29744Z","steps":["trace[1194668200] 'process raft request' (duration: 576.391819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:55.297527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.720957Z","time spent":"576.532823ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:55.297639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.837867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-04-15T19:33:55.29768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.398599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-15T19:33:55.297703Z","caller":"traceutil/trace.go:171","msg":"trace[198362057] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:25018; }","duration":"619.920419ms","start":"2026-04-15T19:33:54.677766Z","end":"2026-04-15T19:33:55.297686Z","steps":["trace[198362057] 'agreement among raft nodes before linearized reading' (duration: 619.721664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:55.29774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.677687Z","time spent":"620.045464ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":135,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-15T19:33:55.297755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.467665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-15T19:33:55.297774Z","caller":"traceutil/trace.go:171","msg":"trace[2117182643] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25018; }","duration":"364.510055ms","start":"2026-04-15T19:33:54.933258Z","end":"2026-04-15T19:33:55.297768Z","steps":["trace[2117182643] 'agreement among raft nodes before linearized reading' (duration: 364.473594ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:55.297765Z","caller":"traceutil/trace.go:171","msg":"trace[1230773144] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"597.079155ms","start":"2026-04-15T19:33:54.700659Z","end":"2026-04-15T19:33:55.297739Z","steps":["trace[1230773144] 'process raft request' (duration: 596.647203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:55.297794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.933242Z","time spent":"364.546377ms","remote":"127.0.0.1:55284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-04-15T19:33:55.29771Z","caller":"traceutil/trace.go:171","msg":"trace[751668831] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25018; }","duration":"674.471971ms","start":"2026-04-15T19:33:54.623229Z","end":"2026-04-15T19:33:55.297701Z","steps":["trace[751668831] 'agreement among raft nodes before linearized reading' (duration: 674.365248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:55.297883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.623201Z","time spent":"674.665196ms","remote":"127.0.0.1:55092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-15T19:33:55.297947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:54.700626Z","time spent":"597.190338ms","remote":"127.0.0.1:54954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:55.617365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.475354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-15T19:33:55.617427Z","caller":"traceutil/trace.go:171","msg":"trace[1168901873] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25018; }","duration":"120.594158ms","start":"2026-04-15T19:33:55.496818Z","end":"2026-04-15T19:33:55.617412Z","steps":["trace[1168901873] 'count revisions from in-memory index tree' (duration: 120.3385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:56.557941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880292833486779,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T19:33:56.76351Z","caller":"traceutil/trace.go:171","msg":"trace[1306348407] linearizableReadLoop","detail":"{readStateIndex:25837; appliedIndex:25836; }","duration":"705.839868ms","start":"2026-04-15T19:33:56.057642Z","end":"2026-04-15T19:33:56.763482Z","steps":["trace[1306348407] 'read index received' (duration: 705.677634ms)","trace[1306348407] 'applied index is now lower than readState.Index' (duration: 160.804µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:56.763613Z","caller":"traceutil/trace.go:171","msg":"trace[718061422] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"785.529847ms","start":"2026-04-15T19:33:55.978054Z","end":"2026-04-15T19:33:56.763584Z","steps":["trace[718061422] 'process raft request' (duration: 785.25256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:56.76373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.052404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-15T19:33:56.763753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:55.978025Z","time spent":"785.638229ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:56.763829Z","caller":"traceutil/trace.go:171","msg":"trace[1465543336] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25019; }","duration":"706.190678ms","start":"2026-04-15T19:33:56.057598Z","end":"2026-04-15T19:33:56.763789Z","steps":["trace[1465543336] 'agreement among raft nodes before linearized reading' (duration: 706.028083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:56.763872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:56.057554Z","time spent":"706.309351ms","remote":"127.0.0.1:52698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"info","ts":"2026-04-15T19:33:56.853305Z","caller":"traceutil/trace.go:171","msg":"trace[1237307647] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"291.16069ms","start":"2026-04-15T19:33:56.562111Z","end":"2026-04-15T19:33:56.853272Z","steps":["trace[1237307647] 'process raft request' (duration: 290.860962ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:56.853373Z","caller":"traceutil/trace.go:171","msg":"trace[911838132] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"290.0635ms","start":"2026-04-15T19:33:56.563286Z","end":"2026-04-15T19:33:56.853349Z","steps":["trace[911838132] 'process raft request' (duration: 289.967328ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:56.853457Z","caller":"traceutil/trace.go:171","msg":"trace[775713459] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"290.986026ms","start":"2026-04-15T19:33:56.562431Z","end":"2026-04-15T19:33:56.853417Z","steps":["trace[775713459] 'process raft request' (duration: 290.7599ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:56.853479Z","caller":"traceutil/trace.go:171","msg":"trace[613923045] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"290.970515ms","start":"2026-04-15T19:33:56.562474Z","end":"2026-04-15T19:33:56.853445Z","steps":["trace[613923045] 'process raft request' (duration: 290.744789ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:56.853556Z","caller":"traceutil/trace.go:171","msg":"trace[1140859944] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"291.207982ms","start":"2026-04-15T19:33:56.562338Z","end":"2026-04-15T19:33:56.853546Z","steps":["trace[1140859944] 'process raft request' (duration: 290.804601ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:56.853677Z","caller":"traceutil/trace.go:171","msg":"trace[726262973] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"288.752335ms","start":"2026-04-15T19:33:56.564903Z","end":"2026-04-15T19:33:56.853656Z","steps":["trace[726262973] 'process raft request' (duration: 288.382375ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:56.853698Z","caller":"traceutil/trace.go:171","msg":"trace[253912988] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"236.624849ms","start":"2026-04-15T19:33:56.617049Z","end":"2026-04-15T19:33:56.853674Z","steps":["trace[253912988] 'process raft request' (duration: 236.506006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:56.853843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.999306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-15T19:33:56.853867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.684147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-79696c9fc6-v4nbg.18a69eb581f92c3b\" ","response":"range_response_count:1 size:890"} {"level":"info","ts":"2026-04-15T19:33:56.85391Z","caller":"traceutil/trace.go:171","msg":"trace[1149525191] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25026; }","duration":"765.125909ms","start":"2026-04-15T19:33:56.08877Z","end":"2026-04-15T19:33:56.853896Z","steps":["trace[1149525191] 'agreement among raft nodes before linearized reading' (duration: 764.903133ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:56.853921Z","caller":"traceutil/trace.go:171","msg":"trace[975622126] range","detail":"{range_begin:/registry/events/openstack/magnum-api-79696c9fc6-v4nbg.18a69eb581f92c3b; range_end:; response_count:1; response_revision:25026; }","duration":"136.82086ms","start":"2026-04-15T19:33:56.717085Z","end":"2026-04-15T19:33:56.853906Z","steps":["trace[975622126] 'agreement among raft nodes before linearized reading' (duration: 136.615715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:56.853948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:56.088746Z","time spent":"765.192231ms","remote":"127.0.0.1:55184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":91,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-15T19:33:56.853961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.699123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-79696c9fc6-v4nbg\" ","response":"range_response_count:1 size:8787"} {"level":"info","ts":"2026-04-15T19:33:56.854007Z","caller":"traceutil/trace.go:171","msg":"trace[393822215] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-79696c9fc6-v4nbg; range_end:; response_count:1; response_revision:25026; }","duration":"141.804036ms","start":"2026-04-15T19:33:56.712191Z","end":"2026-04-15T19:33:56.853995Z","steps":["trace[393822215] 'agreement among raft nodes before linearized reading' (duration: 141.58739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:01.915898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.493255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:34:01.916001Z","caller":"traceutil/trace.go:171","msg":"trace[1160478477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25125; }","duration":"101.596668ms","start":"2026-04-15T19:34:01.814368Z","end":"2026-04-15T19:34:01.915965Z","steps":["trace[1160478477] 'range keys from in-memory index tree' (duration: 101.407203ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:02.475956Z","caller":"traceutil/trace.go:171","msg":"trace[1683663361] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"267.416521ms","start":"2026-04-15T19:34:02.20848Z","end":"2026-04-15T19:34:02.475897Z","steps":["trace[1683663361] 'process raft request' (duration: 267.217626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:09.057018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.707217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-15T19:34:09.057103Z","caller":"traceutil/trace.go:171","msg":"trace[1562081319] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"158.157763ms","start":"2026-04-15T19:34:08.898931Z","end":"2026-04-15T19:34:09.057089Z","steps":["trace[1562081319] 'process raft request' (duration: 21.287952ms)","trace[1562081319] 'compare' (duration: 136.597314ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:09.058649Z","caller":"traceutil/trace.go:171","msg":"trace[2093310589] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"157.437615ms","start":"2026-04-15T19:34:08.901179Z","end":"2026-04-15T19:34:09.058617Z","steps":["trace[2093310589] 'process raft request' (duration: 157.26111ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:09.058712Z","caller":"traceutil/trace.go:171","msg":"trace[695840502] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"154.787082ms","start":"2026-04-15T19:34:08.903917Z","end":"2026-04-15T19:34:09.058704Z","steps":["trace[695840502] 'process raft request' (duration: 154.618267ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:09.058658Z","caller":"traceutil/trace.go:171","msg":"trace[1822725640] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"153.640741ms","start":"2026-04-15T19:34:08.904998Z","end":"2026-04-15T19:34:09.058638Z","steps":["trace[1822725640] 'process raft request' (duration: 153.59472ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:09.058844Z","caller":"traceutil/trace.go:171","msg":"trace[1321302436] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"154.214137ms","start":"2026-04-15T19:34:08.904597Z","end":"2026-04-15T19:34:09.058811Z","steps":["trace[1321302436] 'process raft request' (duration: 153.96925ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:09.082282Z","caller":"traceutil/trace.go:171","msg":"trace[1081512899] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"149.475287ms","start":"2026-04-15T19:34:08.932499Z","end":"2026-04-15T19:34:09.081974Z","steps":["trace[1081512899] 'process raft request' (duration: 149.323223ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:09.589792Z","caller":"traceutil/trace.go:171","msg":"trace[1595270056] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"234.556913ms","start":"2026-04-15T19:34:09.355209Z","end":"2026-04-15T19:34:09.589766Z","steps":["trace[1595270056] 'process raft request' (duration: 234.365078ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:09.590019Z","caller":"traceutil/trace.go:171","msg":"trace[420227496] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"234.530603ms","start":"2026-04-15T19:34:09.355476Z","end":"2026-04-15T19:34:09.590006Z","steps":["trace[420227496] 'process raft request' (duration: 234.248175ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:09.590432Z","caller":"traceutil/trace.go:171","msg":"trace[140498945] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"232.947078ms","start":"2026-04-15T19:34:09.357453Z","end":"2026-04-15T19:34:09.5904Z","steps":["trace[140498945] 'process raft request' (duration: 232.837125ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:09.590496Z","caller":"traceutil/trace.go:171","msg":"trace[374033257] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"234.0712ms","start":"2026-04-15T19:34:09.356324Z","end":"2026-04-15T19:34:09.590396Z","steps":["trace[374033257] 'process raft request' (duration: 233.646069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:27.949672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.146043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-15T19:34:27.949751Z","caller":"traceutil/trace.go:171","msg":"trace[1856445552] linearizableReadLoop","detail":"{readStateIndex:26191; appliedIndex:26190; }","duration":"248.128694ms","start":"2026-04-15T19:34:27.70161Z","end":"2026-04-15T19:34:27.949738Z","steps":["trace[1856445552] 'read index received' (duration: 108.910228ms)","trace[1856445552] 'applied index is now lower than readState.Index' (duration: 139.217546ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:27.949925Z","caller":"traceutil/trace.go:171","msg":"trace[692477293] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"275.229736ms","start":"2026-04-15T19:34:27.674687Z","end":"2026-04-15T19:34:27.949917Z","steps":["trace[692477293] 'process raft request' (duration: 135.767113ms)","trace[692477293] 'compare' (duration: 139.054271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:34:27.950198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.785505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:34:27.950221Z","caller":"traceutil/trace.go:171","msg":"trace[1993001954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25366; }","duration":"138.834986ms","start":"2026-04-15T19:34:27.811379Z","end":"2026-04-15T19:34:27.950214Z","steps":["trace[1993001954] 'agreement among raft nodes before linearized reading' (duration: 138.792035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:27.950296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.662519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-15T19:34:27.950384Z","caller":"traceutil/trace.go:171","msg":"trace[892032247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25366; }","duration":"248.793083ms","start":"2026-04-15T19:34:27.701569Z","end":"2026-04-15T19:34:27.950363Z","steps":["trace[892032247] 'agreement among raft nodes before linearized reading' (duration: 248.426623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:27.950451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.541406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T19:34:27.950568Z","caller":"traceutil/trace.go:171","msg":"trace[726692229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25366; }","duration":"248.7034ms","start":"2026-04-15T19:34:27.701836Z","end":"2026-04-15T19:34:27.950539Z","steps":["trace[726692229] 'agreement among raft nodes before linearized reading' (duration: 248.274088ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:28.234964Z","caller":"traceutil/trace.go:171","msg":"trace[1716472468] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"278.941466ms","start":"2026-04-15T19:34:27.955979Z","end":"2026-04-15T19:34:28.23492Z","steps":["trace[1716472468] 'process raft request' (duration: 267.543174ms)","trace[1716472468] 'compare' (duration: 10.841107ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:28.529905Z","caller":"traceutil/trace.go:171","msg":"trace[97971388] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"457.454988ms","start":"2026-04-15T19:34:28.072424Z","end":"2026-04-15T19:34:28.529879Z","steps":["trace[97971388] 'process raft request' (duration: 457.340645ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:28.529927Z","caller":"traceutil/trace.go:171","msg":"trace[1236559110] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"573.85053ms","start":"2026-04-15T19:34:27.95604Z","end":"2026-04-15T19:34:28.529891Z","steps":["trace[1236559110] 'process raft request' (duration: 573.548251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.530076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.072398Z","time spent":"457.600012ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:34:28.53016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.040569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:34:28.530182Z","caller":"traceutil/trace.go:171","msg":"trace[1331812916] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"393.225802ms","start":"2026-04-15T19:34:28.136934Z","end":"2026-04-15T19:34:28.530159Z","steps":["trace[1331812916] 'process raft request' (duration: 392.889073ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:28.530203Z","caller":"traceutil/trace.go:171","msg":"trace[361154005] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25370; }","duration":"234.110321ms","start":"2026-04-15T19:34:28.296082Z","end":"2026-04-15T19:34:28.530192Z","steps":["trace[361154005] 'agreement among raft nodes before linearized reading' (duration: 234.008188ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:28.530045Z","caller":"traceutil/trace.go:171","msg":"trace[1862559825] linearizableReadLoop","detail":"{readStateIndex:26195; appliedIndex:26192; }","duration":"233.920266ms","start":"2026-04-15T19:34:28.296087Z","end":"2026-04-15T19:34:28.530008Z","steps":["trace[1862559825] 'read index received' (duration: 233.395841ms)","trace[1862559825] 'applied index is now lower than readState.Index' (duration: 522.675µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:34:28.530281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.136901Z","time spent":"393.331024ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:34:28.530386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:27.956009Z","time spent":"574.149588ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:34:28.717042Z","caller":"traceutil/trace.go:171","msg":"trace[1571841043] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"170.738349ms","start":"2026-04-15T19:34:28.546285Z","end":"2026-04-15T19:34:28.717023Z","steps":["trace[1571841043] 'process raft request' (duration: 170.605315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.091259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.56041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-15T19:34:29.091363Z","caller":"traceutil/trace.go:171","msg":"trace[1697311658] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"217.395044ms","start":"2026-04-15T19:34:28.873932Z","end":"2026-04-15T19:34:29.091328Z","steps":["trace[1697311658] 'process raft request' (duration: 90.706401ms)","trace[1697311658] 'compare' (duration: 126.447117ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:29.255024Z","caller":"traceutil/trace.go:171","msg":"trace[762703688] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"129.467439ms","start":"2026-04-15T19:34:29.125521Z","end":"2026-04-15T19:34:29.254988Z","steps":["trace[762703688] 'process raft request' (duration: 109.353649ms)","trace[762703688] 'compare' (duration: 19.938066ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:29.412843Z","caller":"traceutil/trace.go:171","msg":"trace[2129999863] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"285.752322ms","start":"2026-04-15T19:34:29.127071Z","end":"2026-04-15T19:34:29.412823Z","steps":["trace[2129999863] 'process raft request' (duration: 285.566047ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:29.413041Z","caller":"traceutil/trace.go:171","msg":"trace[334958062] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"285.886016ms","start":"2026-04-15T19:34:29.127146Z","end":"2026-04-15T19:34:29.413032Z","steps":["trace[334958062] 'process raft request' (duration: 285.626319ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:29.413548Z","caller":"traceutil/trace.go:171","msg":"trace[1966088777] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"283.589073ms","start":"2026-04-15T19:34:29.129939Z","end":"2026-04-15T19:34:29.413528Z","steps":["trace[1966088777] 'process raft request' (duration: 283.057749ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:29.41367Z","caller":"traceutil/trace.go:171","msg":"trace[269758488] linearizableReadLoop","detail":"{readStateIndex:26204; appliedIndex:26198; }","duration":"102.894234ms","start":"2026-04-15T19:34:29.310756Z","end":"2026-04-15T19:34:29.41365Z","steps":["trace[269758488] 'read index received' (duration: 101.864586ms)","trace[269758488] 'applied index is now lower than readState.Index' (duration: 1.028628ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:29.413717Z","caller":"traceutil/trace.go:171","msg":"trace[1544800598] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"273.519849ms","start":"2026-04-15T19:34:29.140182Z","end":"2026-04-15T19:34:29.413702Z","steps":["trace[1544800598] 'process raft request' (duration: 273.264872ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:29.413946Z","caller":"traceutil/trace.go:171","msg":"trace[136278935] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"206.803834ms","start":"2026-04-15T19:34:29.207131Z","end":"2026-04-15T19:34:29.413935Z","steps":["trace[136278935] 'process raft request' (duration: 206.408674ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:29.414014Z","caller":"traceutil/trace.go:171","msg":"trace[2037197918] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"200.46742ms","start":"2026-04-15T19:34:29.213523Z","end":"2026-04-15T19:34:29.413991Z","steps":["trace[2037197918] 'process raft request' (duration: 200.078539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.415958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.191546ms","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-15T19:34:29.416015Z","caller":"traceutil/trace.go:171","msg":"trace[1598696867] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25379; }","duration":"105.300489ms","start":"2026-04-15T19:34:29.310703Z","end":"2026-04-15T19:34:29.416004Z","steps":["trace[1598696867] 'agreement among raft nodes before linearized reading' (duration: 103.021757ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:29.684029Z","caller":"traceutil/trace.go:171","msg":"trace[78034159] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"211.922405ms","start":"2026-04-15T19:34:29.472073Z","end":"2026-04-15T19:34:29.683996Z","steps":["trace[78034159] 'process raft request' (duration: 210.30697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:30.926599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.553979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-79696c9fc6-v4nbg\" ","response":"range_response_count:1 size:8829"} {"level":"info","ts":"2026-04-15T19:34:30.926704Z","caller":"traceutil/trace.go:171","msg":"trace[353666705] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-79696c9fc6-v4nbg; range_end:; response_count:1; response_revision:25388; }","duration":"198.703692ms","start":"2026-04-15T19:34:30.727975Z","end":"2026-04-15T19:34:30.926679Z","steps":["trace[353666705] 'range keys from in-memory index tree' (duration: 198.414035ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:31.047442Z","caller":"traceutil/trace.go:171","msg":"trace[1047966612] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"109.518154ms","start":"2026-04-15T19:34:30.937897Z","end":"2026-04-15T19:34:31.047415Z","steps":["trace[1047966612] 'process raft request' (duration: 109.297708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:31.265829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.201764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-15T19:34:31.26591Z","caller":"traceutil/trace.go:171","msg":"trace[650603914] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"169.115563ms","start":"2026-04-15T19:34:31.096782Z","end":"2026-04-15T19:34:31.265898Z","steps":["trace[650603914] 'process raft request' (duration: 36.779575ms)","trace[650603914] 'compare' (duration: 132.090251ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:31.362227Z","caller":"traceutil/trace.go:171","msg":"trace[1158481624] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"101.091844ms","start":"2026-04-15T19:34:31.261108Z","end":"2026-04-15T19:34:31.3622Z","steps":["trace[1158481624] 'process raft request' (duration: 100.981381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:31.735048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.848824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-15T19:34:31.735323Z","caller":"traceutil/trace.go:171","msg":"trace[772240574] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"317.10885ms","start":"2026-04-15T19:34:31.418184Z","end":"2026-04-15T19:34:31.735292Z","steps":["trace[772240574] 'process raft request' (duration: 161.899016ms)","trace[772240574] 'compare' (duration: 154.68608ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:31.735344Z","caller":"traceutil/trace.go:171","msg":"trace[948939985] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"314.493199ms","start":"2026-04-15T19:34:31.420829Z","end":"2026-04-15T19:34:31.735322Z","steps":["trace[948939985] 'process raft request' (duration: 314.389386ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:31.735367Z","caller":"traceutil/trace.go:171","msg":"trace[1319666286] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"313.419129ms","start":"2026-04-15T19:34:31.421919Z","end":"2026-04-15T19:34:31.735338Z","steps":["trace[1319666286] 'process raft request' (duration: 313.332957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:31.735465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:31.420821Z","time spent":"314.576961ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:34:31.735512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.080937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"warn","ts":"2026-04-15T19:34:31.73552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:31.421894Z","time spent":"313.543653ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:34:31.735556Z","caller":"traceutil/trace.go:171","msg":"trace[202635680] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25397; }","duration":"224.167399ms","start":"2026-04-15T19:34:31.511379Z","end":"2026-04-15T19:34:31.735546Z","steps":["trace[202635680] 'agreement among raft nodes before linearized reading' (duration: 224.011015ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:31.735599Z","caller":"traceutil/trace.go:171","msg":"trace[1603852954] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"276.540331ms","start":"2026-04-15T19:34:31.459045Z","end":"2026-04-15T19:34:31.735585Z","steps":["trace[1603852954] 'process raft request' (duration: 276.245703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:31.735465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:31.418168Z","time spent":"317.222073ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:34:31.735469Z","caller":"traceutil/trace.go:171","msg":"trace[583265041] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"315.824525ms","start":"2026-04-15T19:34:31.419493Z","end":"2026-04-15T19:34:31.735318Z","steps":["trace[583265041] 'process raft request' (duration: 315.66375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:31.736978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:31.419485Z","time spent":"317.399528ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:34:31.735358Z","caller":"traceutil/trace.go:171","msg":"trace[798650346] linearizableReadLoop","detail":"{readStateIndex:26223; appliedIndex:26218; }","duration":"223.912122ms","start":"2026-04-15T19:34:31.511425Z","end":"2026-04-15T19:34:31.735337Z","steps":["trace[798650346] 'read index received' (duration: 68.581445ms)","trace[798650346] 'applied index is now lower than readState.Index' (duration: 155.329607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:34:31.737276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.255527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:34:31.737332Z","caller":"traceutil/trace.go:171","msg":"trace[437273028] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25397; }","duration":"192.35344ms","start":"2026-04-15T19:34:31.544964Z","end":"2026-04-15T19:34:31.737317Z","steps":["trace[437273028] 'agreement among raft nodes before linearized reading' (duration: 192.243986ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:32.179155Z","caller":"traceutil/trace.go:171","msg":"trace[1274894344] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"203.498034ms","start":"2026-04-15T19:34:31.975631Z","end":"2026-04-15T19:34:32.179129Z","steps":["trace[1274894344] 'process raft request' (duration: 193.481851ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:33.614186Z","caller":"traceutil/trace.go:171","msg":"trace[1666977048] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"125.686106ms","start":"2026-04-15T19:34:33.488473Z","end":"2026-04-15T19:34:33.614159Z","steps":["trace[1666977048] 'process raft request' (duration: 125.550502ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:36.432707Z","caller":"traceutil/trace.go:171","msg":"trace[531013791] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"100.152939ms","start":"2026-04-15T19:34:36.332523Z","end":"2026-04-15T19:34:36.432676Z","steps":["trace[531013791] 'process raft request' (duration: 99.854321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:37.64138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.815815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-15T19:34:37.64147Z","caller":"traceutil/trace.go:171","msg":"trace[1187548775] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:25439; }","duration":"116.982759ms","start":"2026-04-15T19:34:37.524467Z","end":"2026-04-15T19:34:37.64145Z","steps":["trace[1187548775] 'count revisions from in-memory index tree' (duration: 116.67539ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:37.829253Z","caller":"traceutil/trace.go:171","msg":"trace[382574241] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"203.254936ms","start":"2026-04-15T19:34:37.625981Z","end":"2026-04-15T19:34:37.829236Z","steps":["trace[382574241] 'process raft request' (duration: 203.121622ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:37.940168Z","caller":"traceutil/trace.go:171","msg":"trace[1587315902] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"148.620363ms","start":"2026-04-15T19:34:37.79151Z","end":"2026-04-15T19:34:37.940131Z","steps":["trace[1587315902] 'process raft request' (duration: 144.228873ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:37.940244Z","caller":"traceutil/trace.go:171","msg":"trace[753519159] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"147.625977ms","start":"2026-04-15T19:34:37.792596Z","end":"2026-04-15T19:34:37.940222Z","steps":["trace[753519159] 'process raft request' (duration: 147.412321ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:37.940341Z","caller":"traceutil/trace.go:171","msg":"trace[1880037361] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"147.582365ms","start":"2026-04-15T19:34:37.792729Z","end":"2026-04-15T19:34:37.940311Z","steps":["trace[1880037361] 'process raft request' (duration: 147.37347ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:37.940431Z","caller":"traceutil/trace.go:171","msg":"trace[1019854304] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"147.189605ms","start":"2026-04-15T19:34:37.793215Z","end":"2026-04-15T19:34:37.940405Z","steps":["trace[1019854304] 'process raft request' (duration: 146.962859ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:37.940637Z","caller":"traceutil/trace.go:171","msg":"trace[702832989] linearizableReadLoop","detail":"{readStateIndex:26272; appliedIndex:26266; }","duration":"126.603611ms","start":"2026-04-15T19:34:37.814018Z","end":"2026-04-15T19:34:37.940622Z","steps":["trace[702832989] 'read index received' (duration: 15.091742ms)","trace[702832989] 'applied index is now lower than readState.Index' (duration: 111.510969ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:37.940871Z","caller":"traceutil/trace.go:171","msg":"trace[1119964865] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"147.184614ms","start":"2026-04-15T19:34:37.79349Z","end":"2026-04-15T19:34:37.940675Z","steps":["trace[1119964865] 'process raft request' (duration: 146.719471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:37.940923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.888519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:34:37.940986Z","caller":"traceutil/trace.go:171","msg":"trace[1956443535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25445; }","duration":"126.985541ms","start":"2026-04-15T19:34:37.813984Z","end":"2026-04-15T19:34:37.94097Z","steps":["trace[1956443535] 'agreement among raft nodes before linearized reading' (duration: 126.892118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:37.941012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.071442ms","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-15T19:34:37.941048Z","caller":"traceutil/trace.go:171","msg":"trace[1261509560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25445; }","duration":"116.142605ms","start":"2026-04-15T19:34:37.824893Z","end":"2026-04-15T19:34:37.941036Z","steps":["trace[1261509560] 'agreement among raft nodes before linearized reading' (duration: 116.006611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:37.941201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.143618ms","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-15T19:34:37.941248Z","caller":"traceutil/trace.go:171","msg":"trace[361682204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25445; }","duration":"115.22373ms","start":"2026-04-15T19:34:37.82601Z","end":"2026-04-15T19:34:37.941233Z","steps":["trace[361682204] 'agreement among raft nodes before linearized reading' (duration: 114.987694ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:38.073797Z","caller":"traceutil/trace.go:171","msg":"trace[257514091] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"125.688056ms","start":"2026-04-15T19:34:37.94808Z","end":"2026-04-15T19:34:38.073768Z","steps":["trace[257514091] 'process raft request' (duration: 92.857679ms)","trace[257514091] 'compare' (duration: 32.713624ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:40.354265Z","caller":"traceutil/trace.go:171","msg":"trace[38488313] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"138.447915ms","start":"2026-04-15T19:34:40.215792Z","end":"2026-04-15T19:34:40.35424Z","steps":["trace[38488313] 'process raft request' (duration: 138.296411ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:42.210448Z","caller":"traceutil/trace.go:171","msg":"trace[186813550] linearizableReadLoop","detail":"{readStateIndex:26302; appliedIndex:26301; }","duration":"134.020434ms","start":"2026-04-15T19:34:42.076409Z","end":"2026-04-15T19:34:42.210429Z","steps":["trace[186813550] 'read index received' (duration: 93.811765ms)","trace[186813550] 'applied index is now lower than readState.Index' (duration: 40.208129ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:42.210494Z","caller":"traceutil/trace.go:171","msg":"trace[1189666254] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"177.103572ms","start":"2026-04-15T19:34:42.033358Z","end":"2026-04-15T19:34:42.210462Z","steps":["trace[1189666254] 'process raft request' (duration: 136.976075ms)","trace[1189666254] 'compare' (duration: 39.993963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:34:42.210562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.129587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:34:42.210624Z","caller":"traceutil/trace.go:171","msg":"trace[1746659755] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25474; }","duration":"134.23201ms","start":"2026-04-15T19:34:42.076374Z","end":"2026-04-15T19:34:42.210606Z","steps":["trace[1746659755] 'agreement among raft nodes before linearized reading' (duration: 134.120437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:42.210632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.269788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-15T19:34:42.210661Z","caller":"traceutil/trace.go:171","msg":"trace[1159839549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25474; }","duration":"120.35023ms","start":"2026-04-15T19:34:42.090303Z","end":"2026-04-15T19:34:42.210653Z","steps":["trace[1159839549] 'agreement among raft nodes before linearized reading' (duration: 120.233437ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:43.977468Z","caller":"traceutil/trace.go:171","msg":"trace[429155315] linearizableReadLoop","detail":"{readStateIndex:26309; appliedIndex:26308; }","duration":"135.741031ms","start":"2026-04-15T19:34:43.841705Z","end":"2026-04-15T19:34:43.977446Z","steps":["trace[429155315] 'read index received' (duration: 135.727441ms)","trace[429155315] 'applied index is now lower than readState.Index' (duration: 11.82µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:43.977478Z","caller":"traceutil/trace.go:171","msg":"trace[68432098] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"189.257515ms","start":"2026-04-15T19:34:43.788194Z","end":"2026-04-15T19:34:43.977451Z","steps":["trace[68432098] 'process raft request' (duration: 189.125031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:43.97777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.001938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-04-15T19:34:43.977852Z","caller":"traceutil/trace.go:171","msg":"trace[2131921775] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:25481; }","duration":"136.162243ms","start":"2026-04-15T19:34:43.841671Z","end":"2026-04-15T19:34:43.977833Z","steps":["trace[2131921775] 'agreement among raft nodes before linearized reading' (duration: 135.894746ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:44.124406Z","caller":"traceutil/trace.go:171","msg":"trace[598146469] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"272.459569ms","start":"2026-04-15T19:34:43.851926Z","end":"2026-04-15T19:34:44.124386Z","steps":["trace[598146469] 'process raft request' (duration: 272.274104ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:44.124685Z","caller":"traceutil/trace.go:171","msg":"trace[436225613] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"166.46517ms","start":"2026-04-15T19:34:43.958212Z","end":"2026-04-15T19:34:44.124677Z","steps":["trace[436225613] 'process raft request' (duration: 166.132451ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:44.124833Z","caller":"traceutil/trace.go:171","msg":"trace[1020791203] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"164.197938ms","start":"2026-04-15T19:34:43.960625Z","end":"2026-04-15T19:34:44.124823Z","steps":["trace[1020791203] 'process raft request' (duration: 164.018044ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:44.125118Z","caller":"traceutil/trace.go:171","msg":"trace[1265665560] transaction","detail":"{read_only:false; response_revision:25485; number_of_response:1; }","duration":"164.318323ms","start":"2026-04-15T19:34:43.960767Z","end":"2026-04-15T19:34:44.125086Z","steps":["trace[1265665560] 'process raft request' (duration: 164.033714ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:44.125202Z","caller":"traceutil/trace.go:171","msg":"trace[289427514] linearizableReadLoop","detail":"{readStateIndex:26315; appliedIndex:26309; }","duration":"147.643977ms","start":"2026-04-15T19:34:43.977534Z","end":"2026-04-15T19:34:44.125178Z","steps":["trace[289427514] 'read index received' (duration: 146.657299ms)","trace[289427514] 'applied index is now lower than readState.Index' (duration: 985.708µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:44.125257Z","caller":"traceutil/trace.go:171","msg":"trace[1737779049] transaction","detail":"{read_only:false; response_revision:25487; number_of_response:1; }","duration":"162.673508ms","start":"2026-04-15T19:34:43.962574Z","end":"2026-04-15T19:34:44.125248Z","steps":["trace[1737779049] 'process raft request' (duration: 162.520124ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:44.125211Z","caller":"traceutil/trace.go:171","msg":"trace[1666098090] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"164.028995ms","start":"2026-04-15T19:34:43.961152Z","end":"2026-04-15T19:34:44.125181Z","steps":["trace[1666098090] 'process raft request' (duration: 163.82584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:44.125491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.197458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-96r46\" ","response":"range_response_count:1 size:3722"} {"level":"info","ts":"2026-04-15T19:34:44.125564Z","caller":"traceutil/trace.go:171","msg":"trace[1951535275] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-96r46; range_end:; response_count:1; response_revision:25487; }","duration":"146.302691ms","start":"2026-04-15T19:34:43.979244Z","end":"2026-04-15T19:34:44.125547Z","steps":["trace[1951535275] 'agreement among raft nodes before linearized reading' (duration: 146.086495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:44.12551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.656496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-04-15T19:34:44.125708Z","caller":"traceutil/trace.go:171","msg":"trace[1914220390] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:25487; }","duration":"283.891752ms","start":"2026-04-15T19:34:43.841781Z","end":"2026-04-15T19:34:44.125673Z","steps":["trace[1914220390] 'agreement among raft nodes before linearized reading' (duration: 283.534083ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:47.55622Z","caller":"traceutil/trace.go:171","msg":"trace[194347049] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"194.192539ms","start":"2026-04-15T19:34:47.362008Z","end":"2026-04-15T19:34:47.5562Z","steps":["trace[194347049] 'process raft request' (duration: 194.050305ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:47.556553Z","caller":"traceutil/trace.go:171","msg":"trace[1017473077] linearizableReadLoop","detail":"{readStateIndex:26339; appliedIndex:26339; }","duration":"170.748398ms","start":"2026-04-15T19:34:47.385776Z","end":"2026-04-15T19:34:47.556525Z","steps":["trace[1017473077] 'read index received' (duration: 170.738478ms)","trace[1017473077] 'applied index is now lower than readState.Index' (duration: 8.14µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:34:47.556735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.945005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:34:47.556773Z","caller":"traceutil/trace.go:171","msg":"trace[1173038909] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25510; }","duration":"171.053318ms","start":"2026-04-15T19:34:47.385709Z","end":"2026-04-15T19:34:47.556763Z","steps":["trace[1173038909] 'agreement among raft nodes before linearized reading' (duration: 170.965475ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:47.585352Z","caller":"traceutil/trace.go:171","msg":"trace[584925063] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"148.928501ms","start":"2026-04-15T19:34:47.436408Z","end":"2026-04-15T19:34:47.585336Z","steps":["trace[584925063] 'process raft request' (duration: 148.579332ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:48.24254Z","caller":"traceutil/trace.go:171","msg":"trace[618662941] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"103.277264ms","start":"2026-04-15T19:34:48.139239Z","end":"2026-04-15T19:34:48.242516Z","steps":["trace[618662941] 'process raft request' (duration: 95.278985ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:48.242829Z","caller":"traceutil/trace.go:171","msg":"trace[1055345089] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"102.620145ms","start":"2026-04-15T19:34:48.140189Z","end":"2026-04-15T19:34:48.242809Z","steps":["trace[1055345089] 'process raft request' (duration: 102.287046ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:48.242902Z","caller":"traceutil/trace.go:171","msg":"trace[1121378852] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"101.925026ms","start":"2026-04-15T19:34:48.140961Z","end":"2026-04-15T19:34:48.242886Z","steps":["trace[1121378852] 'process raft request' (duration: 101.869244ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:48.242943Z","caller":"traceutil/trace.go:171","msg":"trace[679063671] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"102.014889ms","start":"2026-04-15T19:34:48.140881Z","end":"2026-04-15T19:34:48.242895Z","steps":["trace[679063671] 'process raft request' (duration: 101.917317ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:48.243064Z","caller":"traceutil/trace.go:171","msg":"trace[1448537589] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"102.78908ms","start":"2026-04-15T19:34:48.140257Z","end":"2026-04-15T19:34:48.243046Z","steps":["trace[1448537589] 'process raft request' (duration: 102.44728ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:51.30124Z","caller":"traceutil/trace.go:171","msg":"trace[1962407372] linearizableReadLoop","detail":"{readStateIndex:26371; appliedIndex:26370; }","duration":"159.520272ms","start":"2026-04-15T19:34:51.141699Z","end":"2026-04-15T19:34:51.30122Z","steps":["trace[1962407372] 'read index received' (duration: 159.315736ms)","trace[1962407372] 'applied index is now lower than readState.Index' (duration: 203.926µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:34:51.301349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.639335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T19:34:51.301378Z","caller":"traceutil/trace.go:171","msg":"trace[1992869622] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25541; }","duration":"159.816189ms","start":"2026-04-15T19:34:51.14155Z","end":"2026-04-15T19:34:51.301366Z","steps":["trace[1992869622] 'agreement among raft nodes before linearized reading' (duration: 159.750488ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:51.301541Z","caller":"traceutil/trace.go:171","msg":"trace[1829630256] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"188.73901ms","start":"2026-04-15T19:34:51.112793Z","end":"2026-04-15T19:34:51.301532Z","steps":["trace[1829630256] 'process raft request' (duration: 188.274657ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:35:30.949114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23039} {"level":"info","ts":"2026-04-15T19:35:31.087556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23039,"took":"133.870741ms","hash":2463941758,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":36274176,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-15T19:35:31.087638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2463941758,"revision":23039,"compact-revision":20163} {"level":"info","ts":"2026-04-15T19:36:09.90172Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a71.wal"} {"level":"info","ts":"2026-04-15T19:36:32.051216Z","caller":"traceutil/trace.go:171","msg":"trace[1446543450] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"169.510035ms","start":"2026-04-15T19:36:31.88168Z","end":"2026-04-15T19:36:32.05119Z","steps":["trace[1446543450] 'process raft request' (duration: 169.32615ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:36:32.0896Z","caller":"traceutil/trace.go:171","msg":"trace[1294312117] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"116.799172ms","start":"2026-04-15T19:36:31.972775Z","end":"2026-04-15T19:36:32.089574Z","steps":["trace[1294312117] 'process raft request' (duration: 116.622417ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:36:32.089935Z","caller":"traceutil/trace.go:171","msg":"trace[940883687] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"107.055097ms","start":"2026-04-15T19:36:31.982861Z","end":"2026-04-15T19:36:32.089916Z","steps":["trace[940883687] 'process raft request' (duration: 106.678367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:36:32.090194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.714556ms","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-15T19:36:32.090252Z","caller":"traceutil/trace.go:171","msg":"trace[1913674222] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26604; }","duration":"114.811879ms","start":"2026-04-15T19:36:31.975426Z","end":"2026-04-15T19:36:32.090237Z","steps":["trace[1913674222] 'agreement among raft nodes before linearized reading' (duration: 114.713076ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:36:32.091477Z","caller":"traceutil/trace.go:171","msg":"trace[408069439] linearizableReadLoop","detail":"{readStateIndex:27457; appliedIndex:27455; }","duration":"114.414318ms","start":"2026-04-15T19:36:31.975461Z","end":"2026-04-15T19:36:32.089876Z","steps":["trace[408069439] 'read index received' (duration: 75.646248ms)","trace[408069439] 'applied index is now lower than readState.Index' (duration: 38.76478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:36:32.092491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.936071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21346"} {"level":"info","ts":"2026-04-15T19:36:32.092651Z","caller":"traceutil/trace.go:171","msg":"trace[1683921072] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26604; }","duration":"112.058064ms","start":"2026-04-15T19:36:31.980472Z","end":"2026-04-15T19:36:32.09253Z","steps":["trace[1683921072] 'agreement among raft nodes before linearized reading' (duration: 111.287423ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:41.124912Z","caller":"traceutil/trace.go:171","msg":"trace[128774939] transaction","detail":"{read_only:false; response_revision:27303; number_of_response:1; }","duration":"143.378743ms","start":"2026-04-15T19:37:40.981505Z","end":"2026-04-15T19:37:41.124884Z","steps":["trace[128774939] 'process raft request' (duration: 134.502928ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:41.126611Z","caller":"traceutil/trace.go:171","msg":"trace[1420612573] transaction","detail":"{read_only:false; response_revision:27304; number_of_response:1; }","duration":"144.38339ms","start":"2026-04-15T19:37:40.982204Z","end":"2026-04-15T19:37:41.126588Z","steps":["trace[1420612573] 'process raft request' (duration: 144.215915ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:41.12681Z","caller":"traceutil/trace.go:171","msg":"trace[1516920357] transaction","detail":"{read_only:false; response_revision:27305; number_of_response:1; }","duration":"141.917192ms","start":"2026-04-15T19:37:40.984871Z","end":"2026-04-15T19:37:41.126788Z","steps":["trace[1516920357] 'process raft request' (duration: 141.678106ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:59.138735Z","caller":"traceutil/trace.go:171","msg":"trace[527210655] linearizableReadLoop","detail":"{readStateIndex:28346; appliedIndex:28345; }","duration":"113.448688ms","start":"2026-04-15T19:37:59.025265Z","end":"2026-04-15T19:37:59.138713Z","steps":["trace[527210655] 'read index received' (duration: 27.094227ms)","trace[527210655] 'applied index is now lower than readState.Index' (duration: 86.352501ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:37:59.138849Z","caller":"traceutil/trace.go:171","msg":"trace[146091105] transaction","detail":"{read_only:false; response_revision:27470; number_of_response:1; }","duration":"119.090523ms","start":"2026-04-15T19:37:59.019724Z","end":"2026-04-15T19:37:59.138814Z","steps":["trace[146091105] 'process raft request' (duration: 32.64571ms)","trace[146091105] 'compare' (duration: 86.116194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:37:59.13896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.677784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4787"} {"level":"info","ts":"2026-04-15T19:37:59.138995Z","caller":"traceutil/trace.go:171","msg":"trace[1066167988] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:27470; }","duration":"113.740756ms","start":"2026-04-15T19:37:59.025246Z","end":"2026-04-15T19:37:59.138987Z","steps":["trace[1066167988] 'agreement among raft nodes before linearized reading' (duration: 113.575812ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:59.359742Z","caller":"traceutil/trace.go:171","msg":"trace[1473602106] transaction","detail":"{read_only:false; response_revision:27471; number_of_response:1; }","duration":"150.007596ms","start":"2026-04-15T19:37:59.209707Z","end":"2026-04-15T19:37:59.359715Z","steps":["trace[1473602106] 'process raft request' (duration: 135.851886ms)","trace[1473602106] 'compare' (duration: 13.710338ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:37:59.363856Z","caller":"traceutil/trace.go:171","msg":"trace[1746242362] transaction","detail":"{read_only:false; response_revision:27472; number_of_response:1; }","duration":"150.028385ms","start":"2026-04-15T19:37:59.213805Z","end":"2026-04-15T19:37:59.363833Z","steps":["trace[1746242362] 'process raft request' (duration: 149.84209ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:59.364013Z","caller":"traceutil/trace.go:171","msg":"trace[508535789] transaction","detail":"{read_only:false; response_revision:27473; number_of_response:1; }","duration":"149.931332ms","start":"2026-04-15T19:37:59.214063Z","end":"2026-04-15T19:37:59.363995Z","steps":["trace[508535789] 'process raft request' (duration: 149.726567ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:59.36436Z","caller":"traceutil/trace.go:171","msg":"trace[1653033151] transaction","detail":"{read_only:false; response_revision:27475; number_of_response:1; }","duration":"112.894752ms","start":"2026-04-15T19:37:59.251453Z","end":"2026-04-15T19:37:59.364348Z","steps":["trace[1653033151] 'process raft request' (duration: 112.747378ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:59.364337Z","caller":"traceutil/trace.go:171","msg":"trace[2015267538] transaction","detail":"{read_only:false; response_revision:27474; number_of_response:1; }","duration":"113.479678ms","start":"2026-04-15T19:37:59.250821Z","end":"2026-04-15T19:37:59.3643Z","steps":["trace[2015267538] 'process raft request' (duration: 113.107968ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:59.3678Z","caller":"traceutil/trace.go:171","msg":"trace[185991255] linearizableReadLoop","detail":"{readStateIndex:28352; appliedIndex:28346; }","duration":"112.138351ms","start":"2026-04-15T19:37:59.255634Z","end":"2026-04-15T19:37:59.367773Z","steps":["trace[185991255] 'read index received' (duration: 89.935979ms)","trace[185991255] 'applied index is now lower than readState.Index' (duration: 22.200702ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:37:59.368024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.384037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-15T19:37:59.368077Z","caller":"traceutil/trace.go:171","msg":"trace[1170601068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27475; }","duration":"112.48077ms","start":"2026-04-15T19:37:59.255585Z","end":"2026-04-15T19:37:59.368066Z","steps":["trace[1170601068] 'agreement among raft nodes before linearized reading' (duration: 112.310095ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:40:30.956657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26058} {"level":"info","ts":"2026-04-15T19:40:31.105536Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26058,"took":"144.322669ms","hash":1622383226,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":34836480,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-15T19:40:31.105627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1622383226,"revision":26058,"compact-revision":23039} {"level":"info","ts":"2026-04-15T19:41:14.363329Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-15T19:41:14.367854Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-15T19:41:14.367958Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-15T19:45:30.963578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28758} {"level":"info","ts":"2026-04-15T19:45:31.104871Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28758,"took":"136.696517ms","hash":71494818,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31404032,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-15T19:45:31.104964Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":71494818,"revision":28758,"compact-revision":26058} {"level":"info","ts":"2026-04-15T19:50:30.97046Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30982} {"level":"info","ts":"2026-04-15T19:50:31.094654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30982,"took":"119.94406ms","hash":3449619444,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":32362496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-15T19:50:31.094715Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3449619444,"revision":30982,"compact-revision":28758} {"level":"info","ts":"2026-04-15T19:55:30.976825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33253} {"level":"info","ts":"2026-04-15T19:55:31.128087Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33253,"took":"147.2727ms","hash":3993565076,"current-db-size-bytes":59588608,"current-db-size":"60 MB","current-db-size-in-use-bytes":32919552,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-15T19:55:31.128236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3993565076,"revision":33253,"compact-revision":30982} {"level":"info","ts":"2026-04-15T20:00:30.982916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35552} {"level":"info","ts":"2026-04-15T20:00:31.130102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35552,"took":"142.977435ms","hash":3758145839,"current-db-size-bytes":59588608,"current-db-size":"60 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-15T20:00:31.130196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3758145839,"revision":35552,"compact-revision":33253} {"level":"info","ts":"2026-04-15T20:02:33.099452Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-15T20:02:33.105055Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-15T20:02:33.105172Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"warn","ts":"2026-04-15T20:04:31.717246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.020488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/neutron-ks-service\" ","response":"range_response_count:1 size:542"} {"level":"warn","ts":"2026-04-15T20:04:31.717305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.522894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T20:04:31.717401Z","caller":"traceutil/trace.go:171","msg":"trace[1705542894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:39663; }","duration":"490.658017ms","start":"2026-04-15T20:04:31.226718Z","end":"2026-04-15T20:04:31.717375Z","steps":["trace[1705542894] 'range keys from in-memory index tree' (duration: 490.196925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T20:04:31.717459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T20:04:31.226698Z","time spent":"490.74846ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-15T20:04:31.717596Z","caller":"traceutil/trace.go:171","msg":"trace[1480201621] range","detail":"{range_begin:/registry/serviceaccounts/openstack/neutron-ks-service; range_end:; response_count:1; response_revision:39663; }","duration":"475.192333ms","start":"2026-04-15T20:04:31.242142Z","end":"2026-04-15T20:04:31.717334Z","steps":["trace[1480201621] 'range keys from in-memory index tree' (duration: 474.780621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T20:04:31.717659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T20:04:31.242117Z","time spent":"475.529533ms","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":563,"request content":"key:\"/registry/serviceaccounts/openstack/neutron-ks-service\" "} {"level":"warn","ts":"2026-04-15T20:04:31.717101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.671292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T20:04:31.717887Z","caller":"traceutil/trace.go:171","msg":"trace[2008417257] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:39663; }","duration":"217.538887ms","start":"2026-04-15T20:04:31.500316Z","end":"2026-04-15T20:04:31.717854Z","steps":["trace[2008417257] 'count revisions from in-memory index tree' (duration: 216.57035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T20:04:31.719599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.94831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:386301"} {"level":"info","ts":"2026-04-15T20:04:31.71968Z","caller":"traceutil/trace.go:171","msg":"trace[1446932334] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:39663; }","duration":"478.072664ms","start":"2026-04-15T20:04:31.241591Z","end":"2026-04-15T20:04:31.719663Z","steps":["trace[1446932334] 'range keys from in-memory index tree' (duration: 476.744947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T20:04:31.719721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T20:04:31.24157Z","time spent":"478.135236ms","remote":"127.0.0.1:55006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1794,"response size":386322,"request content":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 "}