{"level":"warn","ts":"2026-04-08T13:00:15.067253Z","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-08T13:00:15.067345Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-08T13:00:15.067403Z","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-08T13:00:15.067411Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-08T13:00:15.067429Z","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-08T13:00:15.067959Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-04-08T13:00:15.06803Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-08T13:00:15.071599Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.337782ms"} {"level":"info","ts":"2026-04-08T13:00:15.081496Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-04-08T13:00:15.081573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T13:00:15.081592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-04-08T13:00:15.081606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T13:00:15.081615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-04-08T13:00:15.081651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-04-08T13:00:15.087777Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T13:00:15.090213Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T13:00:15.091731Z","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-08T13:00:15.095012Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T13:00:15.095593Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T13:00:15.095693Z","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-08T13:00:15.095834Z","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-08T13:00:15.095856Z","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-08T13:00:15.098422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-04-08T13:00:15.098842Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-08T13:00:15.101063Z","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-08T13:00:15.101201Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-08T13:00:15.101254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-08T13:00:15.101419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T13:00:15.101472Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T13:00:15.882571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T13:00:15.882643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T13:00:15.882685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-04-08T13:00:15.882706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-04-08T13:00:15.882718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-08T13:00:15.88274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-04-08T13:00:15.882754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-08T13:00:15.883983Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:00:15.884847Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T13:00:15.884893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T13:00:15.884927Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T13:00:15.885366Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T13:00:15.885477Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T13:00:15.886308Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:00:15.886432Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:00:15.886486Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:00:15.888291Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-04-08T13:00:15.889728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T13:00:25.23705Z","caller":"traceutil/trace.go:171","msg":"trace[585710512] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"140.574373ms","start":"2026-04-08T13:00:25.096443Z","end":"2026-04-08T13:00:25.237017Z","steps":["trace[585710512] 'process raft request' (duration: 62.144732ms)","trace[585710512] 'compare' (duration: 78.178735ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:00:25.237315Z","caller":"traceutil/trace.go:171","msg":"trace[1719463396] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"137.807476ms","start":"2026-04-08T13:00:25.099492Z","end":"2026-04-08T13:00:25.237299Z","steps":["trace[1719463396] 'process raft request' (duration: 137.599361ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:25.237383Z","caller":"traceutil/trace.go:171","msg":"trace[1733481407] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"122.967612ms","start":"2026-04-08T13:00:25.114344Z","end":"2026-04-08T13:00:25.237311Z","steps":["trace[1733481407] 'process raft request' (duration: 122.913541ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:25.237379Z","caller":"traceutil/trace.go:171","msg":"trace[471839973] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"140.01642ms","start":"2026-04-08T13:00:25.097288Z","end":"2026-04-08T13:00:25.237304Z","steps":["trace[471839973] 'process raft request' (duration: 139.6049ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:25.237528Z","caller":"traceutil/trace.go:171","msg":"trace[1281903323] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:331; }","duration":"132.122206ms","start":"2026-04-08T13:00:25.105341Z","end":"2026-04-08T13:00:25.237463Z","steps":["trace[1281903323] 'read index received' (duration: 53.402717ms)","trace[1281903323] 'applied index is now lower than readState.Index' (duration: 78.708628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:00:25.23776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.593697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-08T13:00:25.237884Z","caller":"traceutil/trace.go:171","msg":"trace[18590956] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:326; }","duration":"141.774762ms","start":"2026-04-08T13:00:25.096084Z","end":"2026-04-08T13:00:25.237859Z","steps":["trace[18590956] 'agreement among raft nodes before linearized reading' (duration: 141.525196ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:36.671244Z","caller":"traceutil/trace.go:171","msg":"trace[1416717155] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"231.384509ms","start":"2026-04-08T13:00:36.439825Z","end":"2026-04-08T13:00:36.671209Z","steps":["trace[1416717155] 'process raft request' (duration: 231.211345ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:42.12729Z","caller":"traceutil/trace.go:171","msg":"trace[751044800] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"180.105102ms","start":"2026-04-08T13:00:41.947164Z","end":"2026-04-08T13:00:42.127269Z","steps":["trace[751044800] 'process raft request' (duration: 179.894767ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.477642Z","caller":"traceutil/trace.go:171","msg":"trace[97338697] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"204.930056ms","start":"2026-04-08T13:00:52.272682Z","end":"2026-04-08T13:00:52.477613Z","steps":["trace[97338697] 'process raft request' (duration: 204.775993ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.486335Z","caller":"traceutil/trace.go:171","msg":"trace[1868738576] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"207.587691ms","start":"2026-04-08T13:00:52.278705Z","end":"2026-04-08T13:00:52.486292Z","steps":["trace[1868738576] 'process raft request' (duration: 207.383096ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.486414Z","caller":"traceutil/trace.go:171","msg":"trace[1754520234] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"182.949217ms","start":"2026-04-08T13:00:52.303424Z","end":"2026-04-08T13:00:52.486373Z","steps":["trace[1754520234] 'process raft request' (duration: 182.812824ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.486485Z","caller":"traceutil/trace.go:171","msg":"trace[1884847680] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"180.613269ms","start":"2026-04-08T13:00:52.305818Z","end":"2026-04-08T13:00:52.486431Z","steps":["trace[1884847680] 'process raft request' (duration: 180.490636ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.486584Z","caller":"traceutil/trace.go:171","msg":"trace[1965365563] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"200.439786ms","start":"2026-04-08T13:00:52.286093Z","end":"2026-04-08T13:00:52.486532Z","steps":["trace[1965365563] 'process raft request' (duration: 200.079157ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.486598Z","caller":"traceutil/trace.go:171","msg":"trace[532568485] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"181.609924ms","start":"2026-04-08T13:00:52.304947Z","end":"2026-04-08T13:00:52.486557Z","steps":["trace[532568485] 'process raft request' (duration: 181.324427ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.486688Z","caller":"traceutil/trace.go:171","msg":"trace[18864317] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"180.600068ms","start":"2026-04-08T13:00:52.306079Z","end":"2026-04-08T13:00:52.486679Z","steps":["trace[18864317] 'process raft request' (duration: 180.25764ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.486709Z","caller":"traceutil/trace.go:171","msg":"trace[2144849585] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"163.042999ms","start":"2026-04-08T13:00:52.323654Z","end":"2026-04-08T13:00:52.486697Z","steps":["trace[2144849585] 'process raft request' (duration: 162.768542ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.48671Z","caller":"traceutil/trace.go:171","msg":"trace[580569265] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"138.387164ms","start":"2026-04-08T13:00:52.348313Z","end":"2026-04-08T13:00:52.4867Z","steps":["trace[580569265] 'process raft request' (duration: 138.21658ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.486779Z","caller":"traceutil/trace.go:171","msg":"trace[1353731384] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"169.035965ms","start":"2026-04-08T13:00:52.317731Z","end":"2026-04-08T13:00:52.486767Z","steps":["trace[1353731384] 'process raft request' (duration: 168.655856ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.486648Z","caller":"traceutil/trace.go:171","msg":"trace[532557437] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"178.983159ms","start":"2026-04-08T13:00:52.307643Z","end":"2026-04-08T13:00:52.486627Z","steps":["trace[532557437] 'process raft request' (duration: 178.717023ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.655459Z","caller":"traceutil/trace.go:171","msg":"trace[239815037] transaction","detail":"{read_only:false; number_of_response:1; response_revision:512; }","duration":"118.981527ms","start":"2026-04-08T13:00:52.536434Z","end":"2026-04-08T13:00:52.655415Z","steps":["trace[239815037] 'process raft request' (duration: 112.13152ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.655591Z","caller":"traceutil/trace.go:171","msg":"trace[1421448087] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"115.359508ms","start":"2026-04-08T13:00:52.540184Z","end":"2026-04-08T13:00:52.655543Z","steps":["trace[1421448087] 'process raft request' (duration: 115.154904ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.655766Z","caller":"traceutil/trace.go:171","msg":"trace[554707766] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"113.823502ms","start":"2026-04-08T13:00:52.541865Z","end":"2026-04-08T13:00:52.655689Z","steps":["trace[554707766] 'process raft request' (duration: 113.596615ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.655803Z","caller":"traceutil/trace.go:171","msg":"trace[2020898417] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"111.250238ms","start":"2026-04-08T13:00:52.544529Z","end":"2026-04-08T13:00:52.65578Z","steps":["trace[2020898417] 'process raft request' (duration: 111.021192ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:52.655976Z","caller":"traceutil/trace.go:171","msg":"trace[442755587] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:531; }","duration":"102.444722ms","start":"2026-04-08T13:00:52.553469Z","end":"2026-04-08T13:00:52.655914Z","steps":["trace[442755587] 'read index received' (duration: 20.339438ms)","trace[442755587] 'applied index is now lower than readState.Index' (duration: 82.100124ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:00:52.65621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.695729ms","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-08T13:00:52.656236Z","caller":"traceutil/trace.go:171","msg":"trace[1690575996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"102.796291ms","start":"2026-04-08T13:00:52.553432Z","end":"2026-04-08T13:00:52.656228Z","steps":["trace[1690575996] 'agreement among raft nodes before linearized reading' (duration: 102.607766ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:58.926626Z","caller":"traceutil/trace.go:171","msg":"trace[295179478] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"273.443117ms","start":"2026-04-08T13:00:58.653146Z","end":"2026-04-08T13:00:58.926589Z","steps":["trace[295179478] 'read index received' (duration: 272.243317ms)","trace[295179478] 'applied index is now lower than readState.Index' (duration: 1.197869ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:00:58.926689Z","caller":"traceutil/trace.go:171","msg":"trace[1879586682] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"404.147402ms","start":"2026-04-08T13:00:58.522502Z","end":"2026-04-08T13:00:58.926649Z","steps":["trace[1879586682] 'process raft request' (duration: 403.275021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:58.926984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.780044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vz5cg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-08T13:00:58.927094Z","caller":"traceutil/trace.go:171","msg":"trace[1266158843] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vz5cg; range_end:; response_count:1; response_revision:592; }","duration":"273.973379ms","start":"2026-04-08T13:00:58.653091Z","end":"2026-04-08T13:00:58.927065Z","steps":["trace[1266158843] 'agreement among raft nodes before linearized reading' (duration: 273.709553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:58.927214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.679893ms","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-08T13:00:58.927282Z","caller":"traceutil/trace.go:171","msg":"trace[1828521601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"123.827117ms","start":"2026-04-08T13:00:58.803437Z","end":"2026-04-08T13:00:58.927264Z","steps":["trace[1828521601] 'agreement among raft nodes before linearized reading' (duration: 123.473338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:58.927473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:58.522484Z","time spent":"404.307345ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:00:58.92784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.824078ms","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-08T13:00:58.927926Z","caller":"traceutil/trace.go:171","msg":"trace[9071857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"245.943761ms","start":"2026-04-08T13:00:58.681957Z","end":"2026-04-08T13:00:58.927901Z","steps":["trace[9071857] 'agreement among raft nodes before linearized reading' (duration: 244.881966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:59.479549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625728986902065,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T13:00:59.565298Z","caller":"traceutil/trace.go:171","msg":"trace[840636498] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"625.547262ms","start":"2026-04-08T13:00:58.93972Z","end":"2026-04-08T13:00:59.565267Z","steps":["trace[840636498] 'process raft request' (duration: 625.428429ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:59.56535Z","caller":"traceutil/trace.go:171","msg":"trace[887980551] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"632.850111ms","start":"2026-04-08T13:00:58.932447Z","end":"2026-04-08T13:00:59.565297Z","steps":["trace[887980551] 'process raft request' (duration: 632.491343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:59.565499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:58.939689Z","time spent":"625.706066ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:00:59.565578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:58.932428Z","time spent":"633.027215ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:00:59.887517Z","caller":"traceutil/trace.go:171","msg":"trace[907369728] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:615; }","duration":"908.492661ms","start":"2026-04-08T13:00:58.978997Z","end":"2026-04-08T13:00:59.88749Z","steps":["trace[907369728] 'read index received' (duration: 585.960951ms)","trace[907369728] 'applied index is now lower than readState.Index' (duration: 322.5303ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:00:59.887537Z","caller":"traceutil/trace.go:171","msg":"trace[1735275628] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"900.009294ms","start":"2026-04-08T13:00:58.987485Z","end":"2026-04-08T13:00:59.887495Z","steps":["trace[1735275628] 'process raft request' (duration: 837.400668ms)","trace[1735275628] 'compare' (duration: 62.347779ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:00:59.887738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.708777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-08T13:00:59.887762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:58.987447Z","time spent":"900.177198ms","remote":"127.0.0.1:42630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T13:00:59.887826Z","caller":"traceutil/trace.go:171","msg":"trace[2016680394] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:596; }","duration":"908.831769ms","start":"2026-04-08T13:00:58.978974Z","end":"2026-04-08T13:00:59.887805Z","steps":["trace[2016680394] 'agreement among raft nodes before linearized reading' (duration: 908.601854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:59.887872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:58.978965Z","time spent":"908.898481ms","remote":"127.0.0.1:42180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-04-08T13:00:59.888092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.687253ms","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-08T13:00:59.888128Z","caller":"traceutil/trace.go:171","msg":"trace[1212259025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"145.721704ms","start":"2026-04-08T13:00:59.742397Z","end":"2026-04-08T13:00:59.888118Z","steps":["trace[1212259025] 'agreement among raft nodes before linearized reading' (duration: 145.248002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:00.401769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625728986902071,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T13:01:01.410232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.033687ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-08T13:01:01.410582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:59.889728Z","time spent":"1.52084836s","remote":"127.0.0.1:42180","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-08T13:01:01.410988Z","caller":"traceutil/trace.go:171","msg":"trace[1673226349] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"1.509835049s","start":"2026-04-08T13:00:59.901134Z","end":"2026-04-08T13:01:01.410969Z","steps":["trace[1673226349] 'read index received' (duration: 782.917971ms)","trace[1673226349] 'applied index is now lower than readState.Index' (duration: 726.916138ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:01.411052Z","caller":"traceutil/trace.go:171","msg":"trace[296484795] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.517059127s","start":"2026-04-08T13:00:59.893944Z","end":"2026-04-08T13:01:01.411004Z","steps":["trace[296484795] 'process raft request' (duration: 1.516843962s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:01.411121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509985423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:01:01.411153Z","caller":"traceutil/trace.go:171","msg":"trace[1630768856] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:597; }","duration":"1.510059545s","start":"2026-04-08T13:00:59.901084Z","end":"2026-04-08T13:01:01.411144Z","steps":["trace[1630768856] 'agreement among raft nodes before linearized reading' (duration: 1.509997053s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:01.411167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38998492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:01:01.411187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:59.901069Z","time spent":"1.510109385s","remote":"127.0.0.1:42396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-08T13:01:01.411212Z","caller":"traceutil/trace.go:171","msg":"trace[1171282056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.390092622s","start":"2026-04-08T13:01:00.021106Z","end":"2026-04-08T13:01:01.411199Z","steps":["trace[1171282056] 'agreement among raft nodes before linearized reading' (duration: 1.39001452s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:01.411219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:59.893901Z","time spent":"1.517233411s","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:01:01.411276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147408461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-08T13:01:01.411294Z","caller":"traceutil/trace.go:171","msg":"trace[1465713039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"1.147523503s","start":"2026-04-08T13:01:00.263766Z","end":"2026-04-08T13:01:01.411289Z","steps":["trace[1465713039] 'agreement among raft nodes before linearized reading' (duration: 1.147481962s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:01.411312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:00.263741Z","time spent":"1.147568145s","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T13:01:01.411236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:00.021073Z","time spent":"1.390159064s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T13:01:01.41136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.273741ms","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-08T13:01:01.411451Z","caller":"traceutil/trace.go:171","msg":"trace[526191239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"463.403214ms","start":"2026-04-08T13:01:00.948025Z","end":"2026-04-08T13:01:01.411428Z","steps":["trace[526191239] 'agreement among raft nodes before linearized reading' (duration: 463.185739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:01.41149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:00.948Z","time spent":"463.482007ms","remote":"127.0.0.1:42430","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":"warn","ts":"2026-04-08T13:01:03.721285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.42346ms","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-08T13:01:03.721391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.940057ms","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-08T13:01:03.721444Z","caller":"traceutil/trace.go:171","msg":"trace[1681319385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"141.018299ms","start":"2026-04-08T13:01:03.580412Z","end":"2026-04-08T13:01:03.721431Z","steps":["trace[1681319385] 'range keys from in-memory index tree' (duration: 140.790654ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:03.721392Z","caller":"traceutil/trace.go:171","msg":"trace[2145381811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"274.583874ms","start":"2026-04-08T13:01:03.446785Z","end":"2026-04-08T13:01:03.721369Z","steps":["trace[2145381811] 'range keys from in-memory index tree' (duration: 274.231015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:03.721507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.59034ms","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-08T13:01:03.721558Z","caller":"traceutil/trace.go:171","msg":"trace[730603005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"306.714422ms","start":"2026-04-08T13:01:03.414828Z","end":"2026-04-08T13:01:03.721542Z","steps":["trace[730603005] 'range keys from in-memory index tree' (duration: 306.455786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:03.721565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.676436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-08T13:01:03.721606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:03.414803Z","time spent":"306.790554ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T13:01:03.721661Z","caller":"traceutil/trace.go:171","msg":"trace[2144244200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"274.822169ms","start":"2026-04-08T13:01:03.446818Z","end":"2026-04-08T13:01:03.72164Z","steps":["trace[2144244200] 'range keys from in-memory index tree' (duration: 274.491692ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:04.246558Z","caller":"traceutil/trace.go:171","msg":"trace[434844362] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"365.036262ms","start":"2026-04-08T13:01:03.88149Z","end":"2026-04-08T13:01:04.246526Z","steps":["trace[434844362] 'process raft request' (duration: 364.880539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:04.246722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:03.881478Z","time spent":"365.175856ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T13:01:04.516083Z","caller":"traceutil/trace.go:171","msg":"trace[186847312] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"256.277045ms","start":"2026-04-08T13:01:04.259773Z","end":"2026-04-08T13:01:04.51605Z","steps":["trace[186847312] 'process raft request' (duration: 256.224183ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:04.516111Z","caller":"traceutil/trace.go:171","msg":"trace[1960980107] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"256.707655ms","start":"2026-04-08T13:01:04.259361Z","end":"2026-04-08T13:01:04.516069Z","steps":["trace[1960980107] 'process raft request' (duration: 255.091886ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:04.51615Z","caller":"traceutil/trace.go:171","msg":"trace[1337762512] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"256.48584ms","start":"2026-04-08T13:01:04.259628Z","end":"2026-04-08T13:01:04.516114Z","steps":["trace[1337762512] 'process raft request' (duration: 256.310856ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:18.549224Z","caller":"traceutil/trace.go:171","msg":"trace[986490931] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"218.430727ms","start":"2026-04-08T13:01:18.330768Z","end":"2026-04-08T13:01:18.549199Z","steps":["trace[986490931] 'process raft request' (duration: 218.228672ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:47.258069Z","caller":"traceutil/trace.go:171","msg":"trace[905486175] linearizableReadLoop","detail":"{readStateIndex:1196; appliedIndex:1195; }","duration":"395.132861ms","start":"2026-04-08T13:01:46.86291Z","end":"2026-04-08T13:01:47.258043Z","steps":["trace[905486175] 'read index received' (duration: 394.989117ms)","trace[905486175] 'applied index is now lower than readState.Index' (duration: 142.834µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:47.258215Z","caller":"traceutil/trace.go:171","msg":"trace[2030658735] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"507.529578ms","start":"2026-04-08T13:01:46.750667Z","end":"2026-04-08T13:01:47.258197Z","steps":["trace[2030658735] 'process raft request' (duration: 507.241621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:47.258355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.397117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:01:47.258445Z","caller":"traceutil/trace.go:171","msg":"trace[521198907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1161; }","duration":"238.52805ms","start":"2026-04-08T13:01:47.019897Z","end":"2026-04-08T13:01:47.258425Z","steps":["trace[521198907] 'agreement among raft nodes before linearized reading' (duration: 238.397687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:47.258454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:46.750645Z","time spent":"507.666261ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:01:47.258616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.682914ms","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-08T13:01:47.258678Z","caller":"traceutil/trace.go:171","msg":"trace[1534023886] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1161; }","duration":"395.777847ms","start":"2026-04-08T13:01:46.862884Z","end":"2026-04-08T13:01:47.258662Z","steps":["trace[1534023886] 'agreement among raft nodes before linearized reading' (duration: 395.633793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:47.258727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:46.862873Z","time spent":"395.840258ms","remote":"127.0.0.1:42344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":50,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:02:00.505982Z","caller":"traceutil/trace.go:171","msg":"trace[491170917] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"145.469926ms","start":"2026-04-08T13:02:00.360486Z","end":"2026-04-08T13:02:00.505956Z","steps":["trace[491170917] 'process raft request' (duration: 145.303962ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.210269Z","caller":"traceutil/trace.go:171","msg":"trace[345525192] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1441; }","duration":"189.97142ms","start":"2026-04-08T13:02:06.020274Z","end":"2026-04-08T13:02:06.210245Z","steps":["trace[345525192] 'read index received' (duration: 189.790865ms)","trace[345525192] 'applied index is now lower than readState.Index' (duration: 179.805µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:06.210393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.113284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:06.210418Z","caller":"traceutil/trace.go:171","msg":"trace[258591496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1397; }","duration":"190.194736ms","start":"2026-04-08T13:02:06.020214Z","end":"2026-04-08T13:02:06.210409Z","steps":["trace[258591496] 'agreement among raft nodes before linearized reading' (duration: 190.142454ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.210448Z","caller":"traceutil/trace.go:171","msg":"trace[362114459] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"826.120697ms","start":"2026-04-08T13:02:05.384274Z","end":"2026-04-08T13:02:06.210395Z","steps":["trace[362114459] 'process raft request' (duration: 825.845121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.210659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.384233Z","time spent":"826.319392ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:06.210795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.6254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-08T13:02:06.210931Z","caller":"traceutil/trace.go:171","msg":"trace[909918216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1397; }","duration":"108.826505ms","start":"2026-04-08T13:02:06.102075Z","end":"2026-04-08T13:02:06.210901Z","steps":["trace[909918216] 'agreement among raft nodes before linearized reading' (duration: 108.465986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.72955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625728986903584,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T13:02:06.950329Z","caller":"traceutil/trace.go:171","msg":"trace[1871613562] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"734.73946ms","start":"2026-04-08T13:02:06.215559Z","end":"2026-04-08T13:02:06.950299Z","steps":["trace[1871613562] 'process raft request' (duration: 734.550165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.950692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.215541Z","time spent":"734.883284ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:02:07.11032Z","caller":"traceutil/trace.go:171","msg":"trace[289985887] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1442; }","duration":"881.011195ms","start":"2026-04-08T13:02:06.229232Z","end":"2026-04-08T13:02:07.110244Z","steps":["trace[289985887] 'read index received' (duration: 720.871739ms)","trace[289985887] 'applied index is now lower than readState.Index' (duration: 160.137376ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:07.110365Z","caller":"traceutil/trace.go:171","msg":"trace[816965077] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"578.321165ms","start":"2026-04-08T13:02:06.532008Z","end":"2026-04-08T13:02:07.110329Z","steps":["trace[816965077] 'process raft request' (duration: 576.340187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.110527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.531969Z","time spent":"578.474669ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:07.110694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.713545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T13:02:07.110752Z","caller":"traceutil/trace.go:171","msg":"trace[648274050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1399; }","duration":"877.820298ms","start":"2026-04-08T13:02:06.232916Z","end":"2026-04-08T13:02:07.110736Z","steps":["trace[648274050] 'agreement among raft nodes before linearized reading' (duration: 877.585121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.110821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.232903Z","time spent":"877.9059ms","remote":"127.0.0.1:42430","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-08T13:02:07.110807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.150152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:07.110894Z","caller":"traceutil/trace.go:171","msg":"trace[699577747] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1399; }","duration":"530.292055ms","start":"2026-04-08T13:02:06.580579Z","end":"2026-04-08T13:02:07.110871Z","steps":["trace[699577747] 'agreement among raft nodes before linearized reading' (duration: 530.136031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.110908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.469738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T13:02:07.110983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.329625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:02:07.110953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.694523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-08T13:02:07.111006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.924988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:02:07.111014Z","caller":"traceutil/trace.go:171","msg":"trace[1225638034] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1399; }","duration":"652.413946ms","start":"2026-04-08T13:02:06.458592Z","end":"2026-04-08T13:02:07.111006Z","steps":["trace[1225638034] 'agreement among raft nodes before linearized reading' (duration: 652.353525ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:07.111047Z","caller":"traceutil/trace.go:171","msg":"trace[1107572060] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1399; }","duration":"192.00602ms","start":"2026-04-08T13:02:06.91903Z","end":"2026-04-08T13:02:07.111036Z","steps":["trace[1107572060] 'agreement among raft nodes before linearized reading' (duration: 191.938379ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:07.111058Z","caller":"traceutil/trace.go:171","msg":"trace[804107716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1399; }","duration":"881.849337ms","start":"2026-04-08T13:02:06.229181Z","end":"2026-04-08T13:02:07.111031Z","steps":["trace[804107716] 'agreement among raft nodes before linearized reading' (duration: 881.252882ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:07.110996Z","caller":"traceutil/trace.go:171","msg":"trace[750568485] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1399; }","duration":"857.616472ms","start":"2026-04-08T13:02:06.25336Z","end":"2026-04-08T13:02:07.110977Z","steps":["trace[750568485] 'agreement among raft nodes before linearized reading' (duration: 857.414227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.111114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.229162Z","time spent":"881.938619ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T13:02:07.111143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.253337Z","time spent":"857.797736ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":83,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:02:07.111054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.458557Z","time spent":"652.488978ms","remote":"127.0.0.1:42406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:02:07.111158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.093781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T13:02:07.111291Z","caller":"traceutil/trace.go:171","msg":"trace[569915809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1399; }","duration":"849.344068ms","start":"2026-04-08T13:02:06.261915Z","end":"2026-04-08T13:02:07.111259Z","steps":["trace[569915809] 'agreement among raft nodes before linearized reading' (duration: 848.852366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.110943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.580563Z","time spent":"530.367857ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:02:07.11136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.2619Z","time spent":"849.44843ms","remote":"127.0.0.1:42430","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":"info","ts":"2026-04-08T13:02:11.283303Z","caller":"traceutil/trace.go:171","msg":"trace[844630281] linearizableReadLoop","detail":"{readStateIndex:1469; appliedIndex:1468; }","duration":"145.995689ms","start":"2026-04-08T13:02:11.137279Z","end":"2026-04-08T13:02:11.283274Z","steps":["trace[844630281] 'read index received' (duration: 119.465917ms)","trace[844630281] 'applied index is now lower than readState.Index' (duration: 26.528562ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:11.283341Z","caller":"traceutil/trace.go:171","msg":"trace[593619068] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"160.760992ms","start":"2026-04-08T13:02:11.122539Z","end":"2026-04-08T13:02:11.2833Z","steps":["trace[593619068] 'process raft request' (duration: 134.26061ms)","trace[593619068] 'compare' (duration: 26.04177ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:11.283629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.330247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-08T13:02:11.283692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.34494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T13:02:11.283757Z","caller":"traceutil/trace.go:171","msg":"trace[2114342148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1423; }","duration":"138.476903ms","start":"2026-04-08T13:02:11.145263Z","end":"2026-04-08T13:02:11.28374Z","steps":["trace[2114342148] 'agreement among raft nodes before linearized reading' (duration: 138.280418ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:11.28374Z","caller":"traceutil/trace.go:171","msg":"trace[1874912037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1423; }","duration":"146.492881ms","start":"2026-04-08T13:02:11.13722Z","end":"2026-04-08T13:02:11.283713Z","steps":["trace[1874912037] 'agreement among raft nodes before linearized reading' (duration: 146.194963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:11.283755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.19118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T13:02:11.283907Z","caller":"traceutil/trace.go:171","msg":"trace[2065730635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1423; }","duration":"141.374465ms","start":"2026-04-08T13:02:11.142519Z","end":"2026-04-08T13:02:11.283893Z","steps":["trace[2065730635] 'agreement among raft nodes before linearized reading' (duration: 140.946535ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:11.439239Z","caller":"traceutil/trace.go:171","msg":"trace[666712716] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"150.797577ms","start":"2026-04-08T13:02:11.288396Z","end":"2026-04-08T13:02:11.439193Z","steps":["trace[666712716] 'process raft request' (duration: 145.889576ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:11.439504Z","caller":"traceutil/trace.go:171","msg":"trace[1599753044] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"150.685534ms","start":"2026-04-08T13:02:11.288691Z","end":"2026-04-08T13:02:11.439376Z","steps":["trace[1599753044] 'process raft request' (duration: 150.414148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:12.750308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625728986903649,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T13:02:13.087997Z","caller":"traceutil/trace.go:171","msg":"trace[2104423340] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1473; }","duration":"838.074101ms","start":"2026-04-08T13:02:12.249867Z","end":"2026-04-08T13:02:13.087941Z","steps":["trace[2104423340] 'read index received' (duration: 837.892766ms)","trace[2104423340] 'applied index is now lower than readState.Index' (duration: 180.725µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:13.088025Z","caller":"traceutil/trace.go:171","msg":"trace[1387564353] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"906.726407ms","start":"2026-04-08T13:02:12.181264Z","end":"2026-04-08T13:02:13.08799Z","steps":["trace[1387564353] 'process raft request' (duration: 906.553543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.08818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.038401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:02:13.088235Z","caller":"traceutil/trace.go:171","msg":"trace[978865529] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1428; }","duration":"801.153983ms","start":"2026-04-08T13:02:12.287073Z","end":"2026-04-08T13:02:13.088227Z","steps":["trace[978865529] 'agreement among raft nodes before linearized reading' (duration: 801.02593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.088265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.287059Z","time spent":"801.199015ms","remote":"127.0.0.1:42204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":37,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:02:13.08825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.181245Z","time spent":"906.862401ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4506,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:13.088393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.740067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:02:13.088392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.45376ms","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-08T13:02:13.088499Z","caller":"traceutil/trace.go:171","msg":"trace[1670471496] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1428; }","duration":"469.908301ms","start":"2026-04-08T13:02:12.618567Z","end":"2026-04-08T13:02:13.088475Z","steps":["trace[1670471496] 'agreement among raft nodes before linearized reading' (duration: 469.708616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.088552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.618545Z","time spent":"469.995772ms","remote":"127.0.0.1:43450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:02:13.088556Z","caller":"traceutil/trace.go:171","msg":"trace[1129678269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1428; }","duration":"838.688585ms","start":"2026-04-08T13:02:12.249809Z","end":"2026-04-08T13:02:13.088498Z","steps":["trace[1129678269] 'agreement among raft nodes before linearized reading' (duration: 838.288086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.088627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.249787Z","time spent":"838.813499ms","remote":"127.0.0.1:42430","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-08T13:02:26.214728Z","caller":"traceutil/trace.go:171","msg":"trace[927537186] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"101.354971ms","start":"2026-04-08T13:02:26.113345Z","end":"2026-04-08T13:02:26.2147Z","steps":["trace[927537186] 'process raft request' (duration: 101.165136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:37.628109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.016767ms","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-08T13:02:37.628277Z","caller":"traceutil/trace.go:171","msg":"trace[1076371866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1825; }","duration":"150.218052ms","start":"2026-04-08T13:02:37.47803Z","end":"2026-04-08T13:02:37.628248Z","steps":["trace[1076371866] 'range keys from in-memory index tree' (duration: 149.875663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:37.628314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.159145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c7aeb79-f6c5-4f4a-8847-b075475ea2c4\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-08T13:02:37.628463Z","caller":"traceutil/trace.go:171","msg":"trace[1505470986] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0c7aeb79-f6c5-4f4a-8847-b075475ea2c4; range_end:; response_count:1; response_revision:1825; }","duration":"190.353359ms","start":"2026-04-08T13:02:37.438059Z","end":"2026-04-08T13:02:37.628412Z","steps":["trace[1505470986] 'range keys from in-memory index tree' (duration: 189.861608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:37.628684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.661167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T13:02:37.628787Z","caller":"traceutil/trace.go:171","msg":"trace[93174543] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1825; }","duration":"132.100007ms","start":"2026-04-08T13:02:37.496663Z","end":"2026-04-08T13:02:37.628763Z","steps":["trace[93174543] 'range keys from in-memory index tree' (duration: 131.482222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.677562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.570995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c7aeb79-f6c5-4f4a-8847-b075475ea2c4\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-08T13:02:39.677681Z","caller":"traceutil/trace.go:171","msg":"trace[239788541] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0c7aeb79-f6c5-4f4a-8847-b075475ea2c4; range_end:; response_count:1; response_revision:1834; }","duration":"511.74838ms","start":"2026-04-08T13:02:39.165907Z","end":"2026-04-08T13:02:39.677655Z","steps":["trace[239788541] 'range keys from in-memory index tree' (duration: 511.335599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.677721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.808165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-08T13:02:39.677573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.320638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-08T13:02:39.677718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.800932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a46351691c7bac\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-08T13:02:39.677771Z","caller":"traceutil/trace.go:171","msg":"trace[1411957605] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1834; }","duration":"406.568473ms","start":"2026-04-08T13:02:39.271192Z","end":"2026-04-08T13:02:39.677761Z","steps":["trace[1411957605] 'range keys from in-memory index tree' (duration: 406.167314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.67773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:39.165882Z","time spent":"511.838751ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c7aeb79-f6c5-4f4a-8847-b075475ea2c4\" "} {"level":"warn","ts":"2026-04-08T13:02:39.677809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:39.27118Z","time spent":"406.621265ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T13:02:39.677798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.198164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-08T13:02:39.677827Z","caller":"traceutil/trace.go:171","msg":"trace[939749133] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a46351691c7bac; range_end:; response_count:1; response_revision:1834; }","duration":"636.966316ms","start":"2026-04-08T13:02:39.040834Z","end":"2026-04-08T13:02:39.677801Z","steps":["trace[939749133] 'range keys from in-memory index tree' (duration: 636.560917ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.677868Z","caller":"traceutil/trace.go:171","msg":"trace[1830581959] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1834; }","duration":"587.303556ms","start":"2026-04-08T13:02:39.090539Z","end":"2026-04-08T13:02:39.677843Z","steps":["trace[1830581959] 'range keys from in-memory index tree' (duration: 587.04265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.677901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:39.040809Z","time spent":"637.081869ms","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1006,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a46351691c7bac\" "} {"level":"info","ts":"2026-04-08T13:02:39.677774Z","caller":"traceutil/trace.go:171","msg":"trace[668007802] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:1834; }","duration":"691.907007ms","start":"2026-04-08T13:02:38.985851Z","end":"2026-04-08T13:02:39.677758Z","steps":["trace[668007802] 'range keys from in-memory index tree' (duration: 691.61111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.677904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.336769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-08T13:02:39.677939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:39.090519Z","time spent":"587.381709ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T13:02:39.677992Z","caller":"traceutil/trace.go:171","msg":"trace[385560046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1834; }","duration":"452.472062ms","start":"2026-04-08T13:02:39.225498Z","end":"2026-04-08T13:02:39.67797Z","steps":["trace[385560046] 'range keys from in-memory index tree' (duration: 452.124343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.677944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.608808ms","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":"warn","ts":"2026-04-08T13:02:39.678031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:39.225474Z","time spent":"452.548355ms","remote":"127.0.0.1:42430","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-08T13:02:39.678066Z","caller":"traceutil/trace.go:171","msg":"trace[602201713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1834; }","duration":"435.797152ms","start":"2026-04-08T13:02:39.242245Z","end":"2026-04-08T13:02:39.678042Z","steps":["trace[602201713] 'range keys from in-memory index tree' (duration: 435.367822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.678039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:38.985823Z","time spent":"692.159524ms","remote":"127.0.0.1:42180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-04-08T13:02:39.678132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:39.242224Z","time spent":"435.895325ms","remote":"127.0.0.1:42430","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-08T13:02:39.687926Z","caller":"traceutil/trace.go:171","msg":"trace[1377540331] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1907; }","duration":"308.888333ms","start":"2026-04-08T13:02:39.379024Z","end":"2026-04-08T13:02:39.687912Z","steps":["trace[1377540331] 'read index received' (duration: 308.882233ms)","trace[1377540331] 'applied index is now lower than readState.Index' (duration: 5.25µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:39.687935Z","caller":"traceutil/trace.go:171","msg":"trace[2071364129] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"311.19335ms","start":"2026-04-08T13:02:39.376725Z","end":"2026-04-08T13:02:39.687919Z","steps":["trace[2071364129] 'process raft request' (duration: 311.081657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.688077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:39.376687Z","time spent":"311.292123ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:39.688134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.065497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-08T13:02:39.68817Z","caller":"traceutil/trace.go:171","msg":"trace[1726619211] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1835; }","duration":"309.16656ms","start":"2026-04-08T13:02:39.378995Z","end":"2026-04-08T13:02:39.688162Z","steps":["trace[1726619211] 'agreement among raft nodes before linearized reading' (duration: 308.991666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.688243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:39.378986Z","time spent":"309.249782ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-08T13:02:39.865102Z","caller":"traceutil/trace.go:171","msg":"trace[128349413] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"184.969506ms","start":"2026-04-08T13:02:39.680087Z","end":"2026-04-08T13:02:39.865056Z","steps":["trace[128349413] 'process raft request' (duration: 184.72431ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.865247Z","caller":"traceutil/trace.go:171","msg":"trace[1691920742] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"183.90436ms","start":"2026-04-08T13:02:39.681326Z","end":"2026-04-08T13:02:39.86523Z","steps":["trace[1691920742] 'process raft request' (duration: 183.643964ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.86563Z","caller":"traceutil/trace.go:171","msg":"trace[1413094421] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"183.157412ms","start":"2026-04-08T13:02:39.682454Z","end":"2026-04-08T13:02:39.865611Z","steps":["trace[1413094421] 'process raft request' (duration: 183.029549ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.865665Z","caller":"traceutil/trace.go:171","msg":"trace[1189020043] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"183.704846ms","start":"2026-04-08T13:02:39.681944Z","end":"2026-04-08T13:02:39.865649Z","steps":["trace[1189020043] 'process raft request' (duration: 183.213354ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.86569Z","caller":"traceutil/trace.go:171","msg":"trace[571710295] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"183.040399ms","start":"2026-04-08T13:02:39.682619Z","end":"2026-04-08T13:02:39.865659Z","steps":["trace[571710295] 'process raft request' (duration: 182.940376ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.86588Z","caller":"traceutil/trace.go:171","msg":"trace[694481258] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"181.361168ms","start":"2026-04-08T13:02:39.684509Z","end":"2026-04-08T13:02:39.86587Z","steps":["trace[694481258] 'process raft request' (duration: 181.086701ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.865866Z","caller":"traceutil/trace.go:171","msg":"trace[123567399] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1907; }","duration":"177.865112ms","start":"2026-04-08T13:02:39.687961Z","end":"2026-04-08T13:02:39.865827Z","steps":["trace[123567399] 'read index received' (duration: 113.117181ms)","trace[123567399] 'applied index is now lower than readState.Index' (duration: 64.746171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:39.866023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.098824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T13:02:39.866078Z","caller":"traceutil/trace.go:171","msg":"trace[115150574] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1841; }","duration":"223.235727ms","start":"2026-04-08T13:02:39.642823Z","end":"2026-04-08T13:02:39.866059Z","steps":["trace[115150574] 'agreement among raft nodes before linearized reading' (duration: 223.036533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.866078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.223752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T13:02:39.86613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.761643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-08T13:02:39.866096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.620071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c7aeb79-f6c5-4f4a-8847-b075475ea2c4\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-04-08T13:02:39.866019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.018242ms","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-08T13:02:39.866152Z","caller":"traceutil/trace.go:171","msg":"trace[1061995529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1841; }","duration":"224.316283ms","start":"2026-04-08T13:02:39.641818Z","end":"2026-04-08T13:02:39.866135Z","steps":["trace[1061995529] 'agreement among raft nodes before linearized reading' (duration: 224.125399ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.866179Z","caller":"traceutil/trace.go:171","msg":"trace[1461498944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1841; }","duration":"228.866176ms","start":"2026-04-08T13:02:39.637299Z","end":"2026-04-08T13:02:39.866166Z","steps":["trace[1461498944] 'agreement among raft nodes before linearized reading' (duration: 228.647541ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.866205Z","caller":"traceutil/trace.go:171","msg":"trace[226842199] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0c7aeb79-f6c5-4f4a-8847-b075475ea2c4; range_end:; response_count:1; response_revision:1841; }","duration":"228.779034ms","start":"2026-04-08T13:02:39.637401Z","end":"2026-04-08T13:02:39.86618Z","steps":["trace[226842199] 'agreement among raft nodes before linearized reading' (duration: 228.573779ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.866233Z","caller":"traceutil/trace.go:171","msg":"trace[910871793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1841; }","duration":"223.246588ms","start":"2026-04-08T13:02:39.642942Z","end":"2026-04-08T13:02:39.866189Z","steps":["trace[910871793] 'agreement among raft nodes before linearized reading' (duration: 222.987601ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.869465Z","caller":"traceutil/trace.go:171","msg":"trace[2032344956] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"177.552674ms","start":"2026-04-08T13:02:39.691896Z","end":"2026-04-08T13:02:39.869448Z","steps":["trace[2032344956] 'process raft request' (duration: 177.38807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:40.136599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.822062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:02:40.136912Z","caller":"traceutil/trace.go:171","msg":"trace[6739216] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"265.757963ms","start":"2026-04-08T13:02:39.871125Z","end":"2026-04-08T13:02:40.136883Z","steps":["trace[6739216] 'process raft request' (duration: 161.530891ms)","trace[6739216] 'compare' (duration: 103.689359ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:40.137191Z","caller":"traceutil/trace.go:171","msg":"trace[938973486] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1917; }","duration":"256.69371ms","start":"2026-04-08T13:02:39.880446Z","end":"2026-04-08T13:02:40.13714Z","steps":["trace[938973486] 'read index received' (duration: 152.256343ms)","trace[938973486] 'applied index is now lower than readState.Index' (duration: 104.435917ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:40.137204Z","caller":"traceutil/trace.go:171","msg":"trace[979010290] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"264.83709ms","start":"2026-04-08T13:02:39.87235Z","end":"2026-04-08T13:02:40.137187Z","steps":["trace[979010290] 'process raft request' (duration: 264.366039ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:40.137226Z","caller":"traceutil/trace.go:171","msg":"trace[394116568] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"264.331186ms","start":"2026-04-08T13:02:39.872865Z","end":"2026-04-08T13:02:40.137196Z","steps":["trace[394116568] 'process raft request' (duration: 264.112301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:40.137412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.494689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:40.137458Z","caller":"traceutil/trace.go:171","msg":"trace[1603987793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1847; }","duration":"115.601641ms","start":"2026-04-08T13:02:40.021844Z","end":"2026-04-08T13:02:40.137446Z","steps":["trace[1603987793] 'agreement among raft nodes before linearized reading' (duration: 115.521299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:40.137483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.029958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-08T13:02:40.137564Z","caller":"traceutil/trace.go:171","msg":"trace[860038956] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1847; }","duration":"257.150141ms","start":"2026-04-08T13:02:39.880396Z","end":"2026-04-08T13:02:40.137546Z","steps":["trace[860038956] 'agreement among raft nodes before linearized reading' (duration: 256.900595ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:40.487084Z","caller":"traceutil/trace.go:171","msg":"trace[1078562854] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"221.137716ms","start":"2026-04-08T13:02:40.265911Z","end":"2026-04-08T13:02:40.487049Z","steps":["trace[1078562854] 'process raft request' (duration: 220.970562ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:24.425508Z","caller":"traceutil/trace.go:171","msg":"trace[1537843745] linearizableReadLoop","detail":"{readStateIndex:2217; appliedIndex:2216; }","duration":"229.014719ms","start":"2026-04-08T13:03:24.196471Z","end":"2026-04-08T13:03:24.425486Z","steps":["trace[1537843745] 'read index received' (duration: 228.810714ms)","trace[1537843745] 'applied index is now lower than readState.Index' (duration: 203.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:24.425572Z","caller":"traceutil/trace.go:171","msg":"trace[1609973601] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"243.015941ms","start":"2026-04-08T13:03:24.182517Z","end":"2026-04-08T13:03:24.425533Z","steps":["trace[1609973601] 'process raft request' (duration: 242.736255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:24.425794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.308447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-08T13:03:24.425916Z","caller":"traceutil/trace.go:171","msg":"trace[600919384] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2135; }","duration":"229.46119ms","start":"2026-04-08T13:03:24.196406Z","end":"2026-04-08T13:03:24.425867Z","steps":["trace[600919384] 'agreement among raft nodes before linearized reading' (duration: 229.213774ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:24.48442Z","caller":"traceutil/trace.go:171","msg":"trace[1137557346] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"235.065868ms","start":"2026-04-08T13:03:24.249303Z","end":"2026-04-08T13:03:24.484369Z","steps":["trace[1137557346] 'process raft request' (duration: 234.72113ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:24.484566Z","caller":"traceutil/trace.go:171","msg":"trace[569847122] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"167.582967ms","start":"2026-04-08T13:03:24.31695Z","end":"2026-04-08T13:03:24.484533Z","steps":["trace[569847122] 'process raft request' (duration: 167.337531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:24.484725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.636804ms","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":"warn","ts":"2026-04-08T13:03:24.484722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.054502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T13:03:24.484772Z","caller":"traceutil/trace.go:171","msg":"trace[1675667623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2137; }","duration":"285.697767ms","start":"2026-04-08T13:03:24.199063Z","end":"2026-04-08T13:03:24.484761Z","steps":["trace[1675667623] 'agreement among raft nodes before linearized reading' (duration: 285.532052ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:24.484795Z","caller":"traceutil/trace.go:171","msg":"trace[1962929182] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2137; }","duration":"236.156806ms","start":"2026-04-08T13:03:24.248617Z","end":"2026-04-08T13:03:24.484773Z","steps":["trace[1962929182] 'agreement among raft nodes before linearized reading' (duration: 235.95414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:24.484777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.705909ms","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-08T13:03:24.484789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.935699ms","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-08T13:03:24.48488Z","caller":"traceutil/trace.go:171","msg":"trace[23745969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2137; }","duration":"126.873864ms","start":"2026-04-08T13:03:24.357978Z","end":"2026-04-08T13:03:24.484852Z","steps":["trace[23745969] 'agreement among raft nodes before linearized reading' (duration: 126.576466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:24.485213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.793883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-08T13:03:24.48533Z","caller":"traceutil/trace.go:171","msg":"trace[1640350368] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2137; }","duration":"241.923936ms","start":"2026-04-08T13:03:24.24337Z","end":"2026-04-08T13:03:24.485294Z","steps":["trace[1640350368] 'agreement among raft nodes before linearized reading' (duration: 241.27487ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:24.485585Z","caller":"traceutil/trace.go:171","msg":"trace[1368823914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2137; }","duration":"128.126033ms","start":"2026-04-08T13:03:24.356784Z","end":"2026-04-08T13:03:24.48491Z","steps":["trace[1368823914] 'agreement among raft nodes before linearized reading' (duration: 127.680801ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:24.7701Z","caller":"traceutil/trace.go:171","msg":"trace[849435524] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"338.268591ms","start":"2026-04-08T13:03:24.431783Z","end":"2026-04-08T13:03:24.770051Z","steps":["trace[849435524] 'process raft request' (duration: 286.950666ms)","trace[849435524] 'compare' (duration: 50.828703ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:24.770059Z","caller":"traceutil/trace.go:171","msg":"trace[676525606] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"285.686545ms","start":"2026-04-08T13:03:24.484353Z","end":"2026-04-08T13:03:24.770039Z","steps":["trace[676525606] 'read index received' (duration: 234.462683ms)","trace[676525606] 'applied index is now lower than readState.Index' (duration: 51.222892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:03:24.770294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:24.431756Z","time spent":"338.442755ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:03:24.770538Z","caller":"traceutil/trace.go:171","msg":"trace[807769802] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"280.794177ms","start":"2026-04-08T13:03:24.489729Z","end":"2026-04-08T13:03:24.770523Z","steps":["trace[807769802] 'process raft request' (duration: 280.263733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:24.770712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.897458ms","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-08T13:03:24.770779Z","caller":"traceutil/trace.go:171","msg":"trace[394809956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2139; }","duration":"312.023061ms","start":"2026-04-08T13:03:24.458739Z","end":"2026-04-08T13:03:24.770762Z","steps":["trace[394809956] 'agreement among raft nodes before linearized reading' (duration: 311.447096ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:24.770799Z","caller":"traceutil/trace.go:171","msg":"trace[2022790259] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"280.980211ms","start":"2026-04-08T13:03:24.489798Z","end":"2026-04-08T13:03:24.770778Z","steps":["trace[2022790259] 'process raft request' (duration: 280.651082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:24.770821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:24.458709Z","time spent":"312.101662ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T13:03:24.770943Z","caller":"traceutil/trace.go:171","msg":"trace[899754393] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"278.730536ms","start":"2026-04-08T13:03:24.492202Z","end":"2026-04-08T13:03:24.770932Z","steps":["trace[899754393] 'process raft request' (duration: 278.646314ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:24.770951Z","caller":"traceutil/trace.go:171","msg":"trace[681881776] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"281.079694ms","start":"2026-04-08T13:03:24.489857Z","end":"2026-04-08T13:03:24.770936Z","steps":["trace[681881776] 'process raft request' (duration: 280.872839ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:31.026866Z","caller":"traceutil/trace.go:171","msg":"trace[2025745313] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"222.345618ms","start":"2026-04-08T13:03:30.804494Z","end":"2026-04-08T13:03:31.026839Z","steps":["trace[2025745313] 'process raft request' (duration: 222.192294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:31.532583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.990469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:03:31.53284Z","caller":"traceutil/trace.go:171","msg":"trace[1419965679] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"728.450722ms","start":"2026-04-08T13:03:30.804363Z","end":"2026-04-08T13:03:31.532813Z","steps":["trace[1419965679] 'process raft request' (duration: 378.109786ms)","trace[1419965679] 'compare' (duration: 349.777323ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:03:31.532964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:30.804346Z","time spent":"728.556445ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:03:31.533113Z","caller":"traceutil/trace.go:171","msg":"trace[417692920] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"728.230617ms","start":"2026-04-08T13:03:30.804849Z","end":"2026-04-08T13:03:31.533079Z","steps":["trace[417692920] 'process raft request' (duration: 727.875927ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:31.533194Z","caller":"traceutil/trace.go:171","msg":"trace[2032802871] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"728.292708ms","start":"2026-04-08T13:03:30.804877Z","end":"2026-04-08T13:03:31.53317Z","steps":["trace[2032802871] 'process raft request' (duration: 727.931338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:31.533252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:30.804838Z","time spent":"728.345819ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:03:31.533315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:30.804855Z","time spent":"728.400821ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:03:31.533574Z","caller":"traceutil/trace.go:171","msg":"trace[878029246] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2252; }","duration":"727.310934ms","start":"2026-04-08T13:03:30.806249Z","end":"2026-04-08T13:03:31.53356Z","steps":["trace[878029246] 'read index received' (duration: 220.553573ms)","trace[878029246] 'applied index is now lower than readState.Index' (duration: 506.756551ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:31.533661Z","caller":"traceutil/trace.go:171","msg":"trace[1128966857] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"632.743323ms","start":"2026-04-08T13:03:30.900907Z","end":"2026-04-08T13:03:31.53365Z","steps":["trace[1128966857] 'process raft request' (duration: 632.601859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:31.533749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:30.900891Z","time spent":"632.799314ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:03:31.533759Z","caller":"traceutil/trace.go:171","msg":"trace[1822708602] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"728.663167ms","start":"2026-04-08T13:03:30.804978Z","end":"2026-04-08T13:03:31.533641Z","steps":["trace[1822708602] 'process raft request' (duration: 728.122544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:31.53393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:30.804955Z","time spent":"728.879773ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:03:31.533947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.687383ms","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-08T13:03:31.534044Z","caller":"traceutil/trace.go:171","msg":"trace[660415215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2174; }","duration":"727.800777ms","start":"2026-04-08T13:03:30.806231Z","end":"2026-04-08T13:03:31.534032Z","steps":["trace[660415215] 'agreement among raft nodes before linearized reading' (duration: 727.576451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:31.534087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:30.806222Z","time spent":"727.856658ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T13:03:31.57514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.53182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:03:31.575206Z","caller":"traceutil/trace.go:171","msg":"trace[1908863289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2175; }","duration":"554.655472ms","start":"2026-04-08T13:03:31.020532Z","end":"2026-04-08T13:03:31.575188Z","steps":["trace[1908863289] 'agreement among raft nodes before linearized reading' (duration: 554.54492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:31.575257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:31.020502Z","time spent":"554.733854ms","remote":"127.0.0.1:42138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T13:03:32.152395Z","caller":"traceutil/trace.go:171","msg":"trace[1215214336] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"135.212956ms","start":"2026-04-08T13:03:32.017163Z","end":"2026-04-08T13:03:32.152376Z","steps":["trace[1215214336] 'read index received' (duration: 131.242059ms)","trace[1215214336] 'applied index is now lower than readState.Index' (duration: 3.970067ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:32.152426Z","caller":"traceutil/trace.go:171","msg":"trace[2049365012] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"137.628946ms","start":"2026-04-08T13:03:32.014743Z","end":"2026-04-08T13:03:32.152372Z","steps":["trace[2049365012] 'process raft request' (duration: 133.564056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:32.152501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.32108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:03:32.152534Z","caller":"traceutil/trace.go:171","msg":"trace[1641418562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2178; }","duration":"135.395641ms","start":"2026-04-08T13:03:32.017129Z","end":"2026-04-08T13:03:32.152524Z","steps":["trace[1641418562] 'agreement among raft nodes before linearized reading' (duration: 135.329689ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:55.815947Z","caller":"traceutil/trace.go:171","msg":"trace[556549567] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"121.129313ms","start":"2026-04-08T13:03:55.694789Z","end":"2026-04-08T13:03:55.815918Z","steps":["trace[556549567] 'process raft request' (duration: 22.494061ms)","trace[556549567] 'compare' (duration: 98.456738ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:04:18.497957Z","caller":"traceutil/trace.go:171","msg":"trace[528928850] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"124.568055ms","start":"2026-04-08T13:04:18.373362Z","end":"2026-04-08T13:04:18.49793Z","steps":["trace[528928850] 'process raft request' (duration: 124.405071ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:18.503895Z","caller":"traceutil/trace.go:171","msg":"trace[1125419342] transaction","detail":"{read_only:false; response_revision:2474; number_of_response:1; }","duration":"118.106211ms","start":"2026-04-08T13:04:18.385768Z","end":"2026-04-08T13:04:18.503875Z","steps":["trace[1125419342] 'process raft request' (duration: 118.014059ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:19.225683Z","caller":"traceutil/trace.go:171","msg":"trace[1631906165] linearizableReadLoop","detail":"{readStateIndex:2568; appliedIndex:2567; }","duration":"336.454877ms","start":"2026-04-08T13:04:18.889205Z","end":"2026-04-08T13:04:19.225659Z","steps":["trace[1631906165] 'read index received' (duration: 336.333804ms)","trace[1631906165] 'applied index is now lower than readState.Index' (duration: 120.063µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:04:19.225992Z","caller":"traceutil/trace.go:171","msg":"trace[1049678950] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"541.310907ms","start":"2026-04-08T13:04:18.684667Z","end":"2026-04-08T13:04:19.225978Z","steps":["trace[1049678950] 'process raft request' (duration: 540.758754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:19.226163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:18.684645Z","time spent":"541.420559ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:04:19.226455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.247987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-08T13:04:19.226498Z","caller":"traceutil/trace.go:171","msg":"trace[137475020] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2475; }","duration":"337.325368ms","start":"2026-04-08T13:04:18.889161Z","end":"2026-04-08T13:04:19.226487Z","steps":["trace[137475020] 'agreement among raft nodes before linearized reading' (duration: 337.199314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:19.226532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:18.889136Z","time spent":"337.38864ms","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-08T13:04:19.226748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.866339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-08T13:04:19.226797Z","caller":"traceutil/trace.go:171","msg":"trace[34047323] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:2475; }","duration":"232.949142ms","start":"2026-04-08T13:04:18.993835Z","end":"2026-04-08T13:04:19.226784Z","steps":["trace[34047323] 'agreement among raft nodes before linearized reading' (duration: 232.842299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:19.227323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.364675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-08T13:04:19.227369Z","caller":"traceutil/trace.go:171","msg":"trace[1968051299] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2475; }","duration":"170.444207ms","start":"2026-04-08T13:04:19.056915Z","end":"2026-04-08T13:04:19.227359Z","steps":["trace[1968051299] 'agreement among raft nodes before linearized reading' (duration: 170.335625ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:48.05738Z","caller":"traceutil/trace.go:171","msg":"trace[976000735] linearizableReadLoop","detail":"{readStateIndex:2734; appliedIndex:2733; }","duration":"112.148795ms","start":"2026-04-08T13:04:47.9452Z","end":"2026-04-08T13:04:48.057349Z","steps":["trace[976000735] 'read index received' (duration: 111.968021ms)","trace[976000735] 'applied index is now lower than readState.Index' (duration: 179.424µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:04:48.057536Z","caller":"traceutil/trace.go:171","msg":"trace[410323352] transaction","detail":"{read_only:false; response_revision:2634; number_of_response:1; }","duration":"112.546396ms","start":"2026-04-08T13:04:47.944939Z","end":"2026-04-08T13:04:48.057485Z","steps":["trace[410323352] 'process raft request' (duration: 112.224548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:48.057672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.448243ms","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-08T13:04:48.057714Z","caller":"traceutil/trace.go:171","msg":"trace[2012168278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2634; }","duration":"112.525755ms","start":"2026-04-08T13:04:47.945177Z","end":"2026-04-08T13:04:48.057702Z","steps":["trace[2012168278] 'agreement among raft nodes before linearized reading' (duration: 112.33189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:48.302068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.83775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:04:48.30242Z","caller":"traceutil/trace.go:171","msg":"trace[1930666212] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"355.256612ms","start":"2026-04-08T13:04:47.947133Z","end":"2026-04-08T13:04:48.30239Z","steps":["trace[1930666212] 'process raft request' (duration: 220.741305ms)","trace[1930666212] 'compare' (duration: 133.542583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:04:48.302545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:47.947111Z","time spent":"355.377606ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:04:48.303177Z","caller":"traceutil/trace.go:171","msg":"trace[1193136918] transaction","detail":"{read_only:false; response_revision:2636; number_of_response:1; }","duration":"354.088993ms","start":"2026-04-08T13:04:47.949066Z","end":"2026-04-08T13:04:48.303155Z","steps":["trace[1193136918] 'process raft request' (duration: 353.271844ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:48.303305Z","caller":"traceutil/trace.go:171","msg":"trace[167651263] linearizableReadLoop","detail":"{readStateIndex:2736; appliedIndex:2734; }","duration":"245.787512ms","start":"2026-04-08T13:04:48.057472Z","end":"2026-04-08T13:04:48.30326Z","steps":["trace[167651263] 'read index received' (duration: 110.438405ms)","trace[167651263] 'applied index is now lower than readState.Index' (duration: 135.343607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:04:48.303466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.832038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:04:48.303526Z","caller":"traceutil/trace.go:171","msg":"trace[1472611142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2637; }","duration":"282.937751ms","start":"2026-04-08T13:04:48.020571Z","end":"2026-04-08T13:04:48.303509Z","steps":["trace[1472611142] 'agreement among raft nodes before linearized reading' (duration: 282.846859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:48.303627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:47.949053Z","time spent":"354.186715ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:04:48.303697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.499569ms","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-08T13:04:48.303756Z","caller":"traceutil/trace.go:171","msg":"trace[1883023163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2637; }","duration":"220.603322ms","start":"2026-04-08T13:04:48.083133Z","end":"2026-04-08T13:04:48.303736Z","steps":["trace[1883023163] 'agreement among raft nodes before linearized reading' (duration: 220.342696ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:48.303821Z","caller":"traceutil/trace.go:171","msg":"trace[342543890] transaction","detail":"{read_only:false; response_revision:2637; number_of_response:1; }","duration":"241.702382ms","start":"2026-04-08T13:04:48.0621Z","end":"2026-04-08T13:04:48.303802Z","steps":["trace[342543890] 'process raft request' (duration: 240.951344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:50.416061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.087206ms","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-08T13:04:50.416277Z","caller":"traceutil/trace.go:171","msg":"trace[1237906845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2644; }","duration":"108.365283ms","start":"2026-04-08T13:04:50.30789Z","end":"2026-04-08T13:04:50.416255Z","steps":["trace[1237906845] 'range keys from in-memory index tree' (duration: 107.83576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:50.416297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.891232ms","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-08T13:04:50.416379Z","caller":"traceutil/trace.go:171","msg":"trace[1444164636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2644; }","duration":"108.006314ms","start":"2026-04-08T13:04:50.308354Z","end":"2026-04-08T13:04:50.416361Z","steps":["trace[1444164636] 'range keys from in-memory index tree' (duration: 107.689897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:50.416559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.053365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-08T13:04:50.416644Z","caller":"traceutil/trace.go:171","msg":"trace[1245941656] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2644; }","duration":"108.186188ms","start":"2026-04-08T13:04:50.308438Z","end":"2026-04-08T13:04:50.416625Z","steps":["trace[1245941656] 'range keys from in-memory index tree' (duration: 107.85821ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:50.976634Z","caller":"traceutil/trace.go:171","msg":"trace[1686770212] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"556.143076ms","start":"2026-04-08T13:04:50.42045Z","end":"2026-04-08T13:04:50.976593Z","steps":["trace[1686770212] 'process raft request' (duration: 555.700385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:50.976887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:50.420429Z","time spent":"556.330391ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:04:50.997299Z","caller":"traceutil/trace.go:171","msg":"trace[709740147] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"576.647934ms","start":"2026-04-08T13:04:50.420609Z","end":"2026-04-08T13:04:50.997257Z","steps":["trace[709740147] 'process raft request' (duration: 576.414228ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:50.997714Z","caller":"traceutil/trace.go:171","msg":"trace[1397159065] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"576.688924ms","start":"2026-04-08T13:04:50.420994Z","end":"2026-04-08T13:04:50.997683Z","steps":["trace[1397159065] 'process raft request' (duration: 576.193302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:50.997729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:50.420578Z","time spent":"576.820747ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:04:50.997865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:50.420985Z","time spent":"576.801747ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:04:50.997927Z","caller":"traceutil/trace.go:171","msg":"trace[896701051] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"576.751916ms","start":"2026-04-08T13:04:50.421089Z","end":"2026-04-08T13:04:50.997841Z","steps":["trace[896701051] 'process raft request' (duration: 576.51773ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:50.998182Z","caller":"traceutil/trace.go:171","msg":"trace[34629009] linearizableReadLoop","detail":"{readStateIndex:2751; appliedIndex:2745; }","duration":"108.303622ms","start":"2026-04-08T13:04:50.889858Z","end":"2026-04-08T13:04:50.998162Z","steps":["trace[34629009] 'read index received' (duration: 86.208397ms)","trace[34629009] 'applied index is now lower than readState.Index' (duration: 22.093915ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:04:50.998186Z","caller":"traceutil/trace.go:171","msg":"trace[913378827] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"324.356184ms","start":"2026-04-08T13:04:50.673802Z","end":"2026-04-08T13:04:50.998159Z","steps":["trace[913378827] 'process raft request' (duration: 323.960215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:50.998262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:50.421066Z","time spent":"577.063914ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:04:50.998409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.550929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-08T13:04:50.99846Z","caller":"traceutil/trace.go:171","msg":"trace[1325390165] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"310.604111ms","start":"2026-04-08T13:04:50.687833Z","end":"2026-04-08T13:04:50.998437Z","steps":["trace[1325390165] 'process raft request' (duration: 310.223142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:50.998469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:50.673776Z","time spent":"324.61266ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:04:50.998481Z","caller":"traceutil/trace.go:171","msg":"trace[1694549676] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2650; }","duration":"108.684301ms","start":"2026-04-08T13:04:50.889779Z","end":"2026-04-08T13:04:50.998463Z","steps":["trace[1694549676] 'agreement among raft nodes before linearized reading' (duration: 108.465006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:50.998683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:50.687817Z","time spent":"310.805806ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:07:04.157041Z","caller":"traceutil/trace.go:171","msg":"trace[148896629] linearizableReadLoop","detail":"{readStateIndex:3595; appliedIndex:3594; }","duration":"137.416791ms","start":"2026-04-08T13:07:04.019577Z","end":"2026-04-08T13:07:04.156994Z","steps":["trace[148896629] 'read index received' (duration: 135.561786ms)","trace[148896629] 'applied index is now lower than readState.Index' (duration: 1.853335ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:07:04.157068Z","caller":"traceutil/trace.go:171","msg":"trace[1497079589] transaction","detail":"{read_only:false; response_revision:3461; number_of_response:1; }","duration":"224.062051ms","start":"2026-04-08T13:07:03.93297Z","end":"2026-04-08T13:07:04.157032Z","steps":["trace[1497079589] 'process raft request' (duration: 222.239727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:07:04.157171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.571274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:07:04.157237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.280106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:07:04.157286Z","caller":"traceutil/trace.go:171","msg":"trace[1637905129] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3461; }","duration":"106.348867ms","start":"2026-04-08T13:07:04.050924Z","end":"2026-04-08T13:07:04.157273Z","steps":["trace[1637905129] 'agreement among raft nodes before linearized reading' (duration: 106.246735ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:07:04.15728Z","caller":"traceutil/trace.go:171","msg":"trace[1959747742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3461; }","duration":"137.731688ms","start":"2026-04-08T13:07:04.019522Z","end":"2026-04-08T13:07:04.157254Z","steps":["trace[1959747742] 'agreement among raft nodes before linearized reading' (duration: 137.581844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:07:36.03788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.981122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:07:36.037982Z","caller":"traceutil/trace.go:171","msg":"trace[1903388716] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:3703; }","duration":"180.150486ms","start":"2026-04-08T13:07:35.85781Z","end":"2026-04-08T13:07:36.03796Z","steps":["trace[1903388716] 'count revisions from in-memory index tree' (duration: 179.875909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:07:36.038255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.593618ms","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-08T13:07:36.038369Z","caller":"traceutil/trace.go:171","msg":"trace[1104408405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3703; }","duration":"166.748441ms","start":"2026-04-08T13:07:35.871581Z","end":"2026-04-08T13:07:36.038329Z","steps":["trace[1104408405] 'range keys from in-memory index tree' (duration: 166.371172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:06.647881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.762034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:08:06.648044Z","caller":"traceutil/trace.go:171","msg":"trace[8394250] transaction","detail":"{read_only:false; response_revision:3905; number_of_response:1; }","duration":"486.224685ms","start":"2026-04-08T13:08:06.161799Z","end":"2026-04-08T13:08:06.648024Z","steps":["trace[8394250] 'process raft request' (duration: 309.232684ms)","trace[8394250] 'compare' (duration: 176.458027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:08:06.648111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:08:06.161766Z","time spent":"486.310147ms","remote":"127.0.0.1:42204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24168,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:08:06.677068Z","caller":"traceutil/trace.go:171","msg":"trace[1601519661] linearizableReadLoop","detail":"{readStateIndex:4058; appliedIndex:4056; }","duration":"457.806406ms","start":"2026-04-08T13:08:06.219241Z","end":"2026-04-08T13:08:06.677047Z","steps":["trace[1601519661] 'read index received' (duration: 251.851634ms)","trace[1601519661] 'applied index is now lower than readState.Index' (duration: 205.953731ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:06.677198Z","caller":"traceutil/trace.go:171","msg":"trace[866049950] transaction","detail":"{read_only:false; response_revision:3906; number_of_response:1; }","duration":"505.670566ms","start":"2026-04-08T13:08:06.171516Z","end":"2026-04-08T13:08:06.677186Z","steps":["trace[866049950] 'process raft request' (duration: 504.982609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:06.677408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:08:06.17148Z","time spent":"505.806179ms","remote":"127.0.0.1:42204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":276768,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:08:06.677522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.047611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-08T13:08:06.677577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.338828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-08T13:08:06.677532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.853889ms","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-08T13:08:06.677627Z","caller":"traceutil/trace.go:171","msg":"trace[1446354507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3906; }","duration":"458.4113ms","start":"2026-04-08T13:08:06.219204Z","end":"2026-04-08T13:08:06.677616Z","steps":["trace[1446354507] 'agreement among raft nodes before linearized reading' (duration: 458.289177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:06.67766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:08:06.219191Z","time spent":"458.462282ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T13:08:06.677664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.250508ms","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-08T13:08:06.677581Z","caller":"traceutil/trace.go:171","msg":"trace[849188247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3906; }","duration":"170.131894ms","start":"2026-04-08T13:08:06.507436Z","end":"2026-04-08T13:08:06.677568Z","steps":["trace[849188247] 'agreement among raft nodes before linearized reading' (duration: 169.96356ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:06.677687Z","caller":"traceutil/trace.go:171","msg":"trace[89042974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3906; }","duration":"435.015823ms","start":"2026-04-08T13:08:06.24263Z","end":"2026-04-08T13:08:06.677645Z","steps":["trace[89042974] 'agreement among raft nodes before linearized reading' (duration: 434.761037ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:06.677698Z","caller":"traceutil/trace.go:171","msg":"trace[511857112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3906; }","duration":"142.300349ms","start":"2026-04-08T13:08:06.53539Z","end":"2026-04-08T13:08:06.67769Z","steps":["trace[511857112] 'agreement among raft nodes before linearized reading' (duration: 142.213157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:06.677688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.048174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-08T13:08:06.677763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:08:06.242619Z","time spent":"435.131885ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T13:08:06.677762Z","caller":"traceutil/trace.go:171","msg":"trace[196291340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3906; }","duration":"199.160887ms","start":"2026-04-08T13:08:06.47858Z","end":"2026-04-08T13:08:06.677741Z","steps":["trace[196291340] 'agreement among raft nodes before linearized reading' (duration: 198.976763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:06.677833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.946032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-08T13:08:06.677937Z","caller":"traceutil/trace.go:171","msg":"trace[2003749215] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3906; }","duration":"407.061705ms","start":"2026-04-08T13:08:06.27085Z","end":"2026-04-08T13:08:06.677912Z","steps":["trace[2003749215] 'agreement among raft nodes before linearized reading' (duration: 406.747878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:06.677996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:08:06.270713Z","time spent":"407.27353ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T13:08:06.679092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.196133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413055"} {"level":"info","ts":"2026-04-08T13:08:06.679175Z","caller":"traceutil/trace.go:171","msg":"trace[2111647958] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:3906; }","duration":"408.301366ms","start":"2026-04-08T13:08:06.270857Z","end":"2026-04-08T13:08:06.679158Z","steps":["trace[2111647958] 'agreement among raft nodes before linearized reading' (duration: 407.367633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:06.679232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:08:06.270845Z","time spent":"408.373487ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":413079,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-04-08T13:08:07.319784Z","caller":"traceutil/trace.go:171","msg":"trace[1877839053] transaction","detail":"{read_only:false; response_revision:3923; number_of_response:1; }","duration":"115.048798ms","start":"2026-04-08T13:08:07.204701Z","end":"2026-04-08T13:08:07.319749Z","steps":["trace[1877839053] 'process raft request' (duration: 109.915855ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:08.873503Z","caller":"traceutil/trace.go:171","msg":"trace[95040287] transaction","detail":"{read_only:false; response_revision:3944; number_of_response:1; }","duration":"122.740165ms","start":"2026-04-08T13:08:08.750741Z","end":"2026-04-08T13:08:08.873481Z","steps":["trace[95040287] 'process raft request' (duration: 111.322848ms)","trace[95040287] 'compare' (duration: 11.229282ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:08.873713Z","caller":"traceutil/trace.go:171","msg":"trace[892982583] transaction","detail":"{read_only:false; response_revision:3945; number_of_response:1; }","duration":"122.866098ms","start":"2026-04-08T13:08:08.750839Z","end":"2026-04-08T13:08:08.873705Z","steps":["trace[892982583] 'process raft request' (duration: 122.606672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:08.873957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.471531ms","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-08T13:08:08.873985Z","caller":"traceutil/trace.go:171","msg":"trace[2001782158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3945; }","duration":"125.528543ms","start":"2026-04-08T13:08:08.748449Z","end":"2026-04-08T13:08:08.873978Z","steps":["trace[2001782158] 'agreement among raft nodes before linearized reading' (duration: 125.381139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:08.874069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.511932ms","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-08T13:08:08.87409Z","caller":"traceutil/trace.go:171","msg":"trace[31217452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3946; }","duration":"125.581004ms","start":"2026-04-08T13:08:08.748503Z","end":"2026-04-08T13:08:08.874084Z","steps":["trace[31217452] 'agreement among raft nodes before linearized reading' (duration: 125.530053ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:08.992924Z","caller":"traceutil/trace.go:171","msg":"trace[1415983913] transaction","detail":"{read_only:false; response_revision:3947; number_of_response:1; }","duration":"114.69585ms","start":"2026-04-08T13:08:08.878197Z","end":"2026-04-08T13:08:08.992893Z","steps":["trace[1415983913] 'process raft request' (duration: 114.398253ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:08.994246Z","caller":"traceutil/trace.go:171","msg":"trace[1217491582] transaction","detail":"{read_only:false; response_revision:3948; number_of_response:1; }","duration":"114.7043ms","start":"2026-04-08T13:08:08.879508Z","end":"2026-04-08T13:08:08.994213Z","steps":["trace[1217491582] 'process raft request' (duration: 114.474225ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:08.994391Z","caller":"traceutil/trace.go:171","msg":"trace[1779291639] transaction","detail":"{read_only:false; response_revision:3949; number_of_response:1; }","duration":"112.454375ms","start":"2026-04-08T13:08:08.88191Z","end":"2026-04-08T13:08:08.994364Z","steps":["trace[1779291639] 'process raft request' (duration: 112.197089ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:19.726521Z","caller":"traceutil/trace.go:171","msg":"trace[1418628129] transaction","detail":"{read_only:false; response_revision:4009; number_of_response:1; }","duration":"154.815241ms","start":"2026-04-08T13:08:19.571661Z","end":"2026-04-08T13:08:19.726476Z","steps":["trace[1418628129] 'process raft request' (duration: 154.621937ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:24.20934Z","caller":"traceutil/trace.go:171","msg":"trace[1916578067] linearizableReadLoop","detail":"{readStateIndex:4191; appliedIndex:4190; }","duration":"135.568475ms","start":"2026-04-08T13:08:24.073742Z","end":"2026-04-08T13:08:24.209311Z","steps":["trace[1916578067] 'read index received' (duration: 135.405651ms)","trace[1916578067] 'applied index is now lower than readState.Index' (duration: 161.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:24.209399Z","caller":"traceutil/trace.go:171","msg":"trace[925177152] transaction","detail":"{read_only:false; response_revision:4036; number_of_response:1; }","duration":"164.452375ms","start":"2026-04-08T13:08:24.044912Z","end":"2026-04-08T13:08:24.209364Z","steps":["trace[925177152] 'process raft request' (duration: 164.226459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:24.209614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.861113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-08T13:08:24.209669Z","caller":"traceutil/trace.go:171","msg":"trace[894968042] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4036; }","duration":"135.984045ms","start":"2026-04-08T13:08:24.073666Z","end":"2026-04-08T13:08:24.20965Z","steps":["trace[894968042] 'agreement among raft nodes before linearized reading' (duration: 135.76248ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:41.745491Z","caller":"traceutil/trace.go:171","msg":"trace[153762287] transaction","detail":"{read_only:false; response_revision:4131; number_of_response:1; }","duration":"150.084377ms","start":"2026-04-08T13:08:41.59538Z","end":"2026-04-08T13:08:41.745464Z","steps":["trace[153762287] 'process raft request' (duration: 149.943024ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:41.87628Z","caller":"traceutil/trace.go:171","msg":"trace[440444694] transaction","detail":"{read_only:false; response_revision:4132; number_of_response:1; }","duration":"164.370973ms","start":"2026-04-08T13:08:41.711877Z","end":"2026-04-08T13:08:41.876248Z","steps":["trace[440444694] 'process raft request' (duration: 145.372604ms)","trace[440444694] 'compare' (duration: 18.553589ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:49.711688Z","caller":"traceutil/trace.go:171","msg":"trace[74297147] transaction","detail":"{read_only:false; response_revision:4503; number_of_response:1; }","duration":"174.116668ms","start":"2026-04-08T13:08:49.537541Z","end":"2026-04-08T13:08:49.711657Z","steps":["trace[74297147] 'process raft request' (duration: 108.936083ms)","trace[74297147] 'compare' (duration: 64.988361ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:49.713028Z","caller":"traceutil/trace.go:171","msg":"trace[464107987] transaction","detail":"{read_only:false; response_revision:4504; number_of_response:1; }","duration":"173.995426ms","start":"2026-04-08T13:08:49.538992Z","end":"2026-04-08T13:08:49.712987Z","steps":["trace[464107987] 'process raft request' (duration: 173.696039ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:49.713147Z","caller":"traceutil/trace.go:171","msg":"trace[155556498] transaction","detail":"{read_only:false; response_revision:4505; number_of_response:1; }","duration":"114.857547ms","start":"2026-04-08T13:08:49.598242Z","end":"2026-04-08T13:08:49.7131Z","steps":["trace[155556498] 'process raft request' (duration: 114.649412ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:49.713241Z","caller":"traceutil/trace.go:171","msg":"trace[933282371] linearizableReadLoop","detail":"{readStateIndex:4682; appliedIndex:4680; }","duration":"135.748501ms","start":"2026-04-08T13:08:49.577455Z","end":"2026-04-08T13:08:49.713203Z","steps":["trace[933282371] 'read index received' (duration: 69.187743ms)","trace[933282371] 'applied index is now lower than readState.Index' (duration: 66.557278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:08:49.713506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.041989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-08T13:08:49.71355Z","caller":"traceutil/trace.go:171","msg":"trace[759810163] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4506; }","duration":"136.153991ms","start":"2026-04-08T13:08:49.577385Z","end":"2026-04-08T13:08:49.713539Z","steps":["trace[759810163] 'agreement among raft nodes before linearized reading' (duration: 135.936025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:49.723408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.383488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-08T13:08:49.723531Z","caller":"traceutil/trace.go:171","msg":"trace[2114494882] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4507; }","duration":"110.572682ms","start":"2026-04-08T13:08:49.612925Z","end":"2026-04-08T13:08:49.723498Z","steps":["trace[2114494882] 'agreement among raft nodes before linearized reading' (duration: 110.159512ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:54.254268Z","caller":"traceutil/trace.go:171","msg":"trace[1484167403] linearizableReadLoop","detail":"{readStateIndex:4831; appliedIndex:4830; }","duration":"179.168341ms","start":"2026-04-08T13:08:54.075071Z","end":"2026-04-08T13:08:54.254239Z","steps":["trace[1484167403] 'read index received' (duration: 159.67799ms)","trace[1484167403] 'applied index is now lower than readState.Index' (duration: 19.489061ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:54.254326Z","caller":"traceutil/trace.go:171","msg":"trace[767400404] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4653; }","duration":"184.93157ms","start":"2026-04-08T13:08:54.06937Z","end":"2026-04-08T13:08:54.254302Z","steps":["trace[767400404] 'process raft request' (duration: 165.254005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:54.254487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.389337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-08T13:08:54.254562Z","caller":"traceutil/trace.go:171","msg":"trace[1351926166] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4653; }","duration":"179.49068ms","start":"2026-04-08T13:08:54.075054Z","end":"2026-04-08T13:08:54.254544Z","steps":["trace[1351926166] 'agreement among raft nodes before linearized reading' (duration: 179.298445ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:54.275905Z","caller":"traceutil/trace.go:171","msg":"trace[467914020] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"198.612911ms","start":"2026-04-08T13:08:54.077267Z","end":"2026-04-08T13:08:54.27588Z","steps":["trace[467914020] 'process raft request' (duration: 198.382506ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:54.276159Z","caller":"traceutil/trace.go:171","msg":"trace[1412568706] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"142.607837ms","start":"2026-04-08T13:08:54.13353Z","end":"2026-04-08T13:08:54.276137Z","steps":["trace[1412568706] 'process raft request' (duration: 142.262569ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:54.276242Z","caller":"traceutil/trace.go:171","msg":"trace[1970358791] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"137.284198ms","start":"2026-04-08T13:08:54.138935Z","end":"2026-04-08T13:08:54.276219Z","steps":["trace[1970358791] 'process raft request' (duration: 136.906019ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:54.276529Z","caller":"traceutil/trace.go:171","msg":"trace[1788687718] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"128.050804ms","start":"2026-04-08T13:08:54.148426Z","end":"2026-04-08T13:08:54.276477Z","steps":["trace[1788687718] 'process raft request' (duration: 127.718576ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:54.431724Z","caller":"traceutil/trace.go:171","msg":"trace[1840594403] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4659; }","duration":"137.115514ms","start":"2026-04-08T13:08:54.294588Z","end":"2026-04-08T13:08:54.431703Z","steps":["trace[1840594403] 'process raft request' (duration: 136.764766ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:54.435286Z","caller":"traceutil/trace.go:171","msg":"trace[1685701793] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"127.568813ms","start":"2026-04-08T13:08:54.30769Z","end":"2026-04-08T13:08:54.435259Z","steps":["trace[1685701793] 'process raft request' (duration: 127.203014ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:55.39067Z","caller":"traceutil/trace.go:171","msg":"trace[1267214210] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"166.581046ms","start":"2026-04-08T13:08:55.224052Z","end":"2026-04-08T13:08:55.390633Z","steps":["trace[1267214210] 'process raft request' (duration: 109.133228ms)","trace[1267214210] 'compare' (duration: 57.223043ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:55.392136Z","caller":"traceutil/trace.go:171","msg":"trace[181307669] linearizableReadLoop","detail":"{readStateIndex:4858; appliedIndex:4856; }","duration":"160.529451ms","start":"2026-04-08T13:08:55.231566Z","end":"2026-04-08T13:08:55.392096Z","steps":["trace[181307669] 'read index received' (duration: 101.632067ms)","trace[181307669] 'applied index is now lower than readState.Index' (duration: 58.895304ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:55.392257Z","caller":"traceutil/trace.go:171","msg":"trace[542158299] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"166.392333ms","start":"2026-04-08T13:08:55.225824Z","end":"2026-04-08T13:08:55.392216Z","steps":["trace[542158299] 'process raft request' (duration: 166.070125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:55.392521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.938491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-08T13:08:55.393214Z","caller":"traceutil/trace.go:171","msg":"trace[1958588372] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4679; }","duration":"161.671769ms","start":"2026-04-08T13:08:55.231507Z","end":"2026-04-08T13:08:55.393179Z","steps":["trace[1958588372] 'agreement among raft nodes before linearized reading' (duration: 160.785618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:55.393375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.414893ms","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-08T13:08:55.39349Z","caller":"traceutil/trace.go:171","msg":"trace[1488062667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4679; }","duration":"111.580517ms","start":"2026-04-08T13:08:55.281882Z","end":"2026-04-08T13:08:55.393462Z","steps":["trace[1488062667] 'agreement among raft nodes before linearized reading' (duration: 111.208399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:55.393674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.69486ms","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-08T13:08:55.393755Z","caller":"traceutil/trace.go:171","msg":"trace[349627356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4679; }","duration":"111.824193ms","start":"2026-04-08T13:08:55.281911Z","end":"2026-04-08T13:08:55.393735Z","steps":["trace[349627356] 'agreement among raft nodes before linearized reading' (duration: 111.633268ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:57.338313Z","caller":"traceutil/trace.go:171","msg":"trace[1587650046] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4880; }","duration":"135.708219ms","start":"2026-04-08T13:08:57.202582Z","end":"2026-04-08T13:08:57.33829Z","steps":["trace[1587650046] 'read index received' (duration: 135.574836ms)","trace[1587650046] 'applied index is now lower than readState.Index' (duration: 132.393µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:57.338401Z","caller":"traceutil/trace.go:171","msg":"trace[1866950134] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"171.549177ms","start":"2026-04-08T13:08:57.16679Z","end":"2026-04-08T13:08:57.338339Z","steps":["trace[1866950134] 'process raft request' (duration: 171.348482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:57.338623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.495842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T13:08:57.338696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.988ms","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-08T13:08:57.338732Z","caller":"traceutil/trace.go:171","msg":"trace[213596808] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4701; }","duration":"120.664076ms","start":"2026-04-08T13:08:57.21804Z","end":"2026-04-08T13:08:57.338704Z","steps":["trace[213596808] 'agreement among raft nodes before linearized reading' (duration: 120.41757ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:57.338752Z","caller":"traceutil/trace.go:171","msg":"trace[153194149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4701; }","duration":"115.081611ms","start":"2026-04-08T13:08:57.223655Z","end":"2026-04-08T13:08:57.338736Z","steps":["trace[153194149] 'agreement among raft nodes before linearized reading' (duration: 114.956378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:57.339052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.476069ms","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-08T13:08:57.339079Z","caller":"traceutil/trace.go:171","msg":"trace[798409871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4701; }","duration":"136.53564ms","start":"2026-04-08T13:08:57.202537Z","end":"2026-04-08T13:08:57.339073Z","steps":["trace[798409871] 'agreement among raft nodes before linearized reading' (duration: 135.882193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:58.689316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.919183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-04-08T13:08:58.689429Z","caller":"traceutil/trace.go:171","msg":"trace[1896978086] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4750; }","duration":"224.089726ms","start":"2026-04-08T13:08:58.465317Z","end":"2026-04-08T13:08:58.689406Z","steps":["trace[1896978086] 'range keys from in-memory index tree' (duration: 223.719988ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:58.694858Z","caller":"traceutil/trace.go:171","msg":"trace[443402195] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"133.431906ms","start":"2026-04-08T13:08:58.561406Z","end":"2026-04-08T13:08:58.694838Z","steps":["trace[443402195] 'process raft request' (duration: 133.244741ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:59.210443Z","caller":"traceutil/trace.go:171","msg":"trace[2090579397] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"122.583483ms","start":"2026-04-08T13:08:59.087822Z","end":"2026-04-08T13:08:59.210405Z","steps":["trace[2090579397] 'process raft request' (duration: 94.714879ms)","trace[2090579397] 'compare' (duration: 27.521515ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:59.431492Z","caller":"traceutil/trace.go:171","msg":"trace[717001595] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"153.45755ms","start":"2026-04-08T13:08:59.27801Z","end":"2026-04-08T13:08:59.431467Z","steps":["trace[717001595] 'process raft request' (duration: 153.306945ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:59.486676Z","caller":"traceutil/trace.go:171","msg":"trace[803197840] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"208.457239ms","start":"2026-04-08T13:08:59.278192Z","end":"2026-04-08T13:08:59.486649Z","steps":["trace[803197840] 'process raft request' (duration: 208.217213ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:59.486723Z","caller":"traceutil/trace.go:171","msg":"trace[825276839] linearizableReadLoop","detail":"{readStateIndex:4958; appliedIndex:4953; }","duration":"126.628671ms","start":"2026-04-08T13:08:59.360071Z","end":"2026-04-08T13:08:59.4867Z","steps":["trace[825276839] 'read index received' (duration: 71.444768ms)","trace[825276839] 'applied index is now lower than readState.Index' (duration: 55.182943ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:59.48678Z","caller":"traceutil/trace.go:171","msg":"trace[1972939739] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"208.222363ms","start":"2026-04-08T13:08:59.278524Z","end":"2026-04-08T13:08:59.486747Z","steps":["trace[1972939739] 'process raft request' (duration: 208.060789ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:59.4868Z","caller":"traceutil/trace.go:171","msg":"trace[317971054] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"207.998478ms","start":"2026-04-08T13:08:59.27878Z","end":"2026-04-08T13:08:59.486779Z","steps":["trace[317971054] 'process raft request' (duration: 207.881895ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:59.486827Z","caller":"traceutil/trace.go:171","msg":"trace[2103727329] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"208.155261ms","start":"2026-04-08T13:08:59.278633Z","end":"2026-04-08T13:08:59.486788Z","steps":["trace[2103727329] 'process raft request' (duration: 207.986057ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:59.486819Z","caller":"traceutil/trace.go:171","msg":"trace[1860176797] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"208.566762ms","start":"2026-04-08T13:08:59.278247Z","end":"2026-04-08T13:08:59.486814Z","steps":["trace[1860176797] 'process raft request' (duration: 208.303315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:59.486975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.800565ms","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-08T13:08:59.487002Z","caller":"traceutil/trace.go:171","msg":"trace[2084409704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4773; }","duration":"126.878057ms","start":"2026-04-08T13:08:59.360118Z","end":"2026-04-08T13:08:59.486996Z","steps":["trace[2084409704] 'agreement among raft nodes before linearized reading' (duration: 126.737554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:59.486972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.878427ms","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-08T13:08:59.487228Z","caller":"traceutil/trace.go:171","msg":"trace[1034393740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4773; }","duration":"127.181104ms","start":"2026-04-08T13:08:59.360036Z","end":"2026-04-08T13:08:59.487217Z","steps":["trace[1034393740] 'agreement among raft nodes before linearized reading' (duration: 126.789615ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:01.065214Z","caller":"traceutil/trace.go:171","msg":"trace[385824291] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"107.401127ms","start":"2026-04-08T13:09:00.957787Z","end":"2026-04-08T13:09:01.065188Z","steps":["trace[385824291] 'process raft request' (duration: 107.278684ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:01.065266Z","caller":"traceutil/trace.go:171","msg":"trace[1111667433] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"108.098284ms","start":"2026-04-08T13:09:00.957141Z","end":"2026-04-08T13:09:01.065239Z","steps":["trace[1111667433] 'process raft request' (duration: 70.684219ms)","trace[1111667433] 'compare' (duration: 37.059756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:09:02.448938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.688615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:09:02.449027Z","caller":"traceutil/trace.go:171","msg":"trace[1311513315] linearizableReadLoop","detail":"{readStateIndex:5086; appliedIndex:5085; }","duration":"381.626735ms","start":"2026-04-08T13:09:02.067388Z","end":"2026-04-08T13:09:02.449015Z","steps":["trace[1311513315] 'read index received' (duration: 203.816757ms)","trace[1311513315] 'applied index is now lower than readState.Index' (duration: 177.808498ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:02.449081Z","caller":"traceutil/trace.go:171","msg":"trace[942899431] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"432.403623ms","start":"2026-04-08T13:09:02.016671Z","end":"2026-04-08T13:09:02.449074Z","steps":["trace[942899431] 'process raft request' (duration: 254.497912ms)","trace[942899431] 'compare' (duration: 177.521601ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:09:02.449117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:02.016649Z","time spent":"432.450774ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3705,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:09:02.449624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.22817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:812089"} {"level":"info","ts":"2026-04-08T13:09:02.44969Z","caller":"traceutil/trace.go:171","msg":"trace[1608614437] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4899; }","duration":"382.405294ms","start":"2026-04-08T13:09:02.067268Z","end":"2026-04-08T13:09:02.449673Z","steps":["trace[1608614437] 'agreement among raft nodes before linearized reading' (duration: 381.84142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:02.449724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:02.06724Z","time spent":"382.476356ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":812113,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-04-08T13:09:02.60479Z","caller":"traceutil/trace.go:171","msg":"trace[1694777862] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"290.231624ms","start":"2026-04-08T13:09:02.314527Z","end":"2026-04-08T13:09:02.604759Z","steps":["trace[1694777862] 'process raft request' (duration: 240.724378ms)","trace[1694777862] 'compare' (duration: 49.204369ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:02.605129Z","caller":"traceutil/trace.go:171","msg":"trace[1727047136] linearizableReadLoop","detail":"{readStateIndex:5089; appliedIndex:5086; }","duration":"156.059892ms","start":"2026-04-08T13:09:02.449041Z","end":"2026-04-08T13:09:02.605101Z","steps":["trace[1727047136] 'read index received' (duration: 106.347951ms)","trace[1727047136] 'applied index is now lower than readState.Index' (duration: 49.710861ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:02.605136Z","caller":"traceutil/trace.go:171","msg":"trace[1740465168] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"230.450481ms","start":"2026-04-08T13:09:02.374658Z","end":"2026-04-08T13:09:02.605108Z","steps":["trace[1740465168] 'process raft request' (duration: 230.353319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:02.605248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.112254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:09:02.605348Z","caller":"traceutil/trace.go:171","msg":"trace[1075500693] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4902; }","duration":"269.248398ms","start":"2026-04-08T13:09:02.336075Z","end":"2026-04-08T13:09:02.605323Z","steps":["trace[1075500693] 'agreement among raft nodes before linearized reading' (duration: 269.073494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:02.605357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.055064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-45d4ca42-76cf-444c-b662-159617ab8792\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-04-08T13:09:02.605415Z","caller":"traceutil/trace.go:171","msg":"trace[1274555506] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-45d4ca42-76cf-444c-b662-159617ab8792; range_end:; response_count:1; response_revision:4902; }","duration":"528.178628ms","start":"2026-04-08T13:09:02.077218Z","end":"2026-04-08T13:09:02.605397Z","steps":["trace[1274555506] 'agreement among raft nodes before linearized reading' (duration: 527.979832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:02.605442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.993848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rx4fm\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-04-08T13:09:02.605495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:02.077193Z","time spent":"528.287231ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3744,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-45d4ca42-76cf-444c-b662-159617ab8792\" "} {"level":"info","ts":"2026-04-08T13:09:02.605502Z","caller":"traceutil/trace.go:171","msg":"trace[280058599] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rx4fm; range_end:; response_count:1; response_revision:4902; }","duration":"268.094221ms","start":"2026-04-08T13:09:02.33739Z","end":"2026-04-08T13:09:02.605484Z","steps":["trace[280058599] 'agreement among raft nodes before linearized reading' (duration: 267.955257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:02.60553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.877548ms","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-08T13:09:02.605589Z","caller":"traceutil/trace.go:171","msg":"trace[1939573271] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4902; }","duration":"500.96435ms","start":"2026-04-08T13:09:02.104606Z","end":"2026-04-08T13:09:02.60557Z","steps":["trace[1939573271] 'agreement among raft nodes before linearized reading' (duration: 500.794536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:02.605634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:02.104588Z","time spent":"501.033281ms","remote":"127.0.0.1:42344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":918,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "} {"level":"info","ts":"2026-04-08T13:09:02.605756Z","caller":"traceutil/trace.go:171","msg":"trace[998162306] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"284.300972ms","start":"2026-04-08T13:09:02.320805Z","end":"2026-04-08T13:09:02.605106Z","steps":["trace[998162306] 'process raft request' (duration: 283.901592ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:04.85898Z","caller":"traceutil/trace.go:171","msg":"trace[572814958] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"248.087187ms","start":"2026-04-08T13:09:04.61086Z","end":"2026-04-08T13:09:04.858947Z","steps":["trace[572814958] 'process raft request' (duration: 216.217017ms)","trace[572814958] 'compare' (duration: 31.582013ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:04.859687Z","caller":"traceutil/trace.go:171","msg":"trace[559540842] linearizableReadLoop","detail":"{readStateIndex:5136; appliedIndex:5134; }","duration":"244.851408ms","start":"2026-04-08T13:09:04.614819Z","end":"2026-04-08T13:09:04.85967Z","steps":["trace[559540842] 'read index received' (duration: 212.271081ms)","trace[559540842] 'applied index is now lower than readState.Index' (duration: 32.579497ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:04.85979Z","caller":"traceutil/trace.go:171","msg":"trace[1701327873] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"248.552457ms","start":"2026-04-08T13:09:04.611199Z","end":"2026-04-08T13:09:04.859752Z","steps":["trace[1701327873] 'process raft request' (duration: 248.380943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:04.859866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.998331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-45d4ca42-76cf-444c-b662-159617ab8792\" ","response":"range_response_count:1 size:3906"} {"level":"warn","ts":"2026-04-08T13:09:04.859874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.043522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rx4fm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-08T13:09:04.859906Z","caller":"traceutil/trace.go:171","msg":"trace[1171444633] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-45d4ca42-76cf-444c-b662-159617ab8792; range_end:; response_count:1; response_revision:4946; }","duration":"245.091773ms","start":"2026-04-08T13:09:04.614806Z","end":"2026-04-08T13:09:04.859897Z","steps":["trace[1171444633] 'agreement among raft nodes before linearized reading' (duration: 244.96466ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:04.859932Z","caller":"traceutil/trace.go:171","msg":"trace[1450710970] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rx4fm; range_end:; response_count:1; response_revision:4946; }","duration":"245.143935ms","start":"2026-04-08T13:09:04.614772Z","end":"2026-04-08T13:09:04.859916Z","steps":["trace[1450710970] 'agreement among raft nodes before linearized reading' (duration: 244.982981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:04.860221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.254013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:1 size:799"} {"level":"info","ts":"2026-04-08T13:09:04.860345Z","caller":"traceutil/trace.go:171","msg":"trace[109727623] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:1; response_revision:4946; }","duration":"227.452067ms","start":"2026-04-08T13:09:04.632871Z","end":"2026-04-08T13:09:04.860323Z","steps":["trace[109727623] 'agreement among raft nodes before linearized reading' (duration: 227.065308ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:05.829964Z","caller":"traceutil/trace.go:171","msg":"trace[1981124183] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"195.386583ms","start":"2026-04-08T13:09:05.634554Z","end":"2026-04-08T13:09:05.829941Z","steps":["trace[1981124183] 'process raft request' (duration: 113.155475ms)","trace[1981124183] 'compare' (duration: 82.002322ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:05.830358Z","caller":"traceutil/trace.go:171","msg":"trace[656835901] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"195.098886ms","start":"2026-04-08T13:09:05.635203Z","end":"2026-04-08T13:09:05.830302Z","steps":["trace[656835901] 'process raft request' (duration: 194.696126ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:05.830378Z","caller":"traceutil/trace.go:171","msg":"trace[1381094742] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"195.107736ms","start":"2026-04-08T13:09:05.635242Z","end":"2026-04-08T13:09:05.83035Z","steps":["trace[1381094742] 'process raft request' (duration: 194.919611ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:06.85705Z","caller":"traceutil/trace.go:171","msg":"trace[1576992723] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"239.881768ms","start":"2026-04-08T13:09:06.617141Z","end":"2026-04-08T13:09:06.857023Z","steps":["trace[1576992723] 'process raft request' (duration: 239.366376ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:08.198294Z","caller":"traceutil/trace.go:171","msg":"trace[580670945] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"121.149868ms","start":"2026-04-08T13:09:08.077118Z","end":"2026-04-08T13:09:08.198267Z","steps":["trace[580670945] 'process raft request' (duration: 120.943823ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:08.339121Z","caller":"traceutil/trace.go:171","msg":"trace[866397124] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"134.116062ms","start":"2026-04-08T13:09:08.204969Z","end":"2026-04-08T13:09:08.339085Z","steps":["trace[866397124] 'process raft request' (duration: 133.812205ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:08.33912Z","caller":"traceutil/trace.go:171","msg":"trace[978013451] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"134.104991ms","start":"2026-04-08T13:09:08.204976Z","end":"2026-04-08T13:09:08.339081Z","steps":["trace[978013451] 'process raft request' (duration: 134.036249ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:08.490264Z","caller":"traceutil/trace.go:171","msg":"trace[1700773935] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"285.169713ms","start":"2026-04-08T13:09:08.205062Z","end":"2026-04-08T13:09:08.490232Z","steps":["trace[1700773935] 'process raft request' (duration: 250.791572ms)","trace[1700773935] 'compare' (duration: 33.969861ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:08.490392Z","caller":"traceutil/trace.go:171","msg":"trace[1575843335] linearizableReadLoop","detail":"{readStateIndex:5218; appliedIndex:5214; }","duration":"260.542148ms","start":"2026-04-08T13:09:08.229827Z","end":"2026-04-08T13:09:08.490369Z","steps":["trace[1575843335] 'read index received' (duration: 108.999485ms)","trace[1575843335] 'applied index is now lower than readState.Index' (duration: 151.541863ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:08.490539Z","caller":"traceutil/trace.go:171","msg":"trace[1805449363] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"285.292886ms","start":"2026-04-08T13:09:08.205239Z","end":"2026-04-08T13:09:08.490532Z","steps":["trace[1805449363] 'process raft request' (duration: 284.931297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:08.490773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.937437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-08T13:09:08.490834Z","caller":"traceutil/trace.go:171","msg":"trace[878316955] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5026; }","duration":"261.04064ms","start":"2026-04-08T13:09:08.229774Z","end":"2026-04-08T13:09:08.490815Z","steps":["trace[878316955] 'agreement among raft nodes before linearized reading' (duration: 260.881096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:12.657886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.468367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:09:12.658141Z","caller":"traceutil/trace.go:171","msg":"trace[392105075] linearizableReadLoop","detail":"{readStateIndex:5287; appliedIndex:5285; }","duration":"266.890401ms","start":"2026-04-08T13:09:12.391232Z","end":"2026-04-08T13:09:12.658122Z","steps":["trace[392105075] 'read index received' (duration: 75.089586ms)","trace[392105075] 'applied index is now lower than readState.Index' (duration: 191.799755ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:12.658211Z","caller":"traceutil/trace.go:171","msg":"trace[1170391163] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"287.50174ms","start":"2026-04-08T13:09:12.370672Z","end":"2026-04-08T13:09:12.658173Z","steps":["trace[1170391163] 'process raft request' (duration: 287.343776ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:12.658253Z","caller":"traceutil/trace.go:171","msg":"trace[1094984805] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"292.748886ms","start":"2026-04-08T13:09:12.365427Z","end":"2026-04-08T13:09:12.658176Z","steps":["trace[1094984805] 'process raft request' (duration: 100.875669ms)","trace[1094984805] 'compare' (duration: 191.180041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:09:12.658358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.297808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/memcached-memcached\" ","response":"range_response_count:1 size:543"} {"level":"info","ts":"2026-04-08T13:09:12.658395Z","caller":"traceutil/trace.go:171","msg":"trace[157319924] range","detail":"{range_begin:/registry/serviceaccounts/openstack/memcached-memcached; range_end:; response_count:1; response_revision:5093; }","duration":"137.37066ms","start":"2026-04-08T13:09:12.521012Z","end":"2026-04-08T13:09:12.658383Z","steps":["trace[157319924] 'agreement among raft nodes before linearized reading' (duration: 137.214577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:12.658471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.209199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:7 size:224034"} {"level":"info","ts":"2026-04-08T13:09:12.658548Z","caller":"traceutil/trace.go:171","msg":"trace[1676284076] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:7; response_revision:5093; }","duration":"267.331872ms","start":"2026-04-08T13:09:12.391195Z","end":"2026-04-08T13:09:12.658526Z","steps":["trace[1676284076] 'agreement among raft nodes before linearized reading' (duration: 267.013694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:12.658683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.203423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xlnd2.18a463aa7b242201\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-08T13:09:12.658738Z","caller":"traceutil/trace.go:171","msg":"trace[463779983] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xlnd2.18a463aa7b242201; range_end:; response_count:1; response_revision:5093; }","duration":"129.301525ms","start":"2026-04-08T13:09:12.529421Z","end":"2026-04-08T13:09:12.658722Z","steps":["trace[463779983] 'agreement among raft nodes before linearized reading' (duration: 128.951497ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:13.812153Z","caller":"traceutil/trace.go:171","msg":"trace[1447354435] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"103.584654ms","start":"2026-04-08T13:09:13.708542Z","end":"2026-04-08T13:09:13.812127Z","steps":["trace[1447354435] 'process raft request' (duration: 103.45744ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:40.086956Z","caller":"traceutil/trace.go:171","msg":"trace[37687513] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5604; }","duration":"257.883986ms","start":"2026-04-08T13:09:39.829029Z","end":"2026-04-08T13:09:40.086913Z","steps":["trace[37687513] 'read index received' (duration: 257.684711ms)","trace[37687513] 'applied index is now lower than readState.Index' (duration: 196.695µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:40.08706Z","caller":"traceutil/trace.go:171","msg":"trace[602597500] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"265.636523ms","start":"2026-04-08T13:09:39.821388Z","end":"2026-04-08T13:09:40.087024Z","steps":["trace[602597500] 'process raft request' (duration: 265.292705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:40.087244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.190193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:1 size:603"} {"level":"info","ts":"2026-04-08T13:09:40.08732Z","caller":"traceutil/trace.go:171","msg":"trace[1156045182] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:1; response_revision:5402; }","duration":"258.307665ms","start":"2026-04-08T13:09:39.828993Z","end":"2026-04-08T13:09:40.087301Z","steps":["trace[1156045182] 'agreement among raft nodes before linearized reading' (duration: 258.142771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:40.141401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.686666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:09:40.141498Z","caller":"traceutil/trace.go:171","msg":"trace[1838776350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5403; }","duration":"122.875791ms","start":"2026-04-08T13:09:40.018594Z","end":"2026-04-08T13:09:40.14147Z","steps":["trace[1838776350] 'agreement among raft nodes before linearized reading' (duration: 122.685466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:40.141536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.030282ms","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-08T13:09:40.141571Z","caller":"traceutil/trace.go:171","msg":"trace[1078671752] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"271.245929ms","start":"2026-04-08T13:09:39.870298Z","end":"2026-04-08T13:09:40.141544Z","steps":["trace[1078671752] 'process raft request' (duration: 270.760038ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:40.141614Z","caller":"traceutil/trace.go:171","msg":"trace[476216330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5403; }","duration":"132.192267ms","start":"2026-04-08T13:09:40.009408Z","end":"2026-04-08T13:09:40.141601Z","steps":["trace[476216330] 'agreement among raft nodes before linearized reading' (duration: 132.018952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:40.141666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.587748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T13:09:40.141737Z","caller":"traceutil/trace.go:171","msg":"trace[1457796799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5403; }","duration":"130.67082ms","start":"2026-04-08T13:09:40.011046Z","end":"2026-04-08T13:09:40.141717Z","steps":["trace[1457796799] 'agreement among raft nodes before linearized reading' (duration: 130.555828ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:40.311333Z","caller":"traceutil/trace.go:171","msg":"trace[354636918] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"165.829002ms","start":"2026-04-08T13:09:40.145479Z","end":"2026-04-08T13:09:40.311308Z","steps":["trace[354636918] 'process raft request' (duration: 165.678449ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:40.31288Z","caller":"traceutil/trace.go:171","msg":"trace[1805956949] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"167.101913ms","start":"2026-04-08T13:09:40.145757Z","end":"2026-04-08T13:09:40.312859Z","steps":["trace[1805956949] 'process raft request' (duration: 166.904709ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:40.312942Z","caller":"traceutil/trace.go:171","msg":"trace[1181988111] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"166.97859ms","start":"2026-04-08T13:09:40.145927Z","end":"2026-04-08T13:09:40.312906Z","steps":["trace[1181988111] 'process raft request' (duration: 166.829647ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:40.313033Z","caller":"traceutil/trace.go:171","msg":"trace[1997381098] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"166.131779ms","start":"2026-04-08T13:09:40.146885Z","end":"2026-04-08T13:09:40.313017Z","steps":["trace[1997381098] 'process raft request' (duration: 166.007246ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:40.313059Z","caller":"traceutil/trace.go:171","msg":"trace[488595796] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"166.356494ms","start":"2026-04-08T13:09:40.146663Z","end":"2026-04-08T13:09:40.313019Z","steps":["trace[488595796] 'process raft request' (duration: 166.18735ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:40.312954Z","caller":"traceutil/trace.go:171","msg":"trace[262504006] linearizableReadLoop","detail":"{readStateIndex:5610; appliedIndex:5606; }","duration":"166.53505ms","start":"2026-04-08T13:09:40.146401Z","end":"2026-04-08T13:09:40.312936Z","steps":["trace[262504006] 'read index received' (duration: 164.764967ms)","trace[262504006] 'applied index is now lower than readState.Index' (duration: 1.768983ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:40.31318Z","caller":"traceutil/trace.go:171","msg":"trace[684927251] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"167.010091ms","start":"2026-04-08T13:09:40.146109Z","end":"2026-04-08T13:09:40.313119Z","steps":["trace[684927251] 'process raft request' (duration: 166.692664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:40.31329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.876318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-04-08T13:09:40.313331Z","caller":"traceutil/trace.go:171","msg":"trace[2028799758] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:5409; }","duration":"166.98867ms","start":"2026-04-08T13:09:40.146332Z","end":"2026-04-08T13:09:40.31332Z","steps":["trace[2028799758] 'agreement among raft nodes before linearized reading' (duration: 166.864647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:51.508081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.77909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:09:51.508215Z","caller":"traceutil/trace.go:171","msg":"trace[1547742059] linearizableReadLoop","detail":"{readStateIndex:5674; appliedIndex:5672; }","duration":"410.025575ms","start":"2026-04-08T13:09:51.09815Z","end":"2026-04-08T13:09:51.508176Z","steps":["trace[1547742059] 'read index received' (duration: 61.457161ms)","trace[1547742059] 'applied index is now lower than readState.Index' (duration: 348.567304ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:51.508246Z","caller":"traceutil/trace.go:171","msg":"trace[895450930] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"413.760675ms","start":"2026-04-08T13:09:51.094455Z","end":"2026-04-08T13:09:51.508216Z","steps":["trace[895450930] 'process raft request' (duration: 212.579975ms)","trace[895450930] 'compare' (duration: 200.658528ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:09:51.508373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:51.094443Z","time spent":"413.871038ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:09:51.508473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.510101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-04-08T13:09:51.50854Z","caller":"traceutil/trace.go:171","msg":"trace[2141044204] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:5469; }","duration":"392.605293ms","start":"2026-04-08T13:09:51.115915Z","end":"2026-04-08T13:09:51.50852Z","steps":["trace[2141044204] 'agreement among raft nodes before linearized reading' (duration: 392.382488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:51.508592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:51.115895Z","time spent":"392.683995ms","remote":"127.0.0.1:42344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1065,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" "} {"level":"warn","ts":"2026-04-08T13:09:51.508628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.464136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/\" range_end:\"/registry/events/ingress-nginx0\" limit:500 ","response":"range_response_count:30 size:23669"} {"level":"info","ts":"2026-04-08T13:09:51.508723Z","caller":"traceutil/trace.go:171","msg":"trace[360560806] range","detail":"{range_begin:/registry/events/ingress-nginx/; range_end:/registry/events/ingress-nginx0; response_count:30; response_revision:5469; }","duration":"410.572218ms","start":"2026-04-08T13:09:51.098135Z","end":"2026-04-08T13:09:51.508707Z","steps":["trace[360560806] 'agreement among raft nodes before linearized reading' (duration: 410.120397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:51.508797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:51.098126Z","time spent":"410.656771ms","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":30,"response size":23693,"request content":"key:\"/registry/events/ingress-nginx/\" range_end:\"/registry/events/ingress-nginx0\" limit:500 "} {"level":"warn","ts":"2026-04-08T13:09:51.508982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.754148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/\" range_end:\"/registry/services/endpoints/openstack0\" limit:500 ","response":"range_response_count:9 size:8482"} {"level":"warn","ts":"2026-04-08T13:09:51.509002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.548408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rx4fm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-08T13:09:51.509068Z","caller":"traceutil/trace.go:171","msg":"trace[683940341] range","detail":"{range_begin:/registry/services/endpoints/openstack/; range_end:/registry/services/endpoints/openstack0; response_count:9; response_revision:5469; }","duration":"329.873722ms","start":"2026-04-08T13:09:51.179176Z","end":"2026-04-08T13:09:51.50905Z","steps":["trace[683940341] 'agreement among raft nodes before linearized reading' (duration: 329.628915ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:51.509097Z","caller":"traceutil/trace.go:171","msg":"trace[982576804] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rx4fm; range_end:; response_count:1; response_revision:5469; }","duration":"384.666922ms","start":"2026-04-08T13:09:51.124409Z","end":"2026-04-08T13:09:51.509076Z","steps":["trace[982576804] 'agreement among raft nodes before linearized reading' (duration: 384.408364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:51.50912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:51.17916Z","time spent":"329.944163ms","remote":"127.0.0.1:42316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":9,"response size":8506,"request content":"key:\"/registry/services/endpoints/openstack/\" range_end:\"/registry/services/endpoints/openstack0\" limit:500 "} {"level":"warn","ts":"2026-04-08T13:09:51.50914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:51.124401Z","time spent":"384.730183ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-rx4fm\" "} {"level":"warn","ts":"2026-04-08T13:09:51.509157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.492663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/\" range_end:\"/registry/ingress/openstack0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:09:51.509285Z","caller":"traceutil/trace.go:171","msg":"trace[1589069474] range","detail":"{range_begin:/registry/ingress/openstack/; range_end:/registry/ingress/openstack0; response_count:0; response_revision:5469; }","duration":"315.686348ms","start":"2026-04-08T13:09:51.193568Z","end":"2026-04-08T13:09:51.509254Z","steps":["trace[1589069474] 'agreement among raft nodes before linearized reading' (duration: 314.779275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:51.50936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:51.19351Z","time spent":"315.827891ms","remote":"127.0.0.1:42460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/openstack/\" range_end:\"/registry/ingress/openstack0\" limit:500 "} {"level":"warn","ts":"2026-04-08T13:09:51.734489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.676672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" limit:500 ","response":"range_response_count:2 size:107263"} {"level":"info","ts":"2026-04-08T13:09:51.73455Z","caller":"traceutil/trace.go:171","msg":"trace[1644604663] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:5469; }","duration":"126.764465ms","start":"2026-04-08T13:09:51.607773Z","end":"2026-04-08T13:09:51.734538Z","steps":["trace[1644604663] 'range keys from in-memory index tree' (duration: 126.538229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:51.734739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.016265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:09:51.734767Z","caller":"traceutil/trace.go:171","msg":"trace[855593482] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:5469; }","duration":"121.058776ms","start":"2026-04-08T13:09:51.613701Z","end":"2026-04-08T13:09:51.73476Z","steps":["trace[855593482] 'count revisions from in-memory index tree' (duration: 120.977344ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:10:07.10927Z","caller":"traceutil/trace.go:171","msg":"trace[1216932721] linearizableReadLoop","detail":"{readStateIndex:5758; appliedIndex:5757; }","duration":"483.657341ms","start":"2026-04-08T13:10:06.625587Z","end":"2026-04-08T13:10:07.109245Z","steps":["trace[1216932721] 'read index received' (duration: 483.072437ms)","trace[1216932721] 'applied index is now lower than readState.Index' (duration: 583.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:10:07.109378Z","caller":"traceutil/trace.go:171","msg":"trace[1828215289] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"486.870869ms","start":"2026-04-08T13:10:06.622463Z","end":"2026-04-08T13:10:07.109334Z","steps":["trace[1828215289] 'process raft request' (duration: 486.337026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:10:07.109559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.941158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-persistentvolumesusage\" ","response":"range_response_count:1 size:9966"} {"level":"warn","ts":"2026-04-08T13:10:07.109604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.27019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-pod-total\" ","response":"range_response_count:1 size:16057"} {"level":"warn","ts":"2026-04-08T13:10:07.109548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:10:06.622439Z","time spent":"487.012552ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414004,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:10:07.109653Z","caller":"traceutil/trace.go:171","msg":"trace[1565091297] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-pod-total; range_end:; response_count:1; response_revision:5550; }","duration":"473.363062ms","start":"2026-04-08T13:10:06.636278Z","end":"2026-04-08T13:10:07.109641Z","steps":["trace[1565091297] 'agreement among raft nodes before linearized reading' (duration: 473.108216ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:10:07.109665Z","caller":"traceutil/trace.go:171","msg":"trace[1263537585] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-persistentvolumesusage; range_end:; response_count:1; response_revision:5550; }","duration":"484.083391ms","start":"2026-04-08T13:10:06.625555Z","end":"2026-04-08T13:10:07.109639Z","steps":["trace[1263537585] 'agreement among raft nodes before linearized reading' (duration: 483.811765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:10:07.10969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:10:06.636259Z","time spent":"473.422833ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":16081,"request content":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-pod-total\" "} {"level":"warn","ts":"2026-04-08T13:10:07.109714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:10:06.62554Z","time spent":"484.162943ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":9990,"request content":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-persistentvolumesusage\" "} {"level":"warn","ts":"2026-04-08T13:10:07.109886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.707879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-08T13:10:07.109935Z","caller":"traceutil/trace.go:171","msg":"trace[1068540945] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5550; }","duration":"456.790681ms","start":"2026-04-08T13:10:06.653131Z","end":"2026-04-08T13:10:07.109922Z","steps":["trace[1068540945] 'agreement among raft nodes before linearized reading' (duration: 456.404031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:10:07.109967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:10:06.653117Z","time spent":"456.842122ms","remote":"127.0.0.1:42642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":577,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:10:15.913803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2781} {"level":"info","ts":"2026-04-08T13:10:15.977536Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2781,"took":"62.514086ms","hash":3716930747,"current-db-size-bytes":54689792,"current-db-size":"55 MB","current-db-size-in-use-bytes":31010816,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-08T13:10:15.977611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3716930747,"revision":2781,"compact-revision":-1} {"level":"info","ts":"2026-04-08T13:10:18.429721Z","caller":"traceutil/trace.go:171","msg":"trace[993611474] linearizableReadLoop","detail":"{readStateIndex:5819; appliedIndex:5818; }","duration":"154.546878ms","start":"2026-04-08T13:10:18.275154Z","end":"2026-04-08T13:10:18.429701Z","steps":["trace[993611474] 'read index received' (duration: 154.365724ms)","trace[993611474] 'applied index is now lower than readState.Index' (duration: 180.484µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:10:18.429801Z","caller":"traceutil/trace.go:171","msg":"trace[683713457] transaction","detail":"{read_only:false; response_revision:5607; number_of_response:1; }","duration":"177.767232ms","start":"2026-04-08T13:10:18.251994Z","end":"2026-04-08T13:10:18.429761Z","steps":["trace[683713457] 'process raft request' (duration: 177.575957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:10:18.429949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.89622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/sh.helm.release.v1.node-feature-discovery.v1\" ","response":"range_response_count:1 size:27780"} {"level":"info","ts":"2026-04-08T13:10:18.429976Z","caller":"traceutil/trace.go:171","msg":"trace[78926155] range","detail":"{range_begin:/registry/secrets/monitoring/sh.helm.release.v1.node-feature-discovery.v1; range_end:; response_count:1; response_revision:5607; }","duration":"150.978842ms","start":"2026-04-08T13:10:18.278989Z","end":"2026-04-08T13:10:18.429968Z","steps":["trace[78926155] 'agreement among raft nodes before linearized reading' (duration: 150.827348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:10:18.430539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.269717ms","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-08T13:10:18.430643Z","caller":"traceutil/trace.go:171","msg":"trace[849352210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5607; }","duration":"155.520063ms","start":"2026-04-08T13:10:18.2751Z","end":"2026-04-08T13:10:18.43062Z","steps":["trace[849352210] 'agreement among raft nodes before linearized reading' (duration: 154.690642ms)"],"step_count":1}