{"level":"warn","ts":"2026-08-14T08:26:17.400489Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T08:26:17.400571Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-08-14T08:26:17.400628Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T08:26:17.400642Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-14T08:26:17.400667Z","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-08-14T08:26:17.401274Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-08-14T08:26:17.401345Z","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":8,"max-cpu-available":8,"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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-08-14T08:26:17.405956Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.398414ms"} {"level":"info","ts":"2026-08-14T08:26:17.41473Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-08-14T08:26:17.414819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T08:26:17.414842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-08-14T08:26:17.414853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T08:26:17.414862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-08-14T08:26:17.415016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-08-14T08:26:17.421838Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T08:26:17.423233Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T08:26:17.425092Z","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-08-14T08:26:17.427841Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T08:26:17.428027Z","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-08-14T08:26:17.428135Z","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-08-14T08:26:17.428166Z","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-08-14T08:26:17.428358Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T08:26:17.43447Z","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-08-14T08:26:17.434576Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-14T08:26:17.434632Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-14T08:26:17.434841Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T08:26:17.434904Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T08:26:17.439459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-08-14T08:26:17.439595Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-14T08:26:18.315701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T08:26:18.315756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T08:26:18.315781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-08-14T08:26:18.315801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-08-14T08:26:18.315808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-14T08:26:18.315823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-08-14T08:26:18.31584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-14T08:26:18.316743Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T08:26:18.317579Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T08:26:18.31758Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T08:26:18.317614Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T08:26:18.317937Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T08:26:18.317979Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T08:26:18.318001Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T08:26:18.318041Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T08:26:18.318074Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T08:26:18.31997Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-08-14T08:26:18.3209Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T08:26:43.190363Z","caller":"traceutil/trace.go:171","msg":"trace[1200436743] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"327.51756ms","start":"2026-08-14T08:26:42.862817Z","end":"2026-08-14T08:26:43.190335Z","steps":["trace[1200436743] 'read index received' (duration: 327.392076ms)","trace[1200436743] 'applied index is now lower than readState.Index' (duration: 124.144µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:26:43.190483Z","caller":"traceutil/trace.go:171","msg":"trace[1943299748] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"613.181031ms","start":"2026-08-14T08:26:42.577253Z","end":"2026-08-14T08:26:43.190434Z","steps":["trace[1943299748] 'process raft request' (duration: 612.901563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:26:43.190537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.701065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:26:43.190614Z","caller":"traceutil/trace.go:171","msg":"trace[1123943701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"327.836179ms","start":"2026-08-14T08:26:42.862763Z","end":"2026-08-14T08:26:43.190599Z","steps":["trace[1123943701] 'agreement among raft nodes before linearized reading' (duration: 327.690484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:26:43.190659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:26:42.862743Z","time spent":"327.907581ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T08:26:43.191871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:26:42.577238Z","time spent":"613.374197ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:26:58.10663Z","caller":"traceutil/trace.go:171","msg":"trace[2129675327] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"283.119987ms","start":"2026-08-14T08:26:57.823485Z","end":"2026-08-14T08:26:58.106605Z","steps":["trace[2129675327] 'process raft request' (duration: 282.995064ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.118962Z","caller":"traceutil/trace.go:171","msg":"trace[89793611] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"295.330163ms","start":"2026-08-14T08:26:57.82361Z","end":"2026-08-14T08:26:58.11894Z","steps":["trace[89793611] 'process raft request' (duration: 295.159419ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.119194Z","caller":"traceutil/trace.go:171","msg":"trace[789756537] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"295.571219ms","start":"2026-08-14T08:26:57.82361Z","end":"2026-08-14T08:26:58.119181Z","steps":["trace[789756537] 'process raft request' (duration: 295.060867ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.119239Z","caller":"traceutil/trace.go:171","msg":"trace[2117108561] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"288.625072ms","start":"2026-08-14T08:26:57.830591Z","end":"2026-08-14T08:26:58.119216Z","steps":["trace[2117108561] 'process raft request' (duration: 288.341655ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.119258Z","caller":"traceutil/trace.go:171","msg":"trace[1731613539] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"282.295721ms","start":"2026-08-14T08:26:57.836942Z","end":"2026-08-14T08:26:58.119237Z","steps":["trace[1731613539] 'process raft request' (duration: 282.085546ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.119322Z","caller":"traceutil/trace.go:171","msg":"trace[190057381] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"292.367026ms","start":"2026-08-14T08:26:57.826949Z","end":"2026-08-14T08:26:58.119316Z","steps":["trace[190057381] 'process raft request' (duration: 291.858274ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.119375Z","caller":"traceutil/trace.go:171","msg":"trace[1279606372] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"289.471514ms","start":"2026-08-14T08:26:57.829895Z","end":"2026-08-14T08:26:58.119366Z","steps":["trace[1279606372] 'process raft request' (duration: 288.996662ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.119379Z","caller":"traceutil/trace.go:171","msg":"trace[153031645] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"285.071942ms","start":"2026-08-14T08:26:57.834297Z","end":"2026-08-14T08:26:58.119369Z","steps":["trace[153031645] 'process raft request' (duration: 284.687242ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.119372Z","caller":"traceutil/trace.go:171","msg":"trace[1096903491] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"290.462919ms","start":"2026-08-14T08:26:57.828876Z","end":"2026-08-14T08:26:58.119339Z","steps":["trace[1096903491] 'process raft request' (duration: 289.975597ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.11947Z","caller":"traceutil/trace.go:171","msg":"trace[1291681096] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"275.016227ms","start":"2026-08-14T08:26:57.844446Z","end":"2026-08-14T08:26:58.119463Z","steps":["trace[1291681096] 'process raft request' (duration: 274.637618ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.119625Z","caller":"traceutil/trace.go:171","msg":"trace[1884634077] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"258.763726ms","start":"2026-08-14T08:26:57.860836Z","end":"2026-08-14T08:26:58.1196Z","steps":["trace[1884634077] 'process raft request' (duration: 258.304974ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.120166Z","caller":"traceutil/trace.go:171","msg":"trace[1303575554] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"151.892177ms","start":"2026-08-14T08:26:57.968265Z","end":"2026-08-14T08:26:58.120158Z","steps":["trace[1303575554] 'process raft request' (duration: 151.322313ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:26:58.120115Z","caller":"traceutil/trace.go:171","msg":"trace[1366321207] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:551; }","duration":"213.55062ms","start":"2026-08-14T08:26:57.906551Z","end":"2026-08-14T08:26:58.120101Z","steps":["trace[1366321207] 'read index received' (duration: 200.057361ms)","trace[1366321207] 'applied index is now lower than readState.Index' (duration: 13.492148ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:26:58.120481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.921119ms","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-08-14T08:26:58.120687Z","caller":"traceutil/trace.go:171","msg":"trace[259374729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"214.173736ms","start":"2026-08-14T08:26:57.906502Z","end":"2026-08-14T08:26:58.120675Z","steps":["trace[259374729] 'agreement among raft nodes before linearized reading' (duration: 213.795516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:26:58.127099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.474154ms","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-08-14T08:26:58.127279Z","caller":"traceutil/trace.go:171","msg":"trace[262654915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"194.638178ms","start":"2026-08-14T08:26:57.932567Z","end":"2026-08-14T08:26:58.127205Z","steps":["trace[262654915] 'agreement among raft nodes before linearized reading' (duration: 194.379431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:26:59.748316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.144422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:208485"} {"level":"info","ts":"2026-08-14T08:26:59.748405Z","caller":"traceutil/trace.go:171","msg":"trace[683498708] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:609; }","duration":"253.276414ms","start":"2026-08-14T08:26:59.495111Z","end":"2026-08-14T08:26:59.748387Z","steps":["trace[683498708] 'range keys from in-memory index tree' (duration: 252.903046ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:27:04.022873Z","caller":"traceutil/trace.go:171","msg":"trace[1046842253] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"158.856447ms","start":"2026-08-14T08:27:03.863994Z","end":"2026-08-14T08:27:04.02285Z","steps":["trace[1046842253] 'read index received' (duration: 158.679543ms)","trace[1046842253] 'applied index is now lower than readState.Index' (duration: 176.184µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:27:04.022988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.97844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:27:04.023009Z","caller":"traceutil/trace.go:171","msg":"trace[1377761590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"159.049211ms","start":"2026-08-14T08:27:03.863953Z","end":"2026-08-14T08:27:04.023002Z","steps":["trace[1377761590] 'agreement among raft nodes before linearized reading' (duration: 158.977959ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:27:04.023062Z","caller":"traceutil/trace.go:171","msg":"trace[210292855] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"159.384169ms","start":"2026-08-14T08:27:03.863643Z","end":"2026-08-14T08:27:04.023028Z","steps":["trace[210292855] 'process raft request' (duration: 159.084752ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:27:04.187842Z","caller":"traceutil/trace.go:171","msg":"trace[2112006945] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"134.746355ms","start":"2026-08-14T08:27:04.052913Z","end":"2026-08-14T08:27:04.187659Z","steps":["trace[2112006945] 'process raft request' (duration: 134.238653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:05.017955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.539799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:27:05.018189Z","caller":"traceutil/trace.go:171","msg":"trace[952224147] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"800.574441ms","start":"2026-08-14T08:27:04.217566Z","end":"2026-08-14T08:27:05.018141Z","steps":["trace[952224147] 'read index received' (duration: 49.785375ms)","trace[952224147] 'applied index is now lower than readState.Index' (duration: 750.788086ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:27:05.018314Z","caller":"traceutil/trace.go:171","msg":"trace[1362400202] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"823.015616ms","start":"2026-08-14T08:27:04.195275Z","end":"2026-08-14T08:27:05.018291Z","steps":["trace[1362400202] 'process raft request' (duration: 822.772031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:05.018485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:04.195263Z","time spent":"823.14561ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:27:05.018665Z","caller":"traceutil/trace.go:171","msg":"trace[1588468310] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"824.499711ms","start":"2026-08-14T08:27:04.194132Z","end":"2026-08-14T08:27:05.018632Z","steps":["trace[1588468310] 'process raft request' (duration: 73.092831ms)","trace[1588468310] 'compare' (duration: 750.085989ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:27:05.018494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.922858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-76znk\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-14T08:27:05.018838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:04.194111Z","time spent":"824.636754ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:27:05.018882Z","caller":"traceutil/trace.go:171","msg":"trace[1543144924] range","detail":"{range_begin:/registry/pods/kube-system/cilium-76znk; range_end:; response_count:1; response_revision:641; }","duration":"801.313628ms","start":"2026-08-14T08:27:04.21754Z","end":"2026-08-14T08:27:05.018854Z","steps":["trace[1543144924] 'agreement among raft nodes before linearized reading' (duration: 800.851027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:05.018567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.55318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T08:27:05.018371Z","caller":"traceutil/trace.go:171","msg":"trace[948098441] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"771.05921ms","start":"2026-08-14T08:27:04.2473Z","end":"2026-08-14T08:27:05.01836Z","steps":["trace[948098441] 'process raft request' (duration: 770.805034ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:27:05.019026Z","caller":"traceutil/trace.go:171","msg":"trace[512182841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"587.00929ms","start":"2026-08-14T08:27:04.431976Z","end":"2026-08-14T08:27:05.018985Z","steps":["trace[512182841] 'agreement among raft nodes before linearized reading' (duration: 586.543699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:05.019111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:04.43196Z","time spent":"587.122093ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T08:27:05.019118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:04.247288Z","time spent":"771.787177ms","remote":"127.0.0.1:35056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T08:27:05.018603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.328199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T08:27:05.018935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:04.217531Z","time spent":"801.391569ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-76znk\" "} {"level":"info","ts":"2026-08-14T08:27:05.019199Z","caller":"traceutil/trace.go:171","msg":"trace[836694374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"154.914172ms","start":"2026-08-14T08:27:04.86427Z","end":"2026-08-14T08:27:05.019184Z","steps":["trace[836694374] 'agreement among raft nodes before linearized reading' (duration: 154.319589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:06.363441Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342720768298056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T08:27:06.823698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408453612s","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-08-14T08:27:06.823774Z","caller":"traceutil/trace.go:171","msg":"trace[1752710404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"1.408569035s","start":"2026-08-14T08:27:05.415188Z","end":"2026-08-14T08:27:06.823757Z","steps":["trace[1752710404] 'range keys from in-memory index tree' (duration: 1.408210437s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:06.823814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:05.415167Z","time spent":"1.408638397s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T08:27:06.846168Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.403670976s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T08:27:06.851989Z","caller":"traceutil/trace.go:171","msg":"trace[807030275] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"989.126886ms","start":"2026-08-14T08:27:05.862819Z","end":"2026-08-14T08:27:06.851946Z","steps":["trace[807030275] 'read index received' (duration: 983.547598ms)","trace[807030275] 'applied index is now lower than readState.Index' (duration: 5.578558ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:27:06.852086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.257029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:27:06.852106Z","caller":"traceutil/trace.go:171","msg":"trace[1118431623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"989.333311ms","start":"2026-08-14T08:27:05.862766Z","end":"2026-08-14T08:27:06.8521Z","steps":["trace[1118431623] 'agreement among raft nodes before linearized reading' (duration: 989.2764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:06.852129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:05.862719Z","time spent":"989.404603ms","remote":"127.0.0.1:34508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T08:27:06.852306Z","caller":"traceutil/trace.go:171","msg":"trace[649632413] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.411265701s","start":"2026-08-14T08:27:05.441018Z","end":"2026-08-14T08:27:06.852284Z","steps":["trace[649632413] 'process raft request' (duration: 1.406354508s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:06.852406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:05.440991Z","time spent":"1.411356543s","remote":"127.0.0.1:34632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:27:08.701179Z","caller":"traceutil/trace.go:171","msg":"trace[327906335] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"265.707622ms","start":"2026-08-14T08:27:08.435456Z","end":"2026-08-14T08:27:08.701163Z","steps":["trace[327906335] 'process raft request' (duration: 265.633851ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:27:18.657957Z","caller":"traceutil/trace.go:171","msg":"trace[1758949506] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"343.833311ms","start":"2026-08-14T08:27:18.314086Z","end":"2026-08-14T08:27:18.657919Z","steps":["trace[1758949506] 'process raft request' (duration: 343.620687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:18.658194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:18.314067Z","time spent":"344.002094ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5032,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:27:21.318297Z","caller":"traceutil/trace.go:171","msg":"trace[1106214363] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"395.24511ms","start":"2026-08-14T08:27:20.923024Z","end":"2026-08-14T08:27:21.318269Z","steps":["trace[1106214363] 'process raft request' (duration: 395.043726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:21.318457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:20.923Z","time spent":"395.372812ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:27:21.829176Z","caller":"traceutil/trace.go:171","msg":"trace[1466060469] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"731.091468ms","start":"2026-08-14T08:27:21.098059Z","end":"2026-08-14T08:27:21.82915Z","steps":["trace[1466060469] 'read index received' (duration: 219.999404ms)","trace[1466060469] 'applied index is now lower than readState.Index' (duration: 511.091144ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:27:21.829303Z","caller":"traceutil/trace.go:171","msg":"trace[312035737] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"802.419391ms","start":"2026-08-14T08:27:21.026852Z","end":"2026-08-14T08:27:21.829272Z","steps":["trace[312035737] 'process raft request' (duration: 802.142125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:21.829399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.322842ms","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-08-14T08:27:21.829484Z","caller":"traceutil/trace.go:171","msg":"trace[1436122562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"731.459076ms","start":"2026-08-14T08:27:21.098003Z","end":"2026-08-14T08:27:21.829462Z","steps":["trace[1436122562] 'agreement among raft nodes before linearized reading' (duration: 731.243211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:21.829488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:21.026827Z","time spent":"802.564073ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:27:21.829489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.902402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ncz7t\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-08-14T08:27:21.829529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:21.097983Z","time spent":"731.537047ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T08:27:21.829547Z","caller":"traceutil/trace.go:171","msg":"trace[1118770846] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ncz7t; range_end:; response_count:1; response_revision:801; }","duration":"508.991304ms","start":"2026-08-14T08:27:21.320539Z","end":"2026-08-14T08:27:21.829531Z","steps":["trace[1118770846] 'agreement among raft nodes before linearized reading' (duration: 508.862631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:21.829587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:21.320522Z","time spent":"509.057175ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ncz7t\" "} {"level":"warn","ts":"2026-08-14T08:27:21.829602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.583972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T08:27:21.829648Z","caller":"traceutil/trace.go:171","msg":"trace[367679434] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:801; }","duration":"475.697254ms","start":"2026-08-14T08:27:21.353937Z","end":"2026-08-14T08:27:21.829634Z","steps":["trace[367679434] 'agreement among raft nodes before linearized reading' (duration: 475.496531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:21.829689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.262287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T08:27:21.829692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:21.353915Z","time spent":"475.769745ms","remote":"127.0.0.1:34554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"info","ts":"2026-08-14T08:27:21.829726Z","caller":"traceutil/trace.go:171","msg":"trace[632708793] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:801; }","duration":"616.340859ms","start":"2026-08-14T08:27:21.213376Z","end":"2026-08-14T08:27:21.829716Z","steps":["trace[632708793] 'agreement among raft nodes before linearized reading' (duration: 616.278178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:21.829763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:21.213356Z","time spent":"616.399661ms","remote":"127.0.0.1:34730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-08-14T08:27:21.829651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.639793ms","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-08-14T08:27:21.829938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.313949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-14T08:27:21.829913Z","caller":"traceutil/trace.go:171","msg":"trace[453958807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"702.931738ms","start":"2026-08-14T08:27:21.126919Z","end":"2026-08-14T08:27:21.829851Z","steps":["trace[453958807] 'agreement among raft nodes before linearized reading' (duration: 702.406848ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:27:21.829996Z","caller":"traceutil/trace.go:171","msg":"trace[2102397603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"699.40313ms","start":"2026-08-14T08:27:21.130582Z","end":"2026-08-14T08:27:21.829985Z","steps":["trace[2102397603] 'agreement among raft nodes before linearized reading' (duration: 699.293198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:21.830026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:21.130567Z","time spent":"699.452951ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T08:27:21.830031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:21.126899Z","time spent":"703.117413ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T08:27:52.695879Z","caller":"traceutil/trace.go:171","msg":"trace[1320156791] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"705.856106ms","start":"2026-08-14T08:27:51.989999Z","end":"2026-08-14T08:27:52.695855Z","steps":["trace[1320156791] 'process raft request' (duration: 705.694914ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:27:52.696054Z","caller":"traceutil/trace.go:171","msg":"trace[1617733345] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1237; }","duration":"464.966384ms","start":"2026-08-14T08:27:52.231062Z","end":"2026-08-14T08:27:52.696028Z","steps":["trace[1617733345] 'read index received' (duration: 464.64106ms)","trace[1617733345] 'applied index is now lower than readState.Index' (duration: 323.214µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:27:52.696146Z","caller":"traceutil/trace.go:171","msg":"trace[1991264452] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"704.811741ms","start":"2026-08-14T08:27:51.991292Z","end":"2026-08-14T08:27:52.696104Z","steps":["trace[1991264452] 'process raft request' (duration: 704.528467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:52.696684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:51.989983Z","time spent":"706.594126ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:27:52.696284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.211578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-14T08:27:52.696933Z","caller":"traceutil/trace.go:171","msg":"trace[1406926563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1203; }","duration":"465.841957ms","start":"2026-08-14T08:27:52.23102Z","end":"2026-08-14T08:27:52.696862Z","steps":["trace[1406926563] 'agreement among raft nodes before linearized reading' (duration: 465.087126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:52.697017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:52.231003Z","time spent":"466.002889ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T08:27:52.696796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:51.991283Z","time spent":"705.396309ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:27:52.697757Z","caller":"traceutil/trace.go:171","msg":"trace[1851974887] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"348.728545ms","start":"2026-08-14T08:27:52.349009Z","end":"2026-08-14T08:27:52.697737Z","steps":["trace[1851974887] 'process raft request' (duration: 348.614724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:27:52.697913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:27:52.348982Z","time spent":"348.840247ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:09.881957Z","caller":"traceutil/trace.go:171","msg":"trace[1715629439] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"343.143827ms","start":"2026-08-14T08:28:09.538775Z","end":"2026-08-14T08:28:09.881918Z","steps":["trace[1715629439] 'process raft request' (duration: 341.094381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:09.882169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:09.538744Z","time spent":"343.320819ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:28:09.882329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.684541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-plck6\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-08-14T08:28:09.882475Z","caller":"traceutil/trace.go:171","msg":"trace[851496674] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-plck6; range_end:; response_count:1; response_revision:1344; }","duration":"342.858902ms","start":"2026-08-14T08:28:09.539573Z","end":"2026-08-14T08:28:09.882432Z","steps":["trace[851496674] 'agreement among raft nodes before linearized reading' (duration: 342.551239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:09.882525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:09.539564Z","time spent":"342.951003ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":4219,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-plck6\" "} {"level":"info","ts":"2026-08-14T08:28:09.882665Z","caller":"traceutil/trace.go:171","msg":"trace[1651746942] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"343.037125ms","start":"2026-08-14T08:28:09.539611Z","end":"2026-08-14T08:28:09.882648Z","steps":["trace[1651746942] 'read index received' (duration: 340.183249ms)","trace[1651746942] 'applied index is now lower than readState.Index' (duration: 1.843923ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:09.883344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.517067ms","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-08-14T08:28:09.883487Z","caller":"traceutil/trace.go:171","msg":"trace[312144836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"295.68386ms","start":"2026-08-14T08:28:09.587791Z","end":"2026-08-14T08:28:09.883474Z","steps":["trace[312144836] 'agreement among raft nodes before linearized reading' (duration: 295.177563ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:18.195847Z","caller":"traceutil/trace.go:171","msg":"trace[1366134845] linearizableReadLoop","detail":"{readStateIndex:1518; appliedIndex:1517; }","duration":"277.493966ms","start":"2026-08-14T08:28:17.918326Z","end":"2026-08-14T08:28:18.19582Z","steps":["trace[1366134845] 'read index received' (duration: 277.333163ms)","trace[1366134845] 'applied index is now lower than readState.Index' (duration: 159.723µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:28:18.195948Z","caller":"traceutil/trace.go:171","msg":"trace[432510940] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"289.118806ms","start":"2026-08-14T08:28:17.906798Z","end":"2026-08-14T08:28:18.195917Z","steps":["trace[432510940] 'process raft request' (duration: 288.866732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:18.19607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.695338ms","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-08-14T08:28:18.196109Z","caller":"traceutil/trace.go:171","msg":"trace[1529869410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1474; }","duration":"277.80224ms","start":"2026-08-14T08:28:17.918293Z","end":"2026-08-14T08:28:18.196096Z","steps":["trace[1529869410] 'agreement among raft nodes before linearized reading' (duration: 277.624438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:18.467251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.005433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:28:18.467365Z","caller":"traceutil/trace.go:171","msg":"trace[142226641] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1518; }","duration":"260.897209ms","start":"2026-08-14T08:28:18.206441Z","end":"2026-08-14T08:28:18.467338Z","steps":["trace[142226641] 'read index received' (duration: 153.694854ms)","trace[142226641] 'applied index is now lower than readState.Index' (duration: 107.201065ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:28:18.467472Z","caller":"traceutil/trace.go:171","msg":"trace[366834005] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"267.316577ms","start":"2026-08-14T08:28:18.200143Z","end":"2026-08-14T08:28:18.46746Z","steps":["trace[366834005] 'process raft request' (duration: 160.019699ms)","trace[366834005] 'compare' (duration: 106.899632ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:18.467726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.275105ms","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-08-14T08:28:18.467782Z","caller":"traceutil/trace.go:171","msg":"trace[277322656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1475; }","duration":"261.354935ms","start":"2026-08-14T08:28:18.206412Z","end":"2026-08-14T08:28:18.467767Z","steps":["trace[277322656] 'agreement among raft nodes before linearized reading' (duration: 261.205864ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:19.095744Z","caller":"traceutil/trace.go:171","msg":"trace[849733356] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"280.80598ms","start":"2026-08-14T08:28:18.814905Z","end":"2026-08-14T08:28:19.095711Z","steps":["trace[849733356] 'read index received' (duration: 220.050616ms)","trace[849733356] 'applied index is now lower than readState.Index' (duration: 60.753974ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:28:19.095827Z","caller":"traceutil/trace.go:171","msg":"trace[2085217945] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"623.765514ms","start":"2026-08-14T08:28:18.472032Z","end":"2026-08-14T08:28:19.095798Z","steps":["trace[2085217945] 'process raft request' (duration: 562.847328ms)","trace[2085217945] 'compare' (duration: 60.625213ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:19.095952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:18.472007Z","time spent":"623.879765ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:28:19.09598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.071903ms","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-08-14T08:28:19.096023Z","caller":"traceutil/trace.go:171","msg":"trace[1216247092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1476; }","duration":"281.156804ms","start":"2026-08-14T08:28:18.814855Z","end":"2026-08-14T08:28:19.096012Z","steps":["trace[1216247092] 'agreement among raft nodes before linearized reading' (duration: 280.999952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:19.096171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.18941ms","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-08-14T08:28:19.096201Z","caller":"traceutil/trace.go:171","msg":"trace[242147667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1476; }","duration":"278.25515ms","start":"2026-08-14T08:28:18.817938Z","end":"2026-08-14T08:28:19.096193Z","steps":["trace[242147667] 'agreement among raft nodes before linearized reading' (duration: 278.176429ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:19.096415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.743165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T08:28:19.096482Z","caller":"traceutil/trace.go:171","msg":"trace[2112624609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1476; }","duration":"277.828105ms","start":"2026-08-14T08:28:18.818644Z","end":"2026-08-14T08:28:19.096473Z","steps":["trace[2112624609] 'agreement among raft nodes before linearized reading' (duration: 277.710614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:44.243997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.037187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:28:44.244514Z","caller":"traceutil/trace.go:171","msg":"trace[1226727730] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1897; }","duration":"289.186029ms","start":"2026-08-14T08:28:43.954911Z","end":"2026-08-14T08:28:44.244097Z","steps":["trace[1226727730] 'count revisions from in-memory index tree' (duration: 288.872246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:44.244747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.966335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:28:44.244824Z","caller":"traceutil/trace.go:171","msg":"trace[1263381375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1897; }","duration":"381.095115ms","start":"2026-08-14T08:28:43.863711Z","end":"2026-08-14T08:28:44.244806Z","steps":["trace[1263381375] 'range keys from in-memory index tree' (duration: 380.596701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:44.244863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:43.863689Z","time spent":"381.165877ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T08:28:44.246251Z","caller":"traceutil/trace.go:171","msg":"trace[624533096] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"181.616932ms","start":"2026-08-14T08:28:44.0646Z","end":"2026-08-14T08:28:44.246217Z","steps":["trace[624533096] 'read index received' (duration: 181.37652ms)","trace[624533096] 'applied index is now lower than readState.Index' (duration: 239.302µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:28:44.246267Z","caller":"traceutil/trace.go:171","msg":"trace[741177391] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"186.27264ms","start":"2026-08-14T08:28:44.059971Z","end":"2026-08-14T08:28:44.246244Z","steps":["trace[741177391] 'process raft request' (duration: 186.075708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:44.246494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.892965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-14T08:28:44.246551Z","caller":"traceutil/trace.go:171","msg":"trace[1319822756] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1898; }","duration":"181.985727ms","start":"2026-08-14T08:28:44.064547Z","end":"2026-08-14T08:28:44.246533Z","steps":["trace[1319822756] 'agreement among raft nodes before linearized reading' (duration: 181.774694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:44.246571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.729781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63189e88-9e41-4826-9e7a-d2e2e5c79a93\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-14T08:28:44.24662Z","caller":"traceutil/trace.go:171","msg":"trace[140639510] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63189e88-9e41-4826-9e7a-d2e2e5c79a93; range_end:; response_count:1; response_revision:1898; }","duration":"170.814532ms","start":"2026-08-14T08:28:44.075792Z","end":"2026-08-14T08:28:44.246607Z","steps":["trace[140639510] 'agreement among raft nodes before linearized reading' (duration: 170.652659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.390977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.641923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:28:45.391594Z","caller":"traceutil/trace.go:171","msg":"trace[484288189] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"1.092058659s","start":"2026-08-14T08:28:44.299484Z","end":"2026-08-14T08:28:45.391543Z","steps":["trace[484288189] 'process raft request' (duration: 761.747639ms)","trace[484288189] 'compare' (duration: 329.452372ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:45.391929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.581058ms","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-08-14T08:28:45.391942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:44.299445Z","time spent":"1.09226108s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:45.391977Z","caller":"traceutil/trace.go:171","msg":"trace[872559507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1900; }","duration":"794.698229ms","start":"2026-08-14T08:28:44.597268Z","end":"2026-08-14T08:28:45.391966Z","steps":["trace[872559507] 'agreement among raft nodes before linearized reading' (duration: 794.409125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.392054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:44.597244Z","time spent":"794.796231ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T08:28:45.391641Z","caller":"traceutil/trace.go:171","msg":"trace[734156432] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1968; }","duration":"794.266844ms","start":"2026-08-14T08:28:44.597347Z","end":"2026-08-14T08:28:45.391614Z","steps":["trace[734156432] 'read index received' (duration: 463.899214ms)","trace[734156432] 'applied index is now lower than readState.Index' (duration: 330.36652ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:28:45.392578Z","caller":"traceutil/trace.go:171","msg":"trace[1900481435] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"1.003743012s","start":"2026-08-14T08:28:44.388799Z","end":"2026-08-14T08:28:45.392542Z","steps":["trace[1900481435] 'process raft request' (duration: 1.002745122s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.392845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:44.388763Z","time spent":"1.003994285s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:45.546875Z","caller":"traceutil/trace.go:171","msg":"trace[1302110686] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"453.092141ms","start":"2026-08-14T08:28:45.093747Z","end":"2026-08-14T08:28:45.546839Z","steps":["trace[1302110686] 'process raft request' (duration: 434.225619ms)","trace[1302110686] 'compare' (duration: 18.691021ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:45.547121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.093723Z","time spent":"453.264334ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:45.54732Z","caller":"traceutil/trace.go:171","msg":"trace[1180340815] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1970; }","duration":"155.002872ms","start":"2026-08-14T08:28:45.392294Z","end":"2026-08-14T08:28:45.547297Z","steps":["trace[1180340815] 'read index received' (duration: 135.700864ms)","trace[1180340815] 'applied index is now lower than readState.Index' (duration: 19.300257ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:28:45.547471Z","caller":"traceutil/trace.go:171","msg":"trace[1679016262] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"297.710647ms","start":"2026-08-14T08:28:45.24975Z","end":"2026-08-14T08:28:45.54746Z","steps":["trace[1679016262] 'process raft request' (duration: 297.04777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.547737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.771559ms","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-08-14T08:28:45.547805Z","caller":"traceutil/trace.go:171","msg":"trace[226485016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1903; }","duration":"305.900321ms","start":"2026-08-14T08:28:45.241889Z","end":"2026-08-14T08:28:45.547789Z","steps":["trace[226485016] 'agreement among raft nodes before linearized reading' (duration: 305.615778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.547828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.172251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-08-14T08:28:45.547821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.456905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-14T08:28:45.547886Z","caller":"traceutil/trace.go:171","msg":"trace[1424726180] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1903; }","duration":"295.246492ms","start":"2026-08-14T08:28:45.252625Z","end":"2026-08-14T08:28:45.547871Z","steps":["trace[1424726180] 'agreement among raft nodes before linearized reading' (duration: 295.06172ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:45.547921Z","caller":"traceutil/trace.go:171","msg":"trace[354004528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1903; }","duration":"301.599856ms","start":"2026-08-14T08:28:45.246303Z","end":"2026-08-14T08:28:45.547902Z","steps":["trace[354004528] 'agreement among raft nodes before linearized reading' (duration: 301.315014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.547947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.139408ms","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-08-14T08:28:45.547979Z","caller":"traceutil/trace.go:171","msg":"trace[1382312559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1903; }","duration":"327.217828ms","start":"2026-08-14T08:28:45.220755Z","end":"2026-08-14T08:28:45.547973Z","steps":["trace[1382312559] 'agreement among raft nodes before linearized reading' (duration: 327.114867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.547999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.220722Z","time spent":"327.272519ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T08:28:45.54799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.246291Z","time spent":"301.689717ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T08:28:45.547961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.149771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63189e88-9e41-4826-9e7a-d2e2e5c79a93\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-14T08:28:45.548039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.442149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-14T08:28:45.547842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.241868Z","time spent":"305.968632ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T08:28:45.548087Z","caller":"traceutil/trace.go:171","msg":"trace[250906696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1903; }","duration":"325.555861ms","start":"2026-08-14T08:28:45.22252Z","end":"2026-08-14T08:28:45.548075Z","steps":["trace[250906696] 'agreement among raft nodes before linearized reading' (duration: 325.263078ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:45.548085Z","caller":"traceutil/trace.go:171","msg":"trace[695673861] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63189e88-9e41-4826-9e7a-d2e2e5c79a93; range_end:; response_count:1; response_revision:1903; }","duration":"295.449574ms","start":"2026-08-14T08:28:45.252592Z","end":"2026-08-14T08:28:45.548042Z","steps":["trace[695673861] 'agreement among raft nodes before linearized reading' (duration: 294.962829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.548121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.222495Z","time spent":"325.618721ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T08:28:45.548154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.963844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:28:45.548222Z","caller":"traceutil/trace.go:171","msg":"trace[1054156556] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"150.726327ms","start":"2026-08-14T08:28:45.39744Z","end":"2026-08-14T08:28:45.548166Z","steps":["trace[1054156556] 'process raft request' (duration: 149.590285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.54825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.822154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63189e88-9e41-4826-9e7a-d2e2e5c79a93\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-14T08:28:45.548416Z","caller":"traceutil/trace.go:171","msg":"trace[59421079] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63189e88-9e41-4826-9e7a-d2e2e5c79a93; range_end:; response_count:1; response_revision:1903; }","duration":"806.019786ms","start":"2026-08-14T08:28:44.742384Z","end":"2026-08-14T08:28:45.548403Z","steps":["trace[59421079] 'agreement among raft nodes before linearized reading' (duration: 805.043647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.548457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:44.742361Z","time spent":"806.086616ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63189e88-9e41-4826-9e7a-d2e2e5c79a93\" "} {"level":"info","ts":"2026-08-14T08:28:45.54825Z","caller":"traceutil/trace.go:171","msg":"trace[1077778605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1903; }","duration":"681.106665ms","start":"2026-08-14T08:28:44.867128Z","end":"2026-08-14T08:28:45.548235Z","steps":["trace[1077778605] 'agreement among raft nodes before linearized reading' (duration: 680.956084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:45.548569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:44.86711Z","time spent":"681.432878ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T08:28:46.365624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342720768300546,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T08:28:46.512506Z","caller":"traceutil/trace.go:171","msg":"trace[482074373] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"957.522652ms","start":"2026-08-14T08:28:45.554948Z","end":"2026-08-14T08:28:46.512471Z","steps":["trace[482074373] 'process raft request' (duration: 868.971584ms)","trace[482074373] 'compare' (duration: 88.414747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:46.512763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.554909Z","time spent":"957.683534ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:46.518539Z","caller":"traceutil/trace.go:171","msg":"trace[2031181611] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"963.166779ms","start":"2026-08-14T08:28:45.55535Z","end":"2026-08-14T08:28:46.518516Z","steps":["trace[2031181611] 'process raft request' (duration: 963.087359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:46.518638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.555306Z","time spent":"963.282301ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:46.518655Z","caller":"traceutil/trace.go:171","msg":"trace[716844022] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"963.303361ms","start":"2026-08-14T08:28:45.555302Z","end":"2026-08-14T08:28:46.518605Z","steps":["trace[716844022] 'process raft request' (duration: 963.031569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:46.518904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.555269Z","time spent":"963.511303ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:46.51892Z","caller":"traceutil/trace.go:171","msg":"trace[2116802418] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"932.459276ms","start":"2026-08-14T08:28:45.586434Z","end":"2026-08-14T08:28:46.518893Z","steps":["trace[2116802418] 'process raft request' (duration: 932.375304ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:46.518971Z","caller":"traceutil/trace.go:171","msg":"trace[587239953] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"963.158089ms","start":"2026-08-14T08:28:45.555789Z","end":"2026-08-14T08:28:46.518948Z","steps":["trace[587239953] 'process raft request' (duration: 962.910796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:46.519079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.58642Z","time spent":"932.570747ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:46.519087Z","caller":"traceutil/trace.go:171","msg":"trace[489355037] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"963.517784ms","start":"2026-08-14T08:28:45.555551Z","end":"2026-08-14T08:28:46.519069Z","steps":["trace[489355037] 'process raft request' (duration: 962.934897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:46.519113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.555777Z","time spent":"963.26223ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:28:46.519205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.555541Z","time spent":"963.587574ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:46.51924Z","caller":"traceutil/trace.go:171","msg":"trace[1564306572] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1974; }","duration":"654.703503ms","start":"2026-08-14T08:28:45.864527Z","end":"2026-08-14T08:28:46.519231Z","steps":["trace[1564306572] 'read index received' (duration: 559.404886ms)","trace[1564306572] 'applied index is now lower than readState.Index' (duration: 95.297667ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:46.519293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.783524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:28:46.519319Z","caller":"traceutil/trace.go:171","msg":"trace[532405544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1911; }","duration":"654.919195ms","start":"2026-08-14T08:28:45.864392Z","end":"2026-08-14T08:28:46.519312Z","steps":["trace[532405544] 'agreement among raft nodes before linearized reading' (duration: 654.872194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:46.519352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.864291Z","time spent":"655.048876ms","remote":"127.0.0.1:34508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T08:28:46.519354Z","caller":"traceutil/trace.go:171","msg":"trace[407853203] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"612.257529ms","start":"2026-08-14T08:28:45.907086Z","end":"2026-08-14T08:28:46.519343Z","steps":["trace[407853203] 'process raft request' (duration: 611.765635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:46.51952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:45.907058Z","time spent":"612.427761ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:28:50.985473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.255036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:28:50.985611Z","caller":"traceutil/trace.go:171","msg":"trace[1954289952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1953; }","duration":"121.428688ms","start":"2026-08-14T08:28:50.86416Z","end":"2026-08-14T08:28:50.985588Z","steps":["trace[1954289952] 'range keys from in-memory index tree' (duration: 121.158175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:58.524044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.533056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:28:58.524739Z","caller":"traceutil/trace.go:171","msg":"trace[2113327339] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"930.313456ms","start":"2026-08-14T08:28:57.594348Z","end":"2026-08-14T08:28:58.524661Z","steps":["trace[2113327339] 'process raft request' (duration: 929.833031ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:58.524807Z","caller":"traceutil/trace.go:171","msg":"trace[710057751] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"930.414177ms","start":"2026-08-14T08:28:57.594345Z","end":"2026-08-14T08:28:58.524759Z","steps":["trace[710057751] 'process raft request' (duration: 197.879333ms)","trace[710057751] 'compare' (duration: 731.371964ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:58.524983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:57.594317Z","time spent":"930.528808ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:28:58.525017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:57.594311Z","time spent":"930.60912ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:58.524394Z","caller":"traceutil/trace.go:171","msg":"trace[1835325453] linearizableReadLoop","detail":"{readStateIndex:2078; appliedIndex:2076; }","duration":"897.595402ms","start":"2026-08-14T08:28:57.626777Z","end":"2026-08-14T08:28:58.524373Z","steps":["trace[1835325453] 'read index received' (duration: 165.465662ms)","trace[1835325453] 'applied index is now lower than readState.Index' (duration: 732.12821ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:58.525189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.4003ms","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-08-14T08:28:58.525315Z","caller":"traceutil/trace.go:171","msg":"trace[1726638927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2004; }","duration":"898.495341ms","start":"2026-08-14T08:28:57.626761Z","end":"2026-08-14T08:28:58.525256Z","steps":["trace[1726638927] 'agreement among raft nodes before linearized reading' (duration: 898.246459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:58.525369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:57.62669Z","time spent":"898.670223ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T08:28:58.525523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.013946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-fdrmk\" ","response":"range_response_count:1 size:5855"} {"level":"info","ts":"2026-08-14T08:28:58.525607Z","caller":"traceutil/trace.go:171","msg":"trace[408668441] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-fdrmk; range_end:; response_count:1; response_revision:2004; }","duration":"339.161098ms","start":"2026-08-14T08:28:58.186425Z","end":"2026-08-14T08:28:58.525586Z","steps":["trace[408668441] 'agreement among raft nodes before linearized reading' (duration: 338.917636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:58.525657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:58.186388Z","time spent":"339.255838ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":5877,"request content":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-fdrmk\" "} {"level":"warn","ts":"2026-08-14T08:28:58.52736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.467463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-14T08:28:58.527535Z","caller":"traceutil/trace.go:171","msg":"trace[999978819] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2004; }","duration":"888.680526ms","start":"2026-08-14T08:28:57.638826Z","end":"2026-08-14T08:28:58.527507Z","steps":["trace[999978819] 'agreement among raft nodes before linearized reading' (duration: 886.472265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:58.527614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:57.638814Z","time spent":"888.785557ms","remote":"127.0.0.1:38038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-14T08:28:59.374548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.287782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:28:59.374784Z","caller":"traceutil/trace.go:171","msg":"trace[99228531] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"112.740685ms","start":"2026-08-14T08:28:59.262025Z","end":"2026-08-14T08:28:59.374765Z","steps":["trace[99228531] 'process raft request' (duration: 112.696794ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:59.375142Z","caller":"traceutil/trace.go:171","msg":"trace[1164162239] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"839.11578ms","start":"2026-08-14T08:28:58.536014Z","end":"2026-08-14T08:28:59.375129Z","steps":["trace[1164162239] 'process raft request' (duration: 560.130301ms)","trace[1164162239] 'compare' (duration: 278.17102ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:28:59.375211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:58.535989Z","time spent":"839.18935ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:28:59.375348Z","caller":"traceutil/trace.go:171","msg":"trace[1704895955] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2078; }","duration":"794.918665ms","start":"2026-08-14T08:28:58.580384Z","end":"2026-08-14T08:28:59.375303Z","steps":["trace[1704895955] 'read index received' (duration: 32.561191ms)","trace[1704895955] 'applied index is now lower than readState.Index' (duration: 762.354114ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:28:59.375653Z","caller":"traceutil/trace.go:171","msg":"trace[77355457] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"810.653096ms","start":"2026-08-14T08:28:58.56499Z","end":"2026-08-14T08:28:59.375643Z","steps":["trace[77355457] 'process raft request' (duration: 809.662206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:59.375723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:58.564949Z","time spent":"810.741408ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:28:59.375737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.3339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T08:28:59.375826Z","caller":"traceutil/trace.go:171","msg":"trace[1651646535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2008; }","duration":"795.501001ms","start":"2026-08-14T08:28:58.580303Z","end":"2026-08-14T08:28:59.375804Z","steps":["trace[1651646535] 'agreement among raft nodes before linearized reading' (duration: 795.235108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:59.375877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:58.580282Z","time spent":"795.585861ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T08:28:59.375931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.988838ms","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-08-14T08:28:59.376022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.37477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:28:59.37602Z","caller":"traceutil/trace.go:171","msg":"trace[1758866193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2008; }","duration":"788.111209ms","start":"2026-08-14T08:28:58.587887Z","end":"2026-08-14T08:28:59.375999Z","steps":["trace[1758866193] 'agreement among raft nodes before linearized reading' (duration: 787.815997ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:59.376055Z","caller":"traceutil/trace.go:171","msg":"trace[610775447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2008; }","duration":"512.466841ms","start":"2026-08-14T08:28:58.863578Z","end":"2026-08-14T08:28:59.376045Z","steps":["trace[610775447] 'agreement among raft nodes before linearized reading' (duration: 512.41782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:59.376069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:58.587879Z","time spent":"788.181361ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T08:28:59.376082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:58.863545Z","time spent":"512.532192ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T08:28:59.376277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.940787ms","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-08-14T08:28:59.376359Z","caller":"traceutil/trace.go:171","msg":"trace[1294090545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2008; }","duration":"792.053508ms","start":"2026-08-14T08:28:58.584284Z","end":"2026-08-14T08:28:59.376337Z","steps":["trace[1294090545] 'agreement among raft nodes before linearized reading' (duration: 791.764605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:59.376359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.501892ms","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-08-14T08:28:59.376398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:58.584274Z","time spent":"792.116619ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T08:28:59.376407Z","caller":"traceutil/trace.go:171","msg":"trace[762476023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2008; }","duration":"793.606283ms","start":"2026-08-14T08:28:58.582789Z","end":"2026-08-14T08:28:59.376395Z","steps":["trace[762476023] 'agreement among raft nodes before linearized reading' (duration: 792.839965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:28:59.376484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:28:58.582765Z","time spent":"793.707663ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T08:28:59.591582Z","caller":"traceutil/trace.go:171","msg":"trace[175792531] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"207.178815ms","start":"2026-08-14T08:28:59.384371Z","end":"2026-08-14T08:28:59.591549Z","steps":["trace[175792531] 'process raft request' (duration: 186.178415ms)","trace[175792531] 'compare' (duration: 20.730198ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:28:59.592123Z","caller":"traceutil/trace.go:171","msg":"trace[193725940] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"207.562849ms","start":"2026-08-14T08:28:59.384533Z","end":"2026-08-14T08:28:59.592096Z","steps":["trace[193725940] 'process raft request' (duration: 207.277087ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:59.592197Z","caller":"traceutil/trace.go:171","msg":"trace[874993610] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"207.262866ms","start":"2026-08-14T08:28:59.384915Z","end":"2026-08-14T08:28:59.592178Z","steps":["trace[874993610] 'process raft request' (duration: 207.097954ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:59.592584Z","caller":"traceutil/trace.go:171","msg":"trace[1997993276] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"207.293496ms","start":"2026-08-14T08:28:59.385273Z","end":"2026-08-14T08:28:59.592566Z","steps":["trace[1997993276] 'process raft request' (duration: 206.838622ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:59.592603Z","caller":"traceutil/trace.go:171","msg":"trace[38383495] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"205.741991ms","start":"2026-08-14T08:28:59.38682Z","end":"2026-08-14T08:28:59.592562Z","steps":["trace[38383495] 'process raft request' (duration: 205.356548ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:28:59.595278Z","caller":"traceutil/trace.go:171","msg":"trace[1241741621] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"207.525469ms","start":"2026-08-14T08:28:59.384487Z","end":"2026-08-14T08:28:59.592012Z","steps":["trace[1241741621] 'process raft request' (duration: 206.996224ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:29:02.270846Z","caller":"traceutil/trace.go:171","msg":"trace[1137478923] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"885.217852ms","start":"2026-08-14T08:29:01.385597Z","end":"2026-08-14T08:29:02.270814Z","steps":["trace[1137478923] 'process raft request' (duration: 790.300989ms)","trace[1137478923] 'compare' (duration: 94.48786ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:29:02.270923Z","caller":"traceutil/trace.go:171","msg":"trace[723108601] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"667.96819ms","start":"2026-08-14T08:29:01.602933Z","end":"2026-08-14T08:29:02.270901Z","steps":["trace[723108601] 'process raft request' (duration: 667.93247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:02.271015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:01.385575Z","time spent":"885.348093ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:02.271422Z","caller":"traceutil/trace.go:171","msg":"trace[1162086839] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2103; }","duration":"872.517912ms","start":"2026-08-14T08:29:01.398853Z","end":"2026-08-14T08:29:02.271371Z","steps":["trace[1162086839] 'read index received' (duration: 163.364114ms)","trace[1162086839] 'applied index is now lower than readState.Index' (duration: 709.151948ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:29:02.271468Z","caller":"traceutil/trace.go:171","msg":"trace[1697187452] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"707.944087ms","start":"2026-08-14T08:29:01.563495Z","end":"2026-08-14T08:29:02.271439Z","steps":["trace[1697187452] 'process raft request' (duration: 707.293071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:02.271609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:01.56348Z","time spent":"708.051288ms","remote":"127.0.0.1:34554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:02.271676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.869502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-08-14T08:29:02.271676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.803206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T08:29:02.271714Z","caller":"traceutil/trace.go:171","msg":"trace[970638829] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2032; }","duration":"662.962413ms","start":"2026-08-14T08:29:01.60874Z","end":"2026-08-14T08:29:02.271703Z","steps":["trace[970638829] 'agreement among raft nodes before linearized reading' (duration: 662.860232ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:29:02.27173Z","caller":"traceutil/trace.go:171","msg":"trace[415860597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2032; }","duration":"872.923077ms","start":"2026-08-14T08:29:01.39879Z","end":"2026-08-14T08:29:02.271713Z","steps":["trace[415860597] 'agreement among raft nodes before linearized reading' (duration: 872.738175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:02.271743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:01.608711Z","time spent":"663.025893ms","remote":"127.0.0.1:38038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-14T08:29:02.27175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.432363ms","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-08-14T08:29:02.271748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.996379ms","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-08-14T08:29:02.271776Z","caller":"traceutil/trace.go:171","msg":"trace[1270213557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2032; }","duration":"670.505844ms","start":"2026-08-14T08:29:01.60126Z","end":"2026-08-14T08:29:02.271766Z","steps":["trace[1270213557] 'agreement among raft nodes before linearized reading' (duration: 670.441393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:02.271767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:01.398763Z","time spent":"872.994668ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T08:29:02.27179Z","caller":"traceutil/trace.go:171","msg":"trace[13123447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2032; }","duration":"670.05619ms","start":"2026-08-14T08:29:01.601723Z","end":"2026-08-14T08:29:02.271779Z","steps":["trace[13123447] 'agreement among raft nodes before linearized reading' (duration: 669.921739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:02.271805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:01.601254Z","time spent":"670.545914ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T08:29:02.271825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:01.601714Z","time spent":"670.10424ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T08:29:02.271676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.639545ms","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-08-14T08:29:02.271887Z","caller":"traceutil/trace.go:171","msg":"trace[1655025970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2032; }","duration":"670.886638ms","start":"2026-08-14T08:29:01.600989Z","end":"2026-08-14T08:29:02.271875Z","steps":["trace[1655025970] 'agreement among raft nodes before linearized reading' (duration: 670.614195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:02.271923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:01.600967Z","time spent":"670.946498ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T08:29:02.271991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:01.602915Z","time spent":"669.02128ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:02.27216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.885678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:29:02.272204Z","caller":"traceutil/trace.go:171","msg":"trace[1088019603] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2032; }","duration":"507.980658ms","start":"2026-08-14T08:29:01.764205Z","end":"2026-08-14T08:29:02.272186Z","steps":["trace[1088019603] 'agreement among raft nodes before linearized reading' (duration: 507.922328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:02.272236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:01.76418Z","time spent":"508.048099ms","remote":"127.0.0.1:49262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-14T08:29:03.360656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342720768300816,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T08:29:03.416453Z","caller":"traceutil/trace.go:171","msg":"trace[622185411] linearizableReadLoop","detail":"{readStateIndex:2114; appliedIndex:2113; }","duration":"556.208825ms","start":"2026-08-14T08:29:02.860218Z","end":"2026-08-14T08:29:03.416427Z","steps":["trace[622185411] 'read index received' (duration: 555.969093ms)","trace[622185411] 'applied index is now lower than readState.Index' (duration: 238.892µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:29:03.416687Z","caller":"traceutil/trace.go:171","msg":"trace[911886926] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"783.583674ms","start":"2026-08-14T08:29:02.633082Z","end":"2026-08-14T08:29:03.416665Z","steps":["trace[911886926] 'process raft request' (duration: 783.166571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:03.416724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.488258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:29:03.416795Z","caller":"traceutil/trace.go:171","msg":"trace[2108444161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2039; }","duration":"556.625859ms","start":"2026-08-14T08:29:02.860154Z","end":"2026-08-14T08:29:03.416779Z","steps":["trace[2108444161] 'agreement among raft nodes before linearized reading' (duration: 556.471518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:03.416809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:02.633068Z","time spent":"783.662445ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:03.416826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:02.860132Z","time spent":"556.68899ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T08:29:03.41683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.824023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-14T08:29:03.416895Z","caller":"traceutil/trace.go:171","msg":"trace[1266582985] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2039; }","duration":"135.917563ms","start":"2026-08-14T08:29:03.280948Z","end":"2026-08-14T08:29:03.416865Z","steps":["trace[1266582985] 'agreement among raft nodes before linearized reading' (duration: 135.747982ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:29:25.946446Z","caller":"traceutil/trace.go:171","msg":"trace[67277884] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2240; }","duration":"376.271864ms","start":"2026-08-14T08:29:25.570146Z","end":"2026-08-14T08:29:25.946417Z","steps":["trace[67277884] 'read index received' (duration: 376.171494ms)","trace[67277884] 'applied index is now lower than readState.Index' (duration: 96.05µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:29:25.946586Z","caller":"traceutil/trace.go:171","msg":"trace[1738912472] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"416.587392ms","start":"2026-08-14T08:29:25.52995Z","end":"2026-08-14T08:29:25.946538Z","steps":["trace[1738912472] 'process raft request' (duration: 416.32635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:25.94683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.610467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-14T08:29:25.94688Z","caller":"traceutil/trace.go:171","msg":"trace[1926235657] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2162; }","duration":"376.747398ms","start":"2026-08-14T08:29:25.570116Z","end":"2026-08-14T08:29:25.946864Z","steps":["trace[1926235657] 'agreement among raft nodes before linearized reading' (duration: 376.416525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:25.946888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:25.529934Z","time spent":"416.812974ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:25.946917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:25.570104Z","time spent":"376.803628ms","remote":"127.0.0.1:38038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-14T08:29:26.991649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.200967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:29:26.992045Z","caller":"traceutil/trace.go:171","msg":"trace[2006164667] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"370.434863ms","start":"2026-08-14T08:29:26.62157Z","end":"2026-08-14T08:29:26.992004Z","steps":["trace[2006164667] 'process raft request' (duration: 252.358919ms)","trace[2006164667] 'compare' (duration: 116.720762ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:29:26.992158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:26.621537Z","time spent":"370.571645ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:26.992711Z","caller":"traceutil/trace.go:171","msg":"trace[991899356] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"361.267215ms","start":"2026-08-14T08:29:26.631427Z","end":"2026-08-14T08:29:26.992694Z","steps":["trace[991899356] 'process raft request' (duration: 360.358876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:26.992811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:26.631379Z","time spent":"361.381055ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:27.015532Z","caller":"traceutil/trace.go:171","msg":"trace[1206658922] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2247; }","duration":"341.963538ms","start":"2026-08-14T08:29:26.67354Z","end":"2026-08-14T08:29:27.015504Z","steps":["trace[1206658922] 'read index received' (duration: 200.319341ms)","trace[1206658922] 'applied index is now lower than readState.Index' (duration: 141.642667ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:29:27.015669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.126259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:29:27.015749Z","caller":"traceutil/trace.go:171","msg":"trace[308301377] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2170; }","duration":"342.27212ms","start":"2026-08-14T08:29:26.673461Z","end":"2026-08-14T08:29:27.015733Z","steps":["trace[308301377] 'agreement among raft nodes before linearized reading' (duration: 342.129239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:27.015796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:26.673443Z","time spent":"342.344801ms","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-08-14T08:29:27.015944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.884215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:29:27.015996Z","caller":"traceutil/trace.go:171","msg":"trace[61202573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2170; }","duration":"151.987505ms","start":"2026-08-14T08:29:26.863997Z","end":"2026-08-14T08:29:27.015984Z","steps":["trace[61202573] 'agreement among raft nodes before linearized reading' (duration: 151.785743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:27.892607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.431792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:29:27.892755Z","caller":"traceutil/trace.go:171","msg":"trace[876969603] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"613.793957ms","start":"2026-08-14T08:29:27.278921Z","end":"2026-08-14T08:29:27.892715Z","steps":["trace[876969603] 'process raft request' (duration: 88.150564ms)","trace[876969603] 'compare' (duration: 524.988747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:29:27.892819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:27.278899Z","time spent":"613.889408ms","remote":"127.0.0.1:34614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:28.170287Z","caller":"traceutil/trace.go:171","msg":"trace[1510628285] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"557.900355ms","start":"2026-08-14T08:29:27.612341Z","end":"2026-08-14T08:29:28.170241Z","steps":["trace[1510628285] 'read index received' (duration: 475.565802ms)","trace[1510628285] 'applied index is now lower than readState.Index' (duration: 82.333173ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:29:28.170469Z","caller":"traceutil/trace.go:171","msg":"trace[1422597770] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"863.524362ms","start":"2026-08-14T08:29:27.30693Z","end":"2026-08-14T08:29:28.170454Z","steps":["trace[1422597770] 'process raft request' (duration: 781.063658ms)","trace[1422597770] 'compare' (duration: 82.066511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:29:28.170599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:27.306907Z","time spent":"863.591003ms","remote":"127.0.0.1:34712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8560,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:28.170885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.722418ms","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-08-14T08:29:28.170928Z","caller":"traceutil/trace.go:171","msg":"trace[1157789040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"219.798868ms","start":"2026-08-14T08:29:27.951117Z","end":"2026-08-14T08:29:28.170916Z","steps":["trace[1157789040] 'agreement among raft nodes before linearized reading' (duration: 219.621597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:28.170859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.736256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T08:29:28.171084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.169587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T08:29:28.171129Z","caller":"traceutil/trace.go:171","msg":"trace[2011686664] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2173; }","duration":"395.098841ms","start":"2026-08-14T08:29:27.776019Z","end":"2026-08-14T08:29:28.171118Z","steps":["trace[2011686664] 'agreement among raft nodes before linearized reading' (duration: 394.573556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:28.171147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.574436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T08:29:28.171211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.5125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-14T08:29:28.171268Z","caller":"traceutil/trace.go:171","msg":"trace[1256874046] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2173; }","duration":"231.247537ms","start":"2026-08-14T08:29:27.939897Z","end":"2026-08-14T08:29:28.171144Z","steps":["trace[1256874046] 'agreement among raft nodes before linearized reading' (duration: 231.018594ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:29:28.171261Z","caller":"traceutil/trace.go:171","msg":"trace[1285241833] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2173; }","duration":"394.717977ms","start":"2026-08-14T08:29:27.776524Z","end":"2026-08-14T08:29:28.171242Z","steps":["trace[1285241833] 'agreement among raft nodes before linearized reading' (duration: 394.430484ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:29:28.1713Z","caller":"traceutil/trace.go:171","msg":"trace[1843832351] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"147.664971ms","start":"2026-08-14T08:29:28.023613Z","end":"2026-08-14T08:29:28.171278Z","steps":["trace[1843832351] 'agreement among raft nodes before linearized reading' (duration: 147.423429ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:28.171336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:27.776511Z","time spent":"394.811377ms","remote":"127.0.0.1:34730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":15,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-08-14T08:29:28.171402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.067415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-08-14T08:29:28.171499Z","caller":"traceutil/trace.go:171","msg":"trace[561911863] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2173; }","duration":"559.203506ms","start":"2026-08-14T08:29:27.612285Z","end":"2026-08-14T08:29:28.171488Z","steps":["trace[561911863] 'agreement among raft nodes before linearized reading' (duration: 559.045035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:28.171536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:27.612262Z","time spent":"559.266476ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-14T08:29:28.171167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:27.775992Z","time spent":"395.162451ms","remote":"127.0.0.1:34646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":24,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-08-14T08:29:28.453861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.764523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:29:28.453939Z","caller":"traceutil/trace.go:171","msg":"trace[210542544] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2174; }","duration":"132.900294ms","start":"2026-08-14T08:29:28.321023Z","end":"2026-08-14T08:29:28.453923Z","steps":["trace[210542544] 'count revisions from in-memory index tree' (duration: 132.646641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:29.898597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342136285s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:29:29.898948Z","caller":"traceutil/trace.go:171","msg":"trace[1165422727] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"1.4094011s","start":"2026-08-14T08:29:28.489452Z","end":"2026-08-14T08:29:29.898853Z","steps":["trace[1165422727] 'read index received' (duration: 66.918841ms)","trace[1165422727] 'applied index is now lower than readState.Index' (duration: 1.342479629s)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:29:29.899112Z","caller":"traceutil/trace.go:171","msg":"trace[1403968267] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"1.414942058s","start":"2026-08-14T08:29:28.484154Z","end":"2026-08-14T08:29:29.899096Z","steps":["trace[1403968267] 'process raft request' (duration: 72.224347ms)","trace[1403968267] 'compare' (duration: 1.341364109s)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:29:29.899306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:28.484132Z","time spent":"1.415040088s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:29.899584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410021555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T08:29:29.899664Z","caller":"traceutil/trace.go:171","msg":"trace[1358397949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2178; }","duration":"1.410217947s","start":"2026-08-14T08:29:28.489428Z","end":"2026-08-14T08:29:29.899646Z","steps":["trace[1358397949] 'agreement among raft nodes before linearized reading' (duration: 1.409860374s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:29.899762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:28.489417Z","time spent":"1.410279317s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T08:29:29.900257Z","caller":"traceutil/trace.go:171","msg":"trace[139687343] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"901.250151ms","start":"2026-08-14T08:29:28.998958Z","end":"2026-08-14T08:29:29.900208Z","steps":["trace[139687343] 'process raft request' (duration: 899.779528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:29.90047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:28.998937Z","time spent":"901.430013ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:30.366324Z","caller":"traceutil/trace.go:171","msg":"trace[1751617079] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2258; }","duration":"467.248407ms","start":"2026-08-14T08:29:29.899052Z","end":"2026-08-14T08:29:30.366301Z","steps":["trace[1751617079] 'read index received' (duration: 467.145716ms)","trace[1751617079] 'applied index is now lower than readState.Index' (duration: 101.551µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:29:30.36693Z","caller":"traceutil/trace.go:171","msg":"trace[210560261] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"1.367613391s","start":"2026-08-14T08:29:28.999288Z","end":"2026-08-14T08:29:30.366901Z","steps":["trace[210560261] 'process raft request' (duration: 1.366837564s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.366987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187069244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-08-14T08:29:30.367282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:28.99928Z","time spent":"1.367930784s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:30.367034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525656911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T08:29:30.367398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877437584s","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-08-14T08:29:30.367012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58767487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T08:29:30.367439Z","caller":"traceutil/trace.go:171","msg":"trace[742897833] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2180; }","duration":"1.187538117s","start":"2026-08-14T08:29:29.179838Z","end":"2026-08-14T08:29:30.367376Z","steps":["trace[742897833] 'agreement among raft nodes before linearized reading' (duration: 1.186911403s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.367485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877962898s","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-08-14T08:29:30.367689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504755832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T08:29:30.367664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:29.179813Z","time spent":"1.187834731s","remote":"127.0.0.1:38038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-14T08:29:30.367489Z","caller":"traceutil/trace.go:171","msg":"trace[604163037] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2180; }","duration":"1.526215975s","start":"2026-08-14T08:29:28.841257Z","end":"2026-08-14T08:29:30.367473Z","steps":["trace[604163037] 'agreement among raft nodes before linearized reading' (duration: 1.52551476s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.367842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:28.841218Z","time spent":"1.526607889s","remote":"127.0.0.1:34948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-08-14T08:29:30.367893Z","caller":"traceutil/trace.go:171","msg":"trace[1174163060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2180; }","duration":"1.505486488s","start":"2026-08-14T08:29:28.862239Z","end":"2026-08-14T08:29:30.367726Z","steps":["trace[1174163060] 'agreement among raft nodes before linearized reading' (duration: 1.504656862s)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:29:30.367841Z","caller":"traceutil/trace.go:171","msg":"trace[1396437188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2180; }","duration":"1.87827183s","start":"2026-08-14T08:29:28.489451Z","end":"2026-08-14T08:29:30.367723Z","steps":["trace[1396437188] 'agreement among raft nodes before linearized reading' (duration: 1.877663175s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.367957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:28.862229Z","time spent":"1.505714241s","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T08:29:30.367569Z","caller":"traceutil/trace.go:171","msg":"trace[1556327952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2180; }","duration":"1.877659166s","start":"2026-08-14T08:29:28.48988Z","end":"2026-08-14T08:29:30.367539Z","steps":["trace[1556327952] 'agreement among raft nodes before linearized reading' (duration: 1.877149982s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.368038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:28.489872Z","time spent":"1.8781488s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T08:29:30.36707Z","caller":"traceutil/trace.go:171","msg":"trace[1420052144] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"964.211888ms","start":"2026-08-14T08:29:29.402836Z","end":"2026-08-14T08:29:30.367048Z","steps":["trace[1420052144] 'process raft request' (duration: 963.419391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.368067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:28.489436Z","time spent":"1.878592554s","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T08:29:30.368175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:29.402768Z","time spent":"965.370257ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:30.36757Z","caller":"traceutil/trace.go:171","msg":"trace[513977162] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2180; }","duration":"1.588292435s","start":"2026-08-14T08:29:28.779262Z","end":"2026-08-14T08:29:30.367555Z","steps":["trace[513977162] 'agreement among raft nodes before linearized reading' (duration: 1.58768953s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.368317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:28.779207Z","time spent":"1.589095303s","remote":"127.0.0.1:34712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-14T08:29:30.791891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.613192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:29:30.792037Z","caller":"traceutil/trace.go:171","msg":"trace[324937911] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"424.856514ms","start":"2026-08-14T08:29:30.367155Z","end":"2026-08-14T08:29:30.792012Z","steps":["trace[324937911] 'read index received' (duration: 144.94676ms)","trace[324937911] 'applied index is now lower than readState.Index' (duration: 279.908304ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:29:30.792094Z","caller":"traceutil/trace.go:171","msg":"trace[81409505] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"887.507812ms","start":"2026-08-14T08:29:29.904518Z","end":"2026-08-14T08:29:30.792026Z","steps":["trace[81409505] 'process raft request' (duration: 607.623768ms)","trace[81409505] 'compare' (duration: 279.398251ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:29:30.792349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:29.904501Z","time spent":"887.702495ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:30.79232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.84569ms","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-08-14T08:29:30.7928Z","caller":"traceutil/trace.go:171","msg":"trace[1757268783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2181; }","duration":"567.379984ms","start":"2026-08-14T08:29:30.2254Z","end":"2026-08-14T08:29:30.79278Z","steps":["trace[1757268783] 'agreement among raft nodes before linearized reading' (duration: 566.699349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.792913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:30.225375Z","time spent":"567.523345ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T08:29:30.866703Z","caller":"traceutil/trace.go:171","msg":"trace[174590963] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"493.563668ms","start":"2026-08-14T08:29:30.373103Z","end":"2026-08-14T08:29:30.866666Z","steps":["trace[174590963] 'process raft request' (duration: 493.276726ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:29:30.866763Z","caller":"traceutil/trace.go:171","msg":"trace[664621483] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"393.217017ms","start":"2026-08-14T08:29:30.473518Z","end":"2026-08-14T08:29:30.866735Z","steps":["trace[664621483] 'process raft request' (duration: 393.171946ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:29:30.866851Z","caller":"traceutil/trace.go:171","msg":"trace[1066234479] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"447.278285ms","start":"2026-08-14T08:29:30.419549Z","end":"2026-08-14T08:29:30.866827Z","steps":["trace[1066234479] 'process raft request' (duration: 447.056474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.866884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:30.373072Z","time spent":"493.715589ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:30.86697Z","caller":"traceutil/trace.go:171","msg":"trace[1152259784] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"491.894553ms","start":"2026-08-14T08:29:30.375027Z","end":"2026-08-14T08:29:30.866922Z","steps":["trace[1152259784] 'process raft request' (duration: 491.53052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.867022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:30.419515Z","time spent":"447.391627ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:30.86708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:30.473492Z","time spent":"393.344748ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:29:30.867104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:30.375009Z","time spent":"492.030424ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:30.867566Z","caller":"traceutil/trace.go:171","msg":"trace[718728] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"400.389047ms","start":"2026-08-14T08:29:30.467159Z","end":"2026-08-14T08:29:30.867548Z","steps":["trace[718728] 'process raft request' (duration: 399.49161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.86885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:30.467138Z","time spent":"401.532557ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:30.947539Z","caller":"traceutil/trace.go:171","msg":"trace[679217869] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2266; }","duration":"155.455369ms","start":"2026-08-14T08:29:30.792069Z","end":"2026-08-14T08:29:30.947524Z","steps":["trace[679217869] 'read index received' (duration: 75.129856ms)","trace[679217869] 'applied index is now lower than readState.Index' (duration: 80.324253ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:29:30.947716Z","caller":"traceutil/trace.go:171","msg":"trace[2134301860] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"150.725909ms","start":"2026-08-14T08:29:30.79697Z","end":"2026-08-14T08:29:30.947696Z","steps":["trace[2134301860] 'process raft request' (duration: 150.502477ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:29:30.947601Z","caller":"traceutil/trace.go:171","msg":"trace[931865878] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"375.643838ms","start":"2026-08-14T08:29:30.57193Z","end":"2026-08-14T08:29:30.947574Z","steps":["trace[931865878] 'process raft request' (duration: 325.409742ms)","trace[931865878] 'compare' (duration: 49.914493ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:29:30.947677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.620741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T08:29:30.947967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:30.571889Z","time spent":"376.005422ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:29:30.948006Z","caller":"traceutil/trace.go:171","msg":"trace[1685489936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"578.990623ms","start":"2026-08-14T08:29:30.368996Z","end":"2026-08-14T08:29:30.947987Z","steps":["trace[1685489936] 'agreement among raft nodes before linearized reading' (duration: 578.58808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:30.948061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:30.368977Z","time spent":"579.067204ms","remote":"127.0.0.1:34508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T08:29:31.863653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.601067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-14T08:29:31.863751Z","caller":"traceutil/trace.go:171","msg":"trace[541255352] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2188; }","duration":"486.794778ms","start":"2026-08-14T08:29:31.376931Z","end":"2026-08-14T08:29:31.863726Z","steps":["trace[541255352] 'range keys from in-memory index tree' (duration: 486.408185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:31.863793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:31.376903Z","time spent":"486.878989ms","remote":"127.0.0.1:38038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-14T08:29:31.863833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.603458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T08:29:31.863918Z","caller":"traceutil/trace.go:171","msg":"trace[256555612] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:2188; }","duration":"504.748769ms","start":"2026-08-14T08:29:31.359148Z","end":"2026-08-14T08:29:31.863897Z","steps":["trace[256555612] 'range keys from in-memory index tree' (duration: 504.430516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:29:31.863973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:29:31.359125Z","time spent":"504.82869ms","remote":"127.0.0.1:34554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"info","ts":"2026-08-14T08:30:47.044236Z","caller":"traceutil/trace.go:171","msg":"trace[1147783685] linearizableReadLoop","detail":"{readStateIndex:2788; appliedIndex:2787; }","duration":"304.275039ms","start":"2026-08-14T08:30:46.739915Z","end":"2026-08-14T08:30:47.04419Z","steps":["trace[1147783685] 'read index received' (duration: 304.143168ms)","trace[1147783685] 'applied index is now lower than readState.Index' (duration: 130.171µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:30:47.04432Z","caller":"traceutil/trace.go:171","msg":"trace[395034688] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"408.578102ms","start":"2026-08-14T08:30:46.635698Z","end":"2026-08-14T08:30:47.044276Z","steps":["trace[395034688] 'process raft request' (duration: 408.25352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:30:47.044513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:30:46.635675Z","time spent":"408.731073ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:30:47.0447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.744012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-14T08:30:47.044754Z","caller":"traceutil/trace.go:171","msg":"trace[1579817725] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2690; }","duration":"304.865432ms","start":"2026-08-14T08:30:46.739876Z","end":"2026-08-14T08:30:47.044741Z","steps":["trace[1579817725] 'agreement among raft nodes before linearized reading' (duration: 304.53521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:30:47.044798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:30:46.739859Z","time spent":"304.930183ms","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-14T08:30:47.086398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.828411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:30:47.086585Z","caller":"traceutil/trace.go:171","msg":"trace[1184195595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2690; }","duration":"223.046622ms","start":"2026-08-14T08:30:46.863455Z","end":"2026-08-14T08:30:47.086502Z","steps":["trace[1184195595] 'agreement among raft nodes before linearized reading' (duration: 222.75796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:30:52.768812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342720768302403,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T08:30:52.981767Z","caller":"traceutil/trace.go:171","msg":"trace[934024395] linearizableReadLoop","detail":"{readStateIndex:2817; appliedIndex:2816; }","duration":"713.630033ms","start":"2026-08-14T08:30:52.268093Z","end":"2026-08-14T08:30:52.981723Z","steps":["trace[934024395] 'read index received' (duration: 713.395341ms)","trace[934024395] 'applied index is now lower than readState.Index' (duration: 232.882µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:30:52.981835Z","caller":"traceutil/trace.go:171","msg":"trace[1803915296] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"739.788385ms","start":"2026-08-14T08:30:52.242015Z","end":"2026-08-14T08:30:52.981803Z","steps":["trace[1803915296] 'process raft request' (duration: 739.450331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:30:52.981987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:30:52.241985Z","time spent":"739.924165ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:30:52.982028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.899406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:30:52.982094Z","caller":"traceutil/trace.go:171","msg":"trace[1169304994] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2717; }","duration":"714.050696ms","start":"2026-08-14T08:30:52.268026Z","end":"2026-08-14T08:30:52.982076Z","steps":["trace[1169304994] 'agreement among raft nodes before linearized reading' (duration: 713.893335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:30:52.982146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:30:52.267997Z","time spent":"714.138858ms","remote":"127.0.0.1:34998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-14T08:30:53.051696Z","caller":"traceutil/trace.go:171","msg":"trace[758007759] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"111.161498ms","start":"2026-08-14T08:30:52.940507Z","end":"2026-08-14T08:30:53.051668Z","steps":["trace[758007759] 'process raft request' (duration: 110.986837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:30:53.051766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.628736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T08:30:53.051838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.741854ms","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-08-14T08:30:53.051905Z","caller":"traceutil/trace.go:171","msg":"trace[1596360367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2718; }","duration":"779.851965ms","start":"2026-08-14T08:30:52.272037Z","end":"2026-08-14T08:30:53.051889Z","steps":["trace[1596360367] 'agreement among raft nodes before linearized reading' (duration: 779.645262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:30:53.051946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:30:52.27202Z","time spent":"779.917995ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T08:30:53.052119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.146946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:30:53.052193Z","caller":"traceutil/trace.go:171","msg":"trace[636390918] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2718; }","duration":"288.272497ms","start":"2026-08-14T08:30:52.763908Z","end":"2026-08-14T08:30:53.052181Z","steps":["trace[636390918] 'agreement among raft nodes before linearized reading' (duration: 288.117406ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:30:53.051845Z","caller":"traceutil/trace.go:171","msg":"trace[485765924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2718; }","duration":"187.754676ms","start":"2026-08-14T08:30:52.864071Z","end":"2026-08-14T08:30:53.051826Z","steps":["trace[485765924] 'agreement among raft nodes before linearized reading' (duration: 187.597684ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:31:23.386476Z","caller":"traceutil/trace.go:171","msg":"trace[916334105] transaction","detail":"{read_only:false; response_revision:2889; number_of_response:1; }","duration":"116.894849ms","start":"2026-08-14T08:31:23.269543Z","end":"2026-08-14T08:31:23.386438Z","steps":["trace[916334105] 'process raft request' (duration: 99.806608ms)","trace[916334105] 'compare' (duration: 16.91868ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:31:23.391031Z","caller":"traceutil/trace.go:171","msg":"trace[1785621521] transaction","detail":"{read_only:false; response_revision:2890; number_of_response:1; }","duration":"112.70804ms","start":"2026-08-14T08:31:23.27828Z","end":"2026-08-14T08:31:23.390989Z","steps":["trace[1785621521] 'process raft request' (duration: 112.486668ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:31:23.563064Z","caller":"traceutil/trace.go:171","msg":"trace[85762904] transaction","detail":"{read_only:false; response_revision:2894; number_of_response:1; }","duration":"131.09589ms","start":"2026-08-14T08:31:23.431939Z","end":"2026-08-14T08:31:23.563034Z","steps":["trace[85762904] 'process raft request' (duration: 130.799368ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:31:23.563324Z","caller":"traceutil/trace.go:171","msg":"trace[657383248] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"132.115479ms","start":"2026-08-14T08:31:23.431196Z","end":"2026-08-14T08:31:23.563311Z","steps":["trace[657383248] 'process raft request' (duration: 59.972696ms)","trace[657383248] 'compare' (duration: 71.395127ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:31:23.563369Z","caller":"traceutil/trace.go:171","msg":"trace[496226597] transaction","detail":"{read_only:false; response_revision:2895; number_of_response:1; }","duration":"130.065464ms","start":"2026-08-14T08:31:23.433271Z","end":"2026-08-14T08:31:23.563336Z","steps":["trace[496226597] 'process raft request' (duration: 129.56379ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:31:29.277897Z","caller":"traceutil/trace.go:171","msg":"trace[916462830] linearizableReadLoop","detail":"{readStateIndex:3023; appliedIndex:3022; }","duration":"259.051253ms","start":"2026-08-14T08:31:29.018801Z","end":"2026-08-14T08:31:29.277852Z","steps":["trace[916462830] 'read index received' (duration: 258.810422ms)","trace[916462830] 'applied index is now lower than readState.Index' (duration: 239.101µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:31:29.278204Z","caller":"traceutil/trace.go:171","msg":"trace[1204865608] transaction","detail":"{read_only:false; response_revision:2916; number_of_response:1; }","duration":"315.21719ms","start":"2026-08-14T08:31:28.962918Z","end":"2026-08-14T08:31:29.278135Z","steps":["trace[1204865608] 'process raft request' (duration: 314.728067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:31:29.278332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.514506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-14T08:31:29.278427Z","caller":"traceutil/trace.go:171","msg":"trace[741545909] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2916; }","duration":"259.657258ms","start":"2026-08-14T08:31:29.018746Z","end":"2026-08-14T08:31:29.278403Z","steps":["trace[741545909] 'agreement among raft nodes before linearized reading' (duration: 259.428005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:31:29.279055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:31:28.96289Z","time spent":"315.397471ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:31:29.4924Z","caller":"traceutil/trace.go:171","msg":"trace[1517980296] linearizableReadLoop","detail":"{readStateIndex:3025; appliedIndex:3023; }","duration":"214.354927ms","start":"2026-08-14T08:31:29.278011Z","end":"2026-08-14T08:31:29.492366Z","steps":["trace[1517980296] 'read index received' (duration: 203.234538ms)","trace[1517980296] 'applied index is now lower than readState.Index' (duration: 11.119049ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:31:29.492442Z","caller":"traceutil/trace.go:171","msg":"trace[168097069] transaction","detail":"{read_only:false; response_revision:2917; number_of_response:1; }","duration":"366.17337ms","start":"2026-08-14T08:31:29.126226Z","end":"2026-08-14T08:31:29.492399Z","steps":["trace[168097069] 'process raft request' (duration: 355.032521ms)","trace[168097069] 'compare' (duration: 10.928378ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:31:29.49256Z","caller":"traceutil/trace.go:171","msg":"trace[1060641836] transaction","detail":"{read_only:false; response_revision:2918; number_of_response:1; }","duration":"296.660498ms","start":"2026-08-14T08:31:29.195877Z","end":"2026-08-14T08:31:29.492537Z","steps":["trace[1060641836] 'process raft request' (duration: 296.434056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:31:29.492864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:31:29.126201Z","time spent":"366.478263ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:31:29.492986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.883438ms","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-08-14T08:31:29.493057Z","caller":"traceutil/trace.go:171","msg":"trace[1070807270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2918; }","duration":"250.068209ms","start":"2026-08-14T08:31:29.242972Z","end":"2026-08-14T08:31:29.493041Z","steps":["trace[1070807270] 'agreement among raft nodes before linearized reading' (duration: 249.558965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:31:29.494665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.534894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-08-14T08:31:29.494745Z","caller":"traceutil/trace.go:171","msg":"trace[1511886110] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2920; }","duration":"212.680455ms","start":"2026-08-14T08:31:29.282046Z","end":"2026-08-14T08:31:29.494727Z","steps":["trace[1511886110] 'agreement among raft nodes before linearized reading' (duration: 212.448633ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:35:03.749817Z","caller":"traceutil/trace.go:171","msg":"trace[1548067059] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"179.55998ms","start":"2026-08-14T08:35:03.570232Z","end":"2026-08-14T08:35:03.749792Z","steps":["trace[1548067059] 'process raft request' (duration: 178.308868ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:35:11.034954Z","caller":"traceutil/trace.go:171","msg":"trace[665365007] linearizableReadLoop","detail":"{readStateIndex:4975; appliedIndex:4974; }","duration":"137.728717ms","start":"2026-08-14T08:35:10.897207Z","end":"2026-08-14T08:35:11.034936Z","steps":["trace[665365007] 'read index received' (duration: 137.664717ms)","trace[665365007] 'applied index is now lower than readState.Index' (duration: 63.3µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:35:11.035018Z","caller":"traceutil/trace.go:171","msg":"trace[2006537832] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"149.189178ms","start":"2026-08-14T08:35:10.885793Z","end":"2026-08-14T08:35:11.034983Z","steps":["trace[2006537832] 'process raft request' (duration: 149.022028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:35:11.035183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.955097ms","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-08-14T08:35:11.035541Z","caller":"traceutil/trace.go:171","msg":"trace[749592455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4795; }","duration":"138.349688ms","start":"2026-08-14T08:35:10.897182Z","end":"2026-08-14T08:35:11.035532Z","steps":["trace[749592455] 'agreement among raft nodes before linearized reading' (duration: 137.855177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:35:11.035596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.326348ms","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-08-14T08:35:11.035654Z","caller":"traceutil/trace.go:171","msg":"trace[299117204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4795; }","duration":"138.407079ms","start":"2026-08-14T08:35:10.897234Z","end":"2026-08-14T08:35:11.035641Z","steps":["trace[299117204] 'agreement among raft nodes before linearized reading' (duration: 137.988178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:35:11.036322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.764345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-08-14T08:35:11.03725Z","caller":"traceutil/trace.go:171","msg":"trace[1745135618] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4795; }","duration":"137.686427ms","start":"2026-08-14T08:35:10.89952Z","end":"2026-08-14T08:35:11.037206Z","steps":["trace[1745135618] 'agreement among raft nodes before linearized reading' (duration: 135.761924ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:35:11.189864Z","caller":"traceutil/trace.go:171","msg":"trace[1147895880] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"105.239152ms","start":"2026-08-14T08:35:11.084563Z","end":"2026-08-14T08:35:11.189802Z","steps":["trace[1147895880] 'process raft request' (duration: 87.168739ms)","trace[1147895880] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; req_size:1634; } (duration: 17.836062ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:35:11.953645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.45034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:35:11.953838Z","caller":"traceutil/trace.go:171","msg":"trace[539030165] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"232.018182ms","start":"2026-08-14T08:35:11.721802Z","end":"2026-08-14T08:35:11.95382Z","steps":["trace[539030165] 'process raft request' (duration: 231.932192ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:35:11.954056Z","caller":"traceutil/trace.go:171","msg":"trace[1673320449] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5003; }","duration":"232.308293ms","start":"2026-08-14T08:35:11.721738Z","end":"2026-08-14T08:35:11.954046Z","steps":["trace[1673320449] 'read index received' (duration: 17.407741ms)","trace[1673320449] 'applied index is now lower than readState.Index' (duration: 214.899452ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:35:11.954092Z","caller":"traceutil/trace.go:171","msg":"trace[1949490049] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"232.332342ms","start":"2026-08-14T08:35:11.721577Z","end":"2026-08-14T08:35:11.953909Z","steps":["trace[1949490049] 'process raft request' (duration: 17.548511ms)","trace[1949490049] 'compare' (duration: 214.23143ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:35:11.954368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.784472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-08-14T08:35:11.954469Z","caller":"traceutil/trace.go:171","msg":"trace[1694358691] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4824; }","duration":"226.901302ms","start":"2026-08-14T08:35:11.727548Z","end":"2026-08-14T08:35:11.954449Z","steps":["trace[1694358691] 'agreement among raft nodes before linearized reading' (duration: 226.641632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:35:11.955489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.717955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-14T08:35:11.955555Z","caller":"traceutil/trace.go:171","msg":"trace[588941093] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4824; }","duration":"233.818345ms","start":"2026-08-14T08:35:11.721721Z","end":"2026-08-14T08:35:11.955539Z","steps":["trace[588941093] 'agreement among raft nodes before linearized reading' (duration: 232.420433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:35:14.976559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.418455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:35:14.976643Z","caller":"traceutil/trace.go:171","msg":"trace[533388648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4897; }","duration":"113.553325ms","start":"2026-08-14T08:35:14.863071Z","end":"2026-08-14T08:35:14.976624Z","steps":["trace[533388648] 'range keys from in-memory index tree' (duration: 113.343064ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:35:24.558288Z","caller":"traceutil/trace.go:171","msg":"trace[909949454] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"153.293944ms","start":"2026-08-14T08:35:24.404964Z","end":"2026-08-14T08:35:24.558258Z","steps":["trace[909949454] 'process raft request' (duration: 153.087134ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:35:24.724738Z","caller":"traceutil/trace.go:171","msg":"trace[891108472] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"315.416217ms","start":"2026-08-14T08:35:24.40929Z","end":"2026-08-14T08:35:24.724706Z","steps":["trace[891108472] 'process raft request' (duration: 315.368677ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:35:24.724798Z","caller":"traceutil/trace.go:171","msg":"trace[1128649863] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"319.304475ms","start":"2026-08-14T08:35:24.405465Z","end":"2026-08-14T08:35:24.72477Z","steps":["trace[1128649863] 'process raft request' (duration: 319.110505ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:35:24.724802Z","caller":"traceutil/trace.go:171","msg":"trace[1347943229] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"319.435405ms","start":"2026-08-14T08:35:24.405324Z","end":"2026-08-14T08:35:24.724759Z","steps":["trace[1347943229] 'process raft request' (duration: 319.106755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:35:24.724902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:35:24.40928Z","time spent":"315.529857ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:35:24.724992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:35:24.405288Z","time spent":"319.606735ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:35:24.725197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:35:24.40541Z","time spent":"319.456855ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:35:24.729422Z","caller":"traceutil/trace.go:171","msg":"trace[1283122114] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"119.033152ms","start":"2026-08-14T08:35:24.610357Z","end":"2026-08-14T08:35:24.72939Z","steps":["trace[1283122114] 'process raft request' (duration: 118.783641ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:35:24.857277Z","caller":"traceutil/trace.go:171","msg":"trace[533970190] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"122.029528ms","start":"2026-08-14T08:35:24.735221Z","end":"2026-08-14T08:35:24.857251Z","steps":["trace[533970190] 'process raft request' (duration: 109.07986ms)","trace[533970190] 'compare' (duration: 12.687237ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:35:25.281984Z","caller":"traceutil/trace.go:171","msg":"trace[960867076] linearizableReadLoop","detail":"{readStateIndex:5241; appliedIndex:5239; }","duration":"135.46648ms","start":"2026-08-14T08:35:25.146494Z","end":"2026-08-14T08:35:25.281961Z","steps":["trace[960867076] 'read index received' (duration: 45.480998ms)","trace[960867076] 'applied index is now lower than readState.Index' (duration: 89.984142ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:35:25.282068Z","caller":"traceutil/trace.go:171","msg":"trace[659272180] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"158.436158ms","start":"2026-08-14T08:35:25.123592Z","end":"2026-08-14T08:35:25.282028Z","steps":["trace[659272180] 'process raft request' (duration: 111.169407ms)","trace[659272180] 'compare' (duration: 46.93524ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:35:25.28246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.94293ms","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-08-14T08:35:25.282514Z","caller":"traceutil/trace.go:171","msg":"trace[402932972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5055; }","duration":"136.035571ms","start":"2026-08-14T08:35:25.146468Z","end":"2026-08-14T08:35:25.282503Z","steps":["trace[402932972] 'agreement among raft nodes before linearized reading' (duration: 135.83418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:35:25.282805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.285032ms","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-08-14T08:35:25.282894Z","caller":"traceutil/trace.go:171","msg":"trace[1258410512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5055; }","duration":"136.433452ms","start":"2026-08-14T08:35:25.146447Z","end":"2026-08-14T08:35:25.282881Z","steps":["trace[1258410512] 'agreement among raft nodes before linearized reading' (duration: 135.72061ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:36:05.697495Z","caller":"traceutil/trace.go:171","msg":"trace[1325077207] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"211.578508ms","start":"2026-08-14T08:36:05.485873Z","end":"2026-08-14T08:36:05.697452Z","steps":["trace[1325077207] 'process raft request' (duration: 211.420698ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:36:05.733605Z","caller":"traceutil/trace.go:171","msg":"trace[311438814] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"246.369438ms","start":"2026-08-14T08:36:05.487201Z","end":"2026-08-14T08:36:05.73357Z","steps":["trace[311438814] 'process raft request' (duration: 230.136771ms)","trace[311438814] 'compare' (duration: 15.951817ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:36:05.733609Z","caller":"traceutil/trace.go:171","msg":"trace[676181183] linearizableReadLoop","detail":"{readStateIndex:5587; appliedIndex:5585; }","duration":"233.784332ms","start":"2026-08-14T08:36:05.499788Z","end":"2026-08-14T08:36:05.733572Z","steps":["trace[676181183] 'read index received' (duration: 197.549168ms)","trace[676181183] 'applied index is now lower than readState.Index' (duration: 36.233414ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:36:05.73464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.186467ms","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-08-14T08:36:05.73471Z","caller":"traceutil/trace.go:171","msg":"trace[268423852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5393; }","duration":"225.325548ms","start":"2026-08-14T08:36:05.509367Z","end":"2026-08-14T08:36:05.734693Z","steps":["trace[268423852] 'agreement among raft nodes before linearized reading' (duration: 224.411965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:36:05.735242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.392667ms","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-08-14T08:36:05.735412Z","caller":"traceutil/trace.go:171","msg":"trace[1041609017] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5393; }","duration":"235.547237ms","start":"2026-08-14T08:36:05.499775Z","end":"2026-08-14T08:36:05.735322Z","steps":["trace[1041609017] 'agreement among raft nodes before linearized reading' (duration: 233.924273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:36:05.736043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.492538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-08-14T08:36:05.736115Z","caller":"traceutil/trace.go:171","msg":"trace[1653293922] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5393; }","duration":"173.63382ms","start":"2026-08-14T08:36:05.562463Z","end":"2026-08-14T08:36:05.736097Z","steps":["trace[1653293922] 'agreement among raft nodes before linearized reading' (duration: 171.323693ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:36:18.348342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2864} {"level":"info","ts":"2026-08-14T08:36:18.433833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2864,"took":"82.680354ms","hash":2844462651,"current-db-size-bytes":54173696,"current-db-size":"54 MB","current-db-size-in-use-bytes":28688384,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-14T08:36:18.43397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2844462651,"revision":2864,"compact-revision":-1} {"level":"info","ts":"2026-08-14T08:39:10.85617Z","caller":"traceutil/trace.go:171","msg":"trace[36559304] linearizableReadLoop","detail":"{readStateIndex:6908; appliedIndex:6907; }","duration":"117.656558ms","start":"2026-08-14T08:39:10.738493Z","end":"2026-08-14T08:39:10.85615Z","steps":["trace[36559304] 'read index received' (duration: 117.403596ms)","trace[36559304] 'applied index is now lower than readState.Index' (duration: 252.142µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:39:10.856478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.96543ms","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-08-14T08:39:10.856527Z","caller":"traceutil/trace.go:171","msg":"trace[47741300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6668; }","duration":"118.051882ms","start":"2026-08-14T08:39:10.738465Z","end":"2026-08-14T08:39:10.856517Z","steps":["trace[47741300] 'agreement among raft nodes before linearized reading' (duration: 117.885381ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:10.856291Z","caller":"traceutil/trace.go:171","msg":"trace[26532025] transaction","detail":"{read_only:false; response_revision:6668; number_of_response:1; }","duration":"141.446464ms","start":"2026-08-14T08:39:10.71482Z","end":"2026-08-14T08:39:10.856267Z","steps":["trace[26532025] 'process raft request' (duration: 141.147862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:10.856706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.711035ms","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-08-14T08:39:10.856745Z","caller":"traceutil/trace.go:171","msg":"trace[369847812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6668; }","duration":"107.773386ms","start":"2026-08-14T08:39:10.748961Z","end":"2026-08-14T08:39:10.856734Z","steps":["trace[369847812] 'agreement among raft nodes before linearized reading' (duration: 107.674465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:11.302721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.493776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:39:11.302882Z","caller":"traceutil/trace.go:171","msg":"trace[1281584978] linearizableReadLoop","detail":"{readStateIndex:6910; appliedIndex:6908; }","duration":"439.42272ms","start":"2026-08-14T08:39:10.863442Z","end":"2026-08-14T08:39:11.302865Z","steps":["trace[1281584978] 'read index received' (duration: 147.634071ms)","trace[1281584978] 'applied index is now lower than readState.Index' (duration: 291.787569ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:39:11.302974Z","caller":"traceutil/trace.go:171","msg":"trace[1762602975] transaction","detail":"{read_only:false; response_revision:6669; number_of_response:1; }","duration":"442.046781ms","start":"2026-08-14T08:39:10.860911Z","end":"2026-08-14T08:39:11.302958Z","steps":["trace[1762602975] 'process raft request' (duration: 150.231413ms)","trace[1762602975] 'compare' (duration: 291.384435ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:39:11.303053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:39:10.860894Z","time spent":"442.109971ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:39:11.303305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.785287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-08-14T08:39:11.303341Z","caller":"traceutil/trace.go:171","msg":"trace[104404862] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6670; }","duration":"216.852167ms","start":"2026-08-14T08:39:11.086479Z","end":"2026-08-14T08:39:11.303332Z","steps":["trace[104404862] 'agreement among raft nodes before linearized reading' (duration: 216.744277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:11.303706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.316014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-08-14T08:39:11.303744Z","caller":"traceutil/trace.go:171","msg":"trace[1882382510] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6670; }","duration":"191.390704ms","start":"2026-08-14T08:39:11.112345Z","end":"2026-08-14T08:39:11.303735Z","steps":["trace[1882382510] 'agreement among raft nodes before linearized reading' (duration: 191.307824ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:11.303884Z","caller":"traceutil/trace.go:171","msg":"trace[1443332252] transaction","detail":"{read_only:false; response_revision:6670; number_of_response:1; }","duration":"442.739253ms","start":"2026-08-14T08:39:10.86112Z","end":"2026-08-14T08:39:11.30386Z","steps":["trace[1443332252] 'process raft request' (duration: 441.695199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:11.303981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:39:10.861085Z","time spent":"442.854904ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:39:11.304221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.764046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:39:11.304322Z","caller":"traceutil/trace.go:171","msg":"trace[1328938913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6670; }","duration":"440.940156ms","start":"2026-08-14T08:39:10.863356Z","end":"2026-08-14T08:39:11.304296Z","steps":["trace[1328938913] 'agreement among raft nodes before linearized reading' (duration: 440.766895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:11.30438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:39:10.863337Z","time spent":"441.029886ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T08:39:13.820356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.186479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:39:13.820443Z","caller":"traceutil/trace.go:171","msg":"trace[1537066864] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:6680; }","duration":"348.28523ms","start":"2026-08-14T08:39:13.472136Z","end":"2026-08-14T08:39:13.820421Z","steps":["trace[1537066864] 'count revisions from in-memory index tree' (duration: 348.115349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:13.820485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:39:13.472054Z","time spent":"348.4205ms","remote":"127.0.0.1:44536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-08-14T08:39:13.82103Z","caller":"traceutil/trace.go:171","msg":"trace[443527821] transaction","detail":"{read_only:false; response_revision:6681; number_of_response:1; }","duration":"319.698713ms","start":"2026-08-14T08:39:13.501296Z","end":"2026-08-14T08:39:13.820995Z","steps":["trace[443527821] 'process raft request' (duration: 241.313916ms)","trace[443527821] 'compare' (duration: 78.201916ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:39:13.821276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:39:13.501276Z","time spent":"319.911064ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T08:39:13.868707Z","caller":"traceutil/trace.go:171","msg":"trace[1629231717] transaction","detail":"{read_only:false; response_revision:6682; number_of_response:1; }","duration":"214.92732ms","start":"2026-08-14T08:39:13.653761Z","end":"2026-08-14T08:39:13.868688Z","steps":["trace[1629231717] 'process raft request' (duration: 214.710259ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:13.869075Z","caller":"traceutil/trace.go:171","msg":"trace[273086985] transaction","detail":"{read_only:false; response_revision:6683; number_of_response:1; }","duration":"206.751193ms","start":"2026-08-14T08:39:13.66231Z","end":"2026-08-14T08:39:13.869061Z","steps":["trace[273086985] 'process raft request' (duration: 206.292041ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:13.869207Z","caller":"traceutil/trace.go:171","msg":"trace[1024260072] transaction","detail":"{read_only:false; response_revision:6684; number_of_response:1; }","duration":"204.545336ms","start":"2026-08-14T08:39:13.664634Z","end":"2026-08-14T08:39:13.869179Z","steps":["trace[1024260072] 'process raft request' (duration: 204.288444ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:26.019895Z","caller":"traceutil/trace.go:171","msg":"trace[743585973] transaction","detail":"{read_only:false; response_revision:6799; number_of_response:1; }","duration":"163.933301ms","start":"2026-08-14T08:39:25.855942Z","end":"2026-08-14T08:39:26.019876Z","steps":["trace[743585973] 'process raft request' (duration: 163.823221ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:26.020192Z","caller":"traceutil/trace.go:171","msg":"trace[1746955042] linearizableReadLoop","detail":"{readStateIndex:7045; appliedIndex:7045; }","duration":"157.447972ms","start":"2026-08-14T08:39:25.862719Z","end":"2026-08-14T08:39:26.020167Z","steps":["trace[1746955042] 'read index received' (duration: 157.436792ms)","trace[1746955042] 'applied index is now lower than readState.Index' (duration: 8.98µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:39:26.020364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.626633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:39:26.02039Z","caller":"traceutil/trace.go:171","msg":"trace[409496679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6799; }","duration":"157.692563ms","start":"2026-08-14T08:39:25.862691Z","end":"2026-08-14T08:39:26.020384Z","steps":["trace[409496679] 'agreement among raft nodes before linearized reading' (duration: 157.619133ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:26.032911Z","caller":"traceutil/trace.go:171","msg":"trace[650198306] transaction","detail":"{read_only:false; response_revision:6800; number_of_response:1; }","duration":"119.030671ms","start":"2026-08-14T08:39:25.913869Z","end":"2026-08-14T08:39:26.032899Z","steps":["trace[650198306] 'process raft request' (duration: 118.78268ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:26.033036Z","caller":"traceutil/trace.go:171","msg":"trace[1651408062] transaction","detail":"{read_only:false; response_revision:6801; number_of_response:1; }","duration":"119.100092ms","start":"2026-08-14T08:39:25.913929Z","end":"2026-08-14T08:39:26.033029Z","steps":["trace[1651408062] 'process raft request' (duration: 118.771681ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:26.033123Z","caller":"traceutil/trace.go:171","msg":"trace[2056331809] transaction","detail":"{read_only:false; response_revision:6802; number_of_response:1; }","duration":"118.82464ms","start":"2026-08-14T08:39:25.914292Z","end":"2026-08-14T08:39:26.033116Z","steps":["trace[2056331809] 'process raft request' (duration: 118.434459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:26.033232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.951503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:39:26.033261Z","caller":"traceutil/trace.go:171","msg":"trace[97416386] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:6802; }","duration":"114.994314ms","start":"2026-08-14T08:39:25.918259Z","end":"2026-08-14T08:39:26.033253Z","steps":["trace[97416386] 'agreement among raft nodes before linearized reading' (duration: 114.933113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:26.330043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.129532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T08:39:26.330288Z","caller":"traceutil/trace.go:171","msg":"trace[1738795385] transaction","detail":"{read_only:false; response_revision:6804; number_of_response:1; }","duration":"199.892952ms","start":"2026-08-14T08:39:26.130347Z","end":"2026-08-14T08:39:26.33024Z","steps":["trace[1738795385] 'process raft request' (duration: 82.454508ms)","trace[1738795385] 'compare' (duration: 116.949791ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:39:29.528701Z","caller":"traceutil/trace.go:171","msg":"trace[1197559524] transaction","detail":"{read_only:false; response_revision:6817; number_of_response:1; }","duration":"126.842418ms","start":"2026-08-14T08:39:29.401829Z","end":"2026-08-14T08:39:29.528671Z","steps":["trace[1197559524] 'process raft request' (duration: 100.152768ms)","trace[1197559524] 'compare' (duration: 26.470089ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:39:30.227628Z","caller":"traceutil/trace.go:171","msg":"trace[635784521] transaction","detail":"{read_only:false; response_revision:6819; number_of_response:1; }","duration":"175.902677ms","start":"2026-08-14T08:39:30.051702Z","end":"2026-08-14T08:39:30.227604Z","steps":["trace[635784521] 'process raft request' (duration: 122.628279ms)","trace[635784521] 'compare' (duration: 53.121728ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:39:30.22794Z","caller":"traceutil/trace.go:171","msg":"trace[1543060192] transaction","detail":"{read_only:false; response_revision:6821; number_of_response:1; }","duration":"173.531888ms","start":"2026-08-14T08:39:30.0544Z","end":"2026-08-14T08:39:30.227932Z","steps":["trace[1543060192] 'process raft request' (duration: 173.382917ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:30.22804Z","caller":"traceutil/trace.go:171","msg":"trace[1454513153] transaction","detail":"{read_only:false; response_revision:6820; number_of_response:1; }","duration":"173.677758ms","start":"2026-08-14T08:39:30.054355Z","end":"2026-08-14T08:39:30.228033Z","steps":["trace[1454513153] 'process raft request' (duration: 173.213786ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:32.543343Z","caller":"traceutil/trace.go:171","msg":"trace[1172443095] linearizableReadLoop","detail":"{readStateIndex:7085; appliedIndex:7084; }","duration":"119.570056ms","start":"2026-08-14T08:39:32.423737Z","end":"2026-08-14T08:39:32.543307Z","steps":["trace[1172443095] 'read index received' (duration: 112.337493ms)","trace[1172443095] 'applied index is now lower than readState.Index' (duration: 7.231563ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:39:32.543525Z","caller":"traceutil/trace.go:171","msg":"trace[1460487024] transaction","detail":"{read_only:false; response_revision:6837; number_of_response:1; }","duration":"242.799528ms","start":"2026-08-14T08:39:32.300664Z","end":"2026-08-14T08:39:32.543463Z","steps":["trace[1460487024] 'process raft request' (duration: 235.401955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:32.543704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.947027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-14T08:39:32.543744Z","caller":"traceutil/trace.go:171","msg":"trace[1074410342] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6837; }","duration":"120.012137ms","start":"2026-08-14T08:39:32.423722Z","end":"2026-08-14T08:39:32.543734Z","steps":["trace[1074410342] 'agreement among raft nodes before linearized reading' (duration: 119.761986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:32.543998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.887223ms","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-08-14T08:39:32.544027Z","caller":"traceutil/trace.go:171","msg":"trace[824627506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6837; }","duration":"118.938263ms","start":"2026-08-14T08:39:32.425083Z","end":"2026-08-14T08:39:32.544021Z","steps":["trace[824627506] 'agreement among raft nodes before linearized reading' (duration: 118.862823ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:41.123922Z","caller":"traceutil/trace.go:171","msg":"trace[527714015] transaction","detail":"{read_only:false; response_revision:6879; number_of_response:1; }","duration":"115.425962ms","start":"2026-08-14T08:39:41.008467Z","end":"2026-08-14T08:39:41.123892Z","steps":["trace[527714015] 'process raft request' (duration: 115.25071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:39:42.591993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.566438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T08:39:42.592023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.084006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T08:39:42.592101Z","caller":"traceutil/trace.go:171","msg":"trace[1135320151] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:6887; }","duration":"101.205896ms","start":"2026-08-14T08:39:42.490878Z","end":"2026-08-14T08:39:42.592084Z","steps":["trace[1135320151] 'count revisions from in-memory index tree' (duration: 100.996376ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:42.592054Z","caller":"traceutil/trace.go:171","msg":"trace[282503927] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:6887; }","duration":"143.674578ms","start":"2026-08-14T08:39:42.448367Z","end":"2026-08-14T08:39:42.592041Z","steps":["trace[282503927] 'count revisions from in-memory index tree' (duration: 143.496438ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:43.829625Z","caller":"traceutil/trace.go:171","msg":"trace[1782949191] transaction","detail":"{read_only:false; response_revision:6892; number_of_response:1; }","duration":"131.854705ms","start":"2026-08-14T08:39:43.697717Z","end":"2026-08-14T08:39:43.829571Z","steps":["trace[1782949191] 'process raft request' (duration: 114.615878ms)","trace[1782949191] 'compare' (duration: 17.097726ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:39:44.487067Z","caller":"traceutil/trace.go:171","msg":"trace[1629874727] transaction","detail":"{read_only:false; response_revision:6896; number_of_response:1; }","duration":"117.454661ms","start":"2026-08-14T08:39:44.36959Z","end":"2026-08-14T08:39:44.487045Z","steps":["trace[1629874727] 'process raft request' (duration: 117.409671ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:39:44.487104Z","caller":"traceutil/trace.go:171","msg":"trace[829329824] transaction","detail":"{read_only:false; response_revision:6895; number_of_response:1; }","duration":"118.554156ms","start":"2026-08-14T08:39:44.368514Z","end":"2026-08-14T08:39:44.487068Z","steps":["trace[829329824] 'process raft request' (duration: 108.126279ms)","trace[829329824] 'compare' (duration: 10.232297ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:39:44.857937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.564336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:25 size:449309"} {"level":"info","ts":"2026-08-14T08:39:44.858018Z","caller":"traceutil/trace.go:171","msg":"trace[989441078] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:25; response_revision:6898; }","duration":"109.685696ms","start":"2026-08-14T08:39:44.748315Z","end":"2026-08-14T08:39:44.858001Z","steps":["trace[989441078] 'range keys from in-memory index tree' (duration: 109.089523ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:40:15.537201Z","caller":"traceutil/trace.go:171","msg":"trace[814854687] transaction","detail":"{read_only:false; response_revision:7075; number_of_response:1; }","duration":"108.345668ms","start":"2026-08-14T08:40:15.42883Z","end":"2026-08-14T08:40:15.537175Z","steps":["trace[814854687] 'process raft request' (duration: 108.200457ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:40:17.250861Z","caller":"traceutil/trace.go:171","msg":"trace[1541103954] linearizableReadLoop","detail":"{readStateIndex:7342; appliedIndex:7341; }","duration":"347.210013ms","start":"2026-08-14T08:40:16.903621Z","end":"2026-08-14T08:40:17.250831Z","steps":["trace[1541103954] 'read index received' (duration: 347.030467ms)","trace[1541103954] 'applied index is now lower than readState.Index' (duration: 177.896µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T08:40:17.251058Z","caller":"traceutil/trace.go:171","msg":"trace[1318678644] transaction","detail":"{read_only:false; response_revision:7085; number_of_response:1; }","duration":"364.187693ms","start":"2026-08-14T08:40:16.886857Z","end":"2026-08-14T08:40:17.251045Z","steps":["trace[1318678644] 'process raft request' (duration: 363.752596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:40:17.251205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:40:16.886809Z","time spent":"364.300658ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T08:40:17.251526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.856148ms","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-08-14T08:40:17.251669Z","caller":"traceutil/trace.go:171","msg":"trace[736119734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7085; }","duration":"348.088987ms","start":"2026-08-14T08:40:16.903557Z","end":"2026-08-14T08:40:17.251646Z","steps":["trace[736119734] 'agreement among raft nodes before linearized reading' (duration: 347.552547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:40:17.251857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T08:40:16.903532Z","time spent":"348.241312ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T08:40:17.252408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.406026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-14T08:40:17.252998Z","caller":"traceutil/trace.go:171","msg":"trace[249683139] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:7085; }","duration":"134.039869ms","start":"2026-08-14T08:40:17.118927Z","end":"2026-08-14T08:40:17.252967Z","steps":["trace[249683139] 'agreement among raft nodes before linearized reading' (duration: 132.44705ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:40:17.409343Z","caller":"traceutil/trace.go:171","msg":"trace[1244076809] transaction","detail":"{read_only:false; response_revision:7086; number_of_response:1; }","duration":"153.169656ms","start":"2026-08-14T08:40:17.256141Z","end":"2026-08-14T08:40:17.409311Z","steps":["trace[1244076809] 'process raft request' (duration: 152.344085ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:40:18.149405Z","caller":"traceutil/trace.go:171","msg":"trace[15615930] transaction","detail":"{read_only:false; response_revision:7088; number_of_response:1; }","duration":"156.456641ms","start":"2026-08-14T08:40:17.992918Z","end":"2026-08-14T08:40:18.149374Z","steps":["trace[15615930] 'process raft request' (duration: 155.8777ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:40:18.41189Z","caller":"traceutil/trace.go:171","msg":"trace[284430411] transaction","detail":"{read_only:false; response_revision:7090; number_of_response:1; }","duration":"110.319679ms","start":"2026-08-14T08:40:18.301545Z","end":"2026-08-14T08:40:18.411864Z","steps":["trace[284430411] 'process raft request' (duration: 64.117765ms)","trace[284430411] 'compare' (duration: 45.865861ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:40:18.686911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.610769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:40:18.686993Z","caller":"traceutil/trace.go:171","msg":"trace[728473452] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7091; }","duration":"142.703662ms","start":"2026-08-14T08:40:18.544271Z","end":"2026-08-14T08:40:18.686975Z","steps":["trace[728473452] 'range keys from in-memory index tree' (duration: 142.547927ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:40:21.688708Z","caller":"traceutil/trace.go:171","msg":"trace[1663261330] linearizableReadLoop","detail":"{readStateIndex:7364; appliedIndex:7362; }","duration":"198.688311ms","start":"2026-08-14T08:40:21.489993Z","end":"2026-08-14T08:40:21.688681Z","steps":["trace[1663261330] 'read index received' (duration: 60.379897ms)","trace[1663261330] 'applied index is now lower than readState.Index' (duration: 138.307294ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:40:21.688934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.923989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T08:40:21.688973Z","caller":"traceutil/trace.go:171","msg":"trace[8964651] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:7106; }","duration":"199.028993ms","start":"2026-08-14T08:40:21.489934Z","end":"2026-08-14T08:40:21.688962Z","steps":["trace[8964651] 'agreement among raft nodes before linearized reading' (duration: 198.881888ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:40:21.689121Z","caller":"traceutil/trace.go:171","msg":"trace[874470162] transaction","detail":"{read_only:false; response_revision:7106; number_of_response:1; }","duration":"229.831779ms","start":"2026-08-14T08:40:21.458905Z","end":"2026-08-14T08:40:21.688737Z","steps":["trace[874470162] 'process raft request' (duration: 216.842835ms)","trace[874470162] 'compare' (duration: 12.820178ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T08:40:21.689152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.626369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-08-14T08:40:21.689572Z","caller":"traceutil/trace.go:171","msg":"trace[1819817090] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret; range_end:; response_count:1; response_revision:7106; }","duration":"199.091277ms","start":"2026-08-14T08:40:21.490462Z","end":"2026-08-14T08:40:21.689553Z","steps":["trace[1819817090] 'agreement among raft nodes before linearized reading' (duration: 198.392391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T08:40:21.689204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.94282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-08-14T08:40:21.689783Z","caller":"traceutil/trace.go:171","msg":"trace[252720133] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7106; }","duration":"150.571213ms","start":"2026-08-14T08:40:21.539198Z","end":"2026-08-14T08:40:21.689769Z","steps":["trace[252720133] 'agreement among raft nodes before linearized reading' (duration: 149.844086ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:41:09.878763Z","caller":"traceutil/trace.go:171","msg":"trace[1135030485] transaction","detail":"{read_only:false; response_revision:7349; number_of_response:1; }","duration":"113.285065ms","start":"2026-08-14T08:41:09.765434Z","end":"2026-08-14T08:41:09.878719Z","steps":["trace[1135030485] 'process raft request' (duration: 110.447795ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T08:41:18.357072Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5485} {"level":"info","ts":"2026-08-14T08:41:18.447993Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5485,"took":"89.317873ms","hash":1101400540,"current-db-size-bytes":54173696,"current-db-size":"54 MB","current-db-size-in-use-bytes":18591744,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-14T08:41:18.448115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1101400540,"revision":5485,"compact-revision":2864} {"level":"info","ts":"2026-08-14T08:41:35.372889Z","caller":"traceutil/trace.go:171","msg":"trace[1342556781] transaction","detail":"{read_only:false; response_revision:7480; number_of_response:1; }","duration":"297.411699ms","start":"2026-08-14T08:41:35.075442Z","end":"2026-08-14T08:41:35.372854Z","steps":["trace[1342556781] 'process raft request' (duration: 286.308322ms)","trace[1342556781] 'compare' (duration: 10.410166ms)"],"step_count":2}