{"level":"warn","ts":"2026-09-11T17:21:50.62843Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T17:21:50.628556Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.176:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.176:2380","--initial-cluster=instance=https://162.253.55.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.176: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-09-11T17:21:50.628658Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T17:21:50.628677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-11T17:21:50.628711Z","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-09-11T17:21:50.630001Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"]} {"level":"info","ts":"2026-09-11T17:21:50.630126Z","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://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.176: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-09-11T17:21:50.644265Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.834325ms"} {"level":"info","ts":"2026-09-11T17:21:50.667858Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"590b810b00f00655","cluster-id":"dcc6923d0deac545"} {"level":"info","ts":"2026-09-11T17:21:50.667969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=()"} {"level":"info","ts":"2026-09-11T17:21:50.667997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 0"} {"level":"info","ts":"2026-09-11T17:21:50.668008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 590b810b00f00655 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-11T17:21:50.668015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 1"} {"level":"info","ts":"2026-09-11T17:21:50.668067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"warn","ts":"2026-09-11T17:21:50.697005Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-11T17:21:50.7029Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-11T17:21:50.705309Z","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-09-11T17:21:50.707883Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"590b810b00f00655","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-11T17:21:50.708096Z","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-09-11T17:21:50.708274Z","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-09-11T17:21:50.708327Z","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-09-11T17:21:50.708263Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"590b810b00f00655","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-11T17:21:50.711643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"info","ts":"2026-09-11T17:21:50.711862Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","added-peer-id":"590b810b00f00655","added-peer-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-11T17:21:50.712494Z","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-09-11T17:21:50.712559Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-11T17:21:50.71266Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-11T17:21:50.713268Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"590b810b00f00655","initial-advertise-peer-urls":["https://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-11T17:21:50.713303Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-11T17:21:51.268321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 is starting a new election at term 1"} {"level":"info","ts":"2026-09-11T17:21:51.268415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-11T17:21:51.268448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgPreVoteResp from 590b810b00f00655 at term 1"} {"level":"info","ts":"2026-09-11T17:21:51.268477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became candidate at term 2"} {"level":"info","ts":"2026-09-11T17:21:51.268491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgVoteResp from 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-11T17:21:51.268513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became leader at term 2"} {"level":"info","ts":"2026-09-11T17:21:51.268531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 590b810b00f00655 elected leader 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-11T17:21:51.269813Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:21:51.270925Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"590b810b00f00655","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.176:2379]}","request-path":"/0/members/590b810b00f00655/attributes","cluster-id":"dcc6923d0deac545","publish-timeout":"7s"} {"level":"info","ts":"2026-09-11T17:21:51.270998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T17:21:51.271475Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T17:21:51.272018Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:21:51.272386Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-11T17:21:51.272469Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-11T17:21:51.272556Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:21:51.27261Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:21:51.274344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.176:2379"} {"level":"info","ts":"2026-09-11T17:21:51.275472Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-11T17:21:53.928466Z","caller":"traceutil/trace.go:171","msg":"trace[405226694] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"168.94899ms","start":"2026-09-11T17:21:53.759486Z","end":"2026-09-11T17:21:53.928435Z","steps":["trace[405226694] 'process raft request' (duration: 168.809693ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:21:53.930404Z","caller":"traceutil/trace.go:171","msg":"trace[290748778] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:75; }","duration":"168.909562ms","start":"2026-09-11T17:21:53.761459Z","end":"2026-09-11T17:21:53.930368Z","steps":["trace[290748778] 'read index received' (duration: 166.917256ms)","trace[290748778] 'applied index is now lower than readState.Index' (duration: 1.991116ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:21:53.930649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.133367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-11T17:21:53.930729Z","caller":"traceutil/trace.go:171","msg":"trace[493228079] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:73; }","duration":"169.296573ms","start":"2026-09-11T17:21:53.761416Z","end":"2026-09-11T17:21:53.930712Z","steps":["trace[493228079] 'agreement among raft nodes before linearized reading' (duration: 169.071238ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:21:53.930565Z","caller":"traceutil/trace.go:171","msg":"trace[385067403] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"169.040179ms","start":"2026-09-11T17:21:53.761412Z","end":"2026-09-11T17:21:53.930452Z","steps":["trace[385067403] 'process raft request' (duration: 168.693287ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:21:54.102101Z","caller":"traceutil/trace.go:171","msg":"trace[2077441280] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"147.408342ms","start":"2026-09-11T17:21:53.954652Z","end":"2026-09-11T17:21:54.10206Z","steps":["trace[2077441280] 'process raft request' (duration: 105.792065ms)","trace[2077441280] 'compare' (duration: 41.422102ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:21:54.291486Z","caller":"traceutil/trace.go:171","msg":"trace[2056939133] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"129.164596ms","start":"2026-09-11T17:21:54.162285Z","end":"2026-09-11T17:21:54.29145Z","steps":["trace[2056939133] 'process raft request' (duration: 52.609049ms)","trace[2056939133] 'compare' (duration: 76.387871ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:22:15.474869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.055675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T17:22:15.475019Z","caller":"traceutil/trace.go:171","msg":"trace[440403470] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"190.525728ms","start":"2026-09-11T17:22:15.284446Z","end":"2026-09-11T17:22:15.474971Z","steps":["trace[440403470] 'process raft request' (duration: 78.706947ms)","trace[440403470] 'compare' (duration: 110.920328ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:22:34.625101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.306772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T17:22:34.625226Z","caller":"traceutil/trace.go:171","msg":"trace[288449255] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"278.893024ms","start":"2026-09-11T17:22:34.346312Z","end":"2026-09-11T17:22:34.625205Z","steps":["trace[288449255] 'process raft request' (duration: 156.373956ms)","trace[288449255] 'compare' (duration: 122.125285ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:34.631795Z","caller":"traceutil/trace.go:171","msg":"trace[1796609875] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:583; }","duration":"216.381766ms","start":"2026-09-11T17:22:34.415393Z","end":"2026-09-11T17:22:34.631774Z","steps":["trace[1796609875] 'read index received' (duration: 87.309997ms)","trace[1796609875] 'applied index is now lower than readState.Index' (duration: 129.071069ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:34.631945Z","caller":"traceutil/trace.go:171","msg":"trace[1305542092] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"253.608512ms","start":"2026-09-11T17:22:34.378303Z","end":"2026-09-11T17:22:34.631912Z","steps":["trace[1305542092] 'process raft request' (duration: 253.18405ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:34.631996Z","caller":"traceutil/trace.go:171","msg":"trace[2019863182] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"249.734742ms","start":"2026-09-11T17:22:34.382227Z","end":"2026-09-11T17:22:34.631962Z","steps":["trace[2019863182] 'process raft request' (duration: 249.33824ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:34.632014Z","caller":"traceutil/trace.go:171","msg":"trace[807449484] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"162.422456ms","start":"2026-09-11T17:22:34.469559Z","end":"2026-09-11T17:22:34.631981Z","steps":["trace[807449484] 'process raft request' (duration: 162.127521ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:34.632188Z","caller":"traceutil/trace.go:171","msg":"trace[366928281] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"284.986133ms","start":"2026-09-11T17:22:34.347188Z","end":"2026-09-11T17:22:34.632174Z","steps":["trace[366928281] 'process raft request' (duration: 284.09735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:34.632302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.328791ms","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-09-11T17:22:34.632358Z","caller":"traceutil/trace.go:171","msg":"trace[1565748233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"100.433469ms","start":"2026-09-11T17:22:34.5319Z","end":"2026-09-11T17:22:34.632334Z","steps":["trace[1565748233] 'agreement among raft nodes before linearized reading' (duration: 100.271692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:34.632325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.801879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-11T17:22:34.632556Z","caller":"traceutil/trace.go:171","msg":"trace[318892126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"217.183783ms","start":"2026-09-11T17:22:34.415361Z","end":"2026-09-11T17:22:34.632545Z","steps":["trace[318892126] 'agreement among raft nodes before linearized reading' (duration: 216.631002ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:41.279211Z","caller":"traceutil/trace.go:171","msg":"trace[999468067] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"198.744097ms","start":"2026-09-11T17:22:41.080407Z","end":"2026-09-11T17:22:41.279151Z","steps":["trace[999468067] 'process raft request' (duration: 198.437512ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:41.644454Z","caller":"traceutil/trace.go:171","msg":"trace[1847111466] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"299.842738ms","start":"2026-09-11T17:22:41.344549Z","end":"2026-09-11T17:22:41.644392Z","steps":["trace[1847111466] 'read index received' (duration: 298.755268ms)","trace[1847111466] 'applied index is now lower than readState.Index' (duration: 1.08591ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:41.64451Z","caller":"traceutil/trace.go:171","msg":"trace[787722875] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"362.466496ms","start":"2026-09-11T17:22:41.281996Z","end":"2026-09-11T17:22:41.644463Z","steps":["trace[787722875] 'process raft request' (duration: 361.633831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:41.644738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:22:41.281865Z","time spent":"362.74429ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T17:22:41.644837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.249031ms","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-09-11T17:22:41.64489Z","caller":"traceutil/trace.go:171","msg":"trace[351062724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"300.382819ms","start":"2026-09-11T17:22:41.344489Z","end":"2026-09-11T17:22:41.644872Z","steps":["trace[351062724] 'agreement among raft nodes before linearized reading' (duration: 300.139403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:41.644946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:22:41.344464Z","time spent":"300.469718ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-11T17:22:41.774471Z","caller":"traceutil/trace.go:171","msg":"trace[567300259] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"123.887097ms","start":"2026-09-11T17:22:41.650551Z","end":"2026-09-11T17:22:41.774438Z","steps":["trace[567300259] 'process raft request' (duration: 80.800173ms)","trace[567300259] 'compare' (duration: 42.914507ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:41.778314Z","caller":"traceutil/trace.go:171","msg":"trace[577043425] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"126.907404ms","start":"2026-09-11T17:22:41.651367Z","end":"2026-09-11T17:22:41.778275Z","steps":["trace[577043425] 'process raft request' (duration: 126.737937ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:42.227711Z","caller":"traceutil/trace.go:171","msg":"trace[1509529947] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"242.511479ms","start":"2026-09-11T17:22:41.985167Z","end":"2026-09-11T17:22:42.227679Z","steps":["trace[1509529947] 'read index received' (duration: 242.44122ms)","trace[1509529947] 'applied index is now lower than readState.Index' (duration: 68.559µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:22:42.227889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.702545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:22:42.227921Z","caller":"traceutil/trace.go:171","msg":"trace[882379653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"242.810104ms","start":"2026-09-11T17:22:41.985101Z","end":"2026-09-11T17:22:42.227911Z","steps":["trace[882379653] 'agreement among raft nodes before linearized reading' (duration: 242.696976ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:42.228327Z","caller":"traceutil/trace.go:171","msg":"trace[1346148081] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"391.976842ms","start":"2026-09-11T17:22:41.836258Z","end":"2026-09-11T17:22:42.228235Z","steps":["trace[1346148081] 'process raft request' (duration: 391.259384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:42.228876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:22:41.836232Z","time spent":"392.458413ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T17:22:42.450608Z","caller":"traceutil/trace.go:171","msg":"trace[415474082] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"217.235907ms","start":"2026-09-11T17:22:42.233342Z","end":"2026-09-11T17:22:42.450578Z","steps":["trace[415474082] 'process raft request' (duration: 159.56343ms)","trace[415474082] 'compare' (duration: 57.210686ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:42.45569Z","caller":"traceutil/trace.go:171","msg":"trace[2083378588] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"217.588132ms","start":"2026-09-11T17:22:42.238084Z","end":"2026-09-11T17:22:42.455672Z","steps":["trace[2083378588] 'process raft request' (duration: 217.538433ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:42.455921Z","caller":"traceutil/trace.go:171","msg":"trace[498012274] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"218.969787ms","start":"2026-09-11T17:22:42.236878Z","end":"2026-09-11T17:22:42.455848Z","steps":["trace[498012274] 'process raft request' (duration: 218.675002ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:42.456171Z","caller":"traceutil/trace.go:171","msg":"trace[1805327988] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"220.938652ms","start":"2026-09-11T17:22:42.235153Z","end":"2026-09-11T17:22:42.456092Z","steps":["trace[1805327988] 'process raft request' (duration: 220.311474ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:44.321861Z","caller":"traceutil/trace.go:171","msg":"trace[1347410818] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"103.582952ms","start":"2026-09-11T17:22:44.218252Z","end":"2026-09-11T17:22:44.321835Z","steps":["trace[1347410818] 'process raft request' (duration: 103.433925ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:51.93589Z","caller":"traceutil/trace.go:171","msg":"trace[761285745] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"100.383726ms","start":"2026-09-11T17:22:51.835471Z","end":"2026-09-11T17:22:51.935855Z","steps":["trace[761285745] 'process raft request' (duration: 100.268997ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:53.138206Z","caller":"traceutil/trace.go:171","msg":"trace[23059112] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"104.031817ms","start":"2026-09-11T17:22:53.034126Z","end":"2026-09-11T17:22:53.138158Z","steps":["trace[23059112] 'process raft request' (duration: 103.745412ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:53.13825Z","caller":"traceutil/trace.go:171","msg":"trace[2088545216] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"103.126632ms","start":"2026-09-11T17:22:53.035063Z","end":"2026-09-11T17:22:53.13819Z","steps":["trace[2088545216] 'process raft request' (duration: 103.011424ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:53.13849Z","caller":"traceutil/trace.go:171","msg":"trace[248488396] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"101.702068ms","start":"2026-09-11T17:22:53.036764Z","end":"2026-09-11T17:22:53.138466Z","steps":["trace[248488396] 'process raft request' (duration: 101.359113ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:56.381355Z","caller":"traceutil/trace.go:171","msg":"trace[685219334] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"133.210372ms","start":"2026-09-11T17:22:56.248113Z","end":"2026-09-11T17:22:56.381324Z","steps":["trace[685219334] 'process raft request' (duration: 133.054495ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:01.037991Z","caller":"traceutil/trace.go:171","msg":"trace[980216871] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"181.550379ms","start":"2026-09-11T17:23:00.8564Z","end":"2026-09-11T17:23:01.03795Z","steps":["trace[980216871] 'process raft request' (duration: 181.028967ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:08.4539Z","caller":"traceutil/trace.go:171","msg":"trace[284261624] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"236.069386ms","start":"2026-09-11T17:23:08.217797Z","end":"2026-09-11T17:23:08.453867Z","steps":["trace[284261624] 'read index received' (duration: 235.82062ms)","trace[284261624] 'applied index is now lower than readState.Index' (duration: 247.166µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:23:08.454465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.631515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jpzvc\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-11T17:23:08.454579Z","caller":"traceutil/trace.go:171","msg":"trace[977806997] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jpzvc; range_end:; response_count:1; response_revision:842; }","duration":"236.786522ms","start":"2026-09-11T17:23:08.217761Z","end":"2026-09-11T17:23:08.454547Z","steps":["trace[977806997] 'agreement among raft nodes before linearized reading' (duration: 236.242032ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:08.454567Z","caller":"traceutil/trace.go:171","msg":"trace[1047038830] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"331.096292ms","start":"2026-09-11T17:23:08.123447Z","end":"2026-09-11T17:23:08.454544Z","steps":["trace[1047038830] 'process raft request' (duration: 330.186469ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:23:08.454769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:23:08.123433Z","time spent":"331.20257ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:23:08.492448Z","caller":"traceutil/trace.go:171","msg":"trace[1712070138] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"124.797303ms","start":"2026-09-11T17:23:08.367622Z","end":"2026-09-11T17:23:08.492419Z","steps":["trace[1712070138] 'process raft request' (duration: 124.522758ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:23:08.492655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.937948ms","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-09-11T17:23:08.492905Z","caller":"traceutil/trace.go:171","msg":"trace[1262391638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:843; }","duration":"240.318733ms","start":"2026-09-11T17:23:08.252549Z","end":"2026-09-11T17:23:08.492867Z","steps":["trace[1262391638] 'agreement among raft nodes before linearized reading' (duration: 239.782552ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:30.870394Z","caller":"traceutil/trace.go:171","msg":"trace[1682894209] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"213.477443ms","start":"2026-09-11T17:23:30.65689Z","end":"2026-09-11T17:23:30.870367Z","steps":["trace[1682894209] 'process raft request' (duration: 182.693916ms)","trace[1682894209] 'compare' (duration: 30.661099ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:23:30.888203Z","caller":"traceutil/trace.go:171","msg":"trace[572339971] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"126.489943ms","start":"2026-09-11T17:23:30.761683Z","end":"2026-09-11T17:23:30.888173Z","steps":["trace[572339971] 'process raft request' (duration: 126.324005ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:31.508443Z","caller":"traceutil/trace.go:171","msg":"trace[1974727051] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"111.0527ms","start":"2026-09-11T17:23:31.397359Z","end":"2026-09-11T17:23:31.508412Z","steps":["trace[1974727051] 'process raft request' (duration: 110.794144ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:31.508796Z","caller":"traceutil/trace.go:171","msg":"trace[1780492992] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"110.938972ms","start":"2026-09-11T17:23:31.397826Z","end":"2026-09-11T17:23:31.508765Z","steps":["trace[1780492992] 'process raft request' (duration: 110.793905ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:31.508814Z","caller":"traceutil/trace.go:171","msg":"trace[2043830106] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"111.232607ms","start":"2026-09-11T17:23:31.39754Z","end":"2026-09-11T17:23:31.508773Z","steps":["trace[2043830106] 'process raft request' (duration: 110.798714ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:03.710066Z","caller":"traceutil/trace.go:171","msg":"trace[1268362287] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"126.521055ms","start":"2026-09-11T17:24:03.583489Z","end":"2026-09-11T17:24:03.71001Z","steps":["trace[1268362287] 'process raft request' (duration: 81.90407ms)","trace[1268362287] 'compare' (duration: 44.30338ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:24:08.701349Z","caller":"traceutil/trace.go:171","msg":"trace[1527604793] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"122.854968ms","start":"2026-09-11T17:24:08.578472Z","end":"2026-09-11T17:24:08.701327Z","steps":["trace[1527604793] 'process raft request' (duration: 122.807308ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:08.701788Z","caller":"traceutil/trace.go:171","msg":"trace[770748831] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"123.496047ms","start":"2026-09-11T17:24:08.57827Z","end":"2026-09-11T17:24:08.701767Z","steps":["trace[770748831] 'process raft request' (duration: 122.869508ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:09.032211Z","caller":"traceutil/trace.go:171","msg":"trace[236069212] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"115.689306ms","start":"2026-09-11T17:24:08.916483Z","end":"2026-09-11T17:24:09.032172Z","steps":["trace[236069212] 'process raft request' (duration: 115.43824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:24:09.042499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.699111ms","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-09-11T17:24:09.042626Z","caller":"traceutil/trace.go:171","msg":"trace[778815161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1605; }","duration":"102.837207ms","start":"2026-09-11T17:24:08.939744Z","end":"2026-09-11T17:24:09.042581Z","steps":["trace[778815161] 'agreement among raft nodes before linearized reading' (duration: 102.529153ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:09.201816Z","caller":"traceutil/trace.go:171","msg":"trace[1757430536] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"146.922593ms","start":"2026-09-11T17:24:09.054861Z","end":"2026-09-11T17:24:09.201784Z","steps":["trace[1757430536] 'process raft request' (duration: 72.660884ms)","trace[1757430536] 'compare' (duration: 73.922724ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:24:15.049124Z","caller":"traceutil/trace.go:171","msg":"trace[1981354422] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"105.023114ms","start":"2026-09-11T17:24:14.944074Z","end":"2026-09-11T17:24:15.049097Z","steps":["trace[1981354422] 'process raft request' (duration: 98.449792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:24:31.178682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":456447483982797708,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-11T17:24:31.253583Z","caller":"traceutil/trace.go:171","msg":"trace[88006166] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"654.910632ms","start":"2026-09-11T17:24:30.598639Z","end":"2026-09-11T17:24:31.25355Z","steps":["trace[88006166] 'process raft request' (duration: 654.679856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:24:31.253827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:24:30.598595Z","time spent":"655.114729ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:24:31.307678Z","caller":"traceutil/trace.go:171","msg":"trace[989412353] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2003; }","duration":"629.854474ms","start":"2026-09-11T17:24:30.67777Z","end":"2026-09-11T17:24:31.307625Z","steps":["trace[989412353] 'read index received' (duration: 575.582864ms)","trace[989412353] 'applied index is now lower than readState.Index' (duration: 54.27039ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:24:31.307726Z","caller":"traceutil/trace.go:171","msg":"trace[193209496] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"506.260972ms","start":"2026-09-11T17:24:30.801432Z","end":"2026-09-11T17:24:31.307692Z","steps":["trace[193209496] 'process raft request' (duration: 506.019196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:24:31.308062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.268937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8d108d8-2d22-4672-a172-d00b1ead4314\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-11T17:24:31.308171Z","caller":"traceutil/trace.go:171","msg":"trace[1479621860] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8d108d8-2d22-4672-a172-d00b1ead4314; range_end:; response_count:1; response_revision:1926; }","duration":"630.432554ms","start":"2026-09-11T17:24:30.677719Z","end":"2026-09-11T17:24:31.308152Z","steps":["trace[1479621860] 'agreement among raft nodes before linearized reading' (duration: 630.05872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:24:31.308244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.268937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8d108d8-2d22-4672-a172-d00b1ead4314\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-09-11T17:24:31.308217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:24:30.801396Z","time spent":"506.565687ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:24:31.308399Z","caller":"traceutil/trace.go:171","msg":"trace[1140953354] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8d108d8-2d22-4672-a172-d00b1ead4314; range_end:; response_count:1; response_revision:1926; }","duration":"432.486213ms","start":"2026-09-11T17:24:30.875891Z","end":"2026-09-11T17:24:31.308377Z","steps":["trace[1140953354] 'agreement among raft nodes before linearized reading' (duration: 432.233808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:24:31.308366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:24:30.677662Z","time spent":"630.68573ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8d108d8-2d22-4672-a172-d00b1ead4314\" "} {"level":"warn","ts":"2026-09-11T17:24:31.308608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:24:30.87586Z","time spent":"432.623841ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8d108d8-2d22-4672-a172-d00b1ead4314\" "} {"level":"warn","ts":"2026-09-11T17:24:31.308945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.468183ms","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-09-11T17:24:31.309008Z","caller":"traceutil/trace.go:171","msg":"trace[1599906979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1926; }","duration":"117.582451ms","start":"2026-09-11T17:24:31.191408Z","end":"2026-09-11T17:24:31.30899Z","steps":["trace[1599906979] 'agreement among raft nodes before linearized reading' (duration: 117.408603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:24:31.309316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.529841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-11T17:24:31.30942Z","caller":"traceutil/trace.go:171","msg":"trace[170743298] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1926; }","duration":"217.707727ms","start":"2026-09-11T17:24:31.091686Z","end":"2026-09-11T17:24:31.309394Z","steps":["trace[170743298] 'agreement among raft nodes before linearized reading' (duration: 217.380763ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:31.504241Z","caller":"traceutil/trace.go:171","msg":"trace[475105517] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"188.167573ms","start":"2026-09-11T17:24:31.31604Z","end":"2026-09-11T17:24:31.504207Z","steps":["trace[475105517] 'process raft request' (duration: 187.843068ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:31.504391Z","caller":"traceutil/trace.go:171","msg":"trace[570586035] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2005; }","duration":"183.555779ms","start":"2026-09-11T17:24:31.320799Z","end":"2026-09-11T17:24:31.504355Z","steps":["trace[570586035] 'read index received' (duration: 77.093956ms)","trace[570586035] 'applied index is now lower than readState.Index' (duration: 106.459093ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:24:31.504491Z","caller":"traceutil/trace.go:171","msg":"trace[1260161700] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"183.796355ms","start":"2026-09-11T17:24:31.320685Z","end":"2026-09-11T17:24:31.504481Z","steps":["trace[1260161700] 'process raft request' (duration: 183.392521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:24:31.504854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.274693ms","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":"warn","ts":"2026-09-11T17:24:31.504907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.08138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d45424c19f62b7\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-11T17:24:31.504922Z","caller":"traceutil/trace.go:171","msg":"trace[1169055595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1929; }","duration":"183.374372ms","start":"2026-09-11T17:24:31.321533Z","end":"2026-09-11T17:24:31.504907Z","steps":["trace[1169055595] 'agreement among raft nodes before linearized reading' (duration: 183.133946ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:31.504954Z","caller":"traceutil/trace.go:171","msg":"trace[1479756816] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d45424c19f62b7; range_end:; response_count:1; response_revision:1929; }","duration":"184.176848ms","start":"2026-09-11T17:24:31.320763Z","end":"2026-09-11T17:24:31.504939Z","steps":["trace[1479756816] 'agreement among raft nodes before linearized reading' (duration: 183.814404ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:31.796892Z","caller":"traceutil/trace.go:171","msg":"trace[231230464] linearizableReadLoop","detail":"{readStateIndex:2010; appliedIndex:2009; }","duration":"160.720362ms","start":"2026-09-11T17:24:31.636142Z","end":"2026-09-11T17:24:31.796862Z","steps":["trace[231230464] 'read index received' (duration: 133.339342ms)","trace[231230464] 'applied index is now lower than readState.Index' (duration: 27.37958ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:24:31.797043Z","caller":"traceutil/trace.go:171","msg":"trace[2009081414] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"285.284529ms","start":"2026-09-11T17:24:31.511701Z","end":"2026-09-11T17:24:31.796985Z","steps":["trace[2009081414] 'process raft request' (duration: 257.84756ms)","trace[2009081414] 'compare' (duration: 27.167383ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:24:31.797154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.971158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:24:31.797228Z","caller":"traceutil/trace.go:171","msg":"trace[328651958] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1931; }","duration":"161.075087ms","start":"2026-09-11T17:24:31.636132Z","end":"2026-09-11T17:24:31.797207Z","steps":["trace[328651958] 'agreement among raft nodes before linearized reading' (duration: 160.934939ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:39.998736Z","caller":"traceutil/trace.go:171","msg":"trace[2037773324] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"133.387573ms","start":"2026-09-11T17:24:39.865313Z","end":"2026-09-11T17:24:39.9987Z","steps":["trace[2037773324] 'process raft request' (duration: 121.724824ms)","trace[2037773324] 'compare' (duration: 11.399133ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:24:39.99881Z","caller":"traceutil/trace.go:171","msg":"trace[245941300] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"123.343527ms","start":"2026-09-11T17:24:39.875434Z","end":"2026-09-11T17:24:39.998778Z","steps":["trace[245941300] 'process raft request' (duration: 123.18296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:24:44.570164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.369473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-11T17:24:44.570244Z","caller":"traceutil/trace.go:171","msg":"trace[991897] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2042; }","duration":"155.504111ms","start":"2026-09-11T17:24:44.414719Z","end":"2026-09-11T17:24:44.570223Z","steps":["trace[991897] 'range keys from in-memory index tree' (duration: 155.156037ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:25:05.648674Z","caller":"traceutil/trace.go:171","msg":"trace[1267434962] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"123.197032ms","start":"2026-09-11T17:25:05.525452Z","end":"2026-09-11T17:25:05.648649Z","steps":["trace[1267434962] 'process raft request' (duration: 122.817058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:25:12.156737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.020822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T17:25:12.157171Z","caller":"traceutil/trace.go:171","msg":"trace[1212970094] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"406.920535ms","start":"2026-09-11T17:25:11.750203Z","end":"2026-09-11T17:25:12.157123Z","steps":["trace[1212970094] 'process raft request' (duration: 96.436831ms)","trace[1212970094] 'compare' (duration: 309.52119ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:25:12.157236Z","caller":"traceutil/trace.go:171","msg":"trace[342727164] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2291; }","duration":"368.569133ms","start":"2026-09-11T17:25:11.788643Z","end":"2026-09-11T17:25:12.157212Z","steps":["trace[342727164] 'read index received' (duration: 57.98552ms)","trace[342727164] 'applied index is now lower than readState.Index' (duration: 310.580303ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:25:12.157466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.827239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-11T17:25:12.157516Z","caller":"traceutil/trace.go:171","msg":"trace[484736062] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2204; }","duration":"368.925487ms","start":"2026-09-11T17:25:11.78858Z","end":"2026-09-11T17:25:12.157506Z","steps":["trace[484736062] 'agreement among raft nodes before linearized reading' (duration: 368.76491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:25:12.157562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:25:11.788527Z","time spent":"369.025725ms","remote":"127.0.0.1:41818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-11T17:25:12.157552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:25:11.750166Z","time spent":"407.161992ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T17:25:12.171547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.637783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:25:12.171706Z","caller":"traceutil/trace.go:171","msg":"trace[1572056446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2206; }","duration":"186.83854ms","start":"2026-09-11T17:25:11.984848Z","end":"2026-09-11T17:25:12.171686Z","steps":["trace[1572056446] 'agreement among raft nodes before linearized reading' (duration: 186.624344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:26:12.379723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.940139ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:26:12.379864Z","caller":"traceutil/trace.go:171","msg":"trace[594722036] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2626; }","duration":"214.094556ms","start":"2026-09-11T17:26:12.165752Z","end":"2026-09-11T17:26:12.379846Z","steps":["trace[594722036] 'range keys from in-memory index tree' (duration: 213.925149ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:26:43.127183Z","caller":"traceutil/trace.go:171","msg":"trace[2019904251] linearizableReadLoop","detail":"{readStateIndex:2907; appliedIndex:2906; }","duration":"189.580507ms","start":"2026-09-11T17:26:42.937572Z","end":"2026-09-11T17:26:43.127152Z","steps":["trace[2019904251] 'read index received' (duration: 153.31853ms)","trace[2019904251] 'applied index is now lower than readState.Index' (duration: 36.260847ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:26:43.127424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.849423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-11T17:26:43.127608Z","caller":"traceutil/trace.go:171","msg":"trace[1439845783] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2799; }","duration":"190.068829ms","start":"2026-09-11T17:26:42.937508Z","end":"2026-09-11T17:26:43.127577Z","steps":["trace[1439845783] 'agreement among raft nodes before linearized reading' (duration: 189.793634ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:26:43.127762Z","caller":"traceutil/trace.go:171","msg":"trace[671638333] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"303.515263ms","start":"2026-09-11T17:26:42.824208Z","end":"2026-09-11T17:26:43.127723Z","steps":["trace[671638333] 'process raft request' (duration: 266.712755ms)","trace[671638333] 'compare' (duration: 35.826423ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:26:43.127869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.283081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:26:43.127918Z","caller":"traceutil/trace.go:171","msg":"trace[1187816403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2799; }","duration":"142.393019ms","start":"2026-09-11T17:26:42.985511Z","end":"2026-09-11T17:26:43.127904Z","steps":["trace[1187816403] 'agreement among raft nodes before linearized reading' (duration: 141.797708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:26:43.127927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:26:42.824183Z","time spent":"303.65238ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:26:48.457257Z","caller":"traceutil/trace.go:171","msg":"trace[789089144] linearizableReadLoop","detail":"{readStateIndex:2933; appliedIndex:2932; }","duration":"142.651623ms","start":"2026-09-11T17:26:48.314576Z","end":"2026-09-11T17:26:48.457228Z","steps":["trace[789089144] 'read index received' (duration: 142.387301ms)","trace[789089144] 'applied index is now lower than readState.Index' (duration: 263.161µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:26:48.457521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.927076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-11T17:26:48.457601Z","caller":"traceutil/trace.go:171","msg":"trace[293672134] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2824; }","duration":"143.048093ms","start":"2026-09-11T17:26:48.314503Z","end":"2026-09-11T17:26:48.457551Z","steps":["trace[293672134] 'agreement among raft nodes before linearized reading' (duration: 142.859924ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:26:48.457971Z","caller":"traceutil/trace.go:171","msg":"trace[1318316610] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"151.097587ms","start":"2026-09-11T17:26:48.306862Z","end":"2026-09-11T17:26:48.45796Z","steps":["trace[1318316610] 'process raft request' (duration: 150.200717ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:26:48.706792Z","caller":"traceutil/trace.go:171","msg":"trace[750576889] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"243.525948ms","start":"2026-09-11T17:26:48.463236Z","end":"2026-09-11T17:26:48.706762Z","steps":["trace[750576889] 'process raft request' (duration: 149.668703ms)","trace[750576889] 'compare' (duration: 93.728085ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:26:48.739498Z","caller":"traceutil/trace.go:171","msg":"trace[1580654400] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"160.186684ms","start":"2026-09-11T17:26:48.579277Z","end":"2026-09-11T17:26:48.739463Z","steps":["trace[1580654400] 'process raft request' (duration: 159.881796ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:31:51.332197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2845} {"level":"info","ts":"2026-09-11T17:31:51.407935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2845,"took":"74.552499ms","hash":1276483161,"current-db-size-bytes":49860608,"current-db-size":"50 MB","current-db-size-in-use-bytes":26202112,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-11T17:31:51.408008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1276483161,"revision":2845,"compact-revision":-1} {"level":"info","ts":"2026-09-11T17:31:54.373135Z","caller":"traceutil/trace.go:171","msg":"trace[1484086966] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"124.310887ms","start":"2026-09-11T17:31:54.248809Z","end":"2026-09-11T17:31:54.37312Z","steps":["trace[1484086966] 'process raft request' (duration: 123.958982ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:31:54.373419Z","caller":"traceutil/trace.go:171","msg":"trace[35036338] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"115.977723ms","start":"2026-09-11T17:31:54.257417Z","end":"2026-09-11T17:31:54.373394Z","steps":["trace[35036338] 'process raft request' (duration: 115.508274ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:31:54.373518Z","caller":"traceutil/trace.go:171","msg":"trace[193172619] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"113.951868ms","start":"2026-09-11T17:31:54.259497Z","end":"2026-09-11T17:31:54.373449Z","steps":["trace[193172619] 'process raft request' (duration: 113.51002ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:31:54.414609Z","caller":"traceutil/trace.go:171","msg":"trace[739505856] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"125.521779ms","start":"2026-09-11T17:31:54.289065Z","end":"2026-09-11T17:31:54.414587Z","steps":["trace[739505856] 'process raft request' (duration: 124.853128ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:31:54.414804Z","caller":"traceutil/trace.go:171","msg":"trace[1509061574] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5107; }","duration":"121.53566ms","start":"2026-09-11T17:31:54.293233Z","end":"2026-09-11T17:31:54.414769Z","steps":["trace[1509061574] 'process raft request' (duration: 120.830047ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:31:54.546724Z","caller":"traceutil/trace.go:171","msg":"trace[422449667] linearizableReadLoop","detail":"{readStateIndex:5312; appliedIndex:5310; }","duration":"110.828503ms","start":"2026-09-11T17:31:54.435867Z","end":"2026-09-11T17:31:54.546696Z","steps":["trace[422449667] 'read index received' (duration: 1.564947ms)","trace[422449667] 'applied index is now lower than readState.Index' (duration: 109.262256ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:31:54.546939Z","caller":"traceutil/trace.go:171","msg":"trace[1557589909] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"111.437353ms","start":"2026-09-11T17:31:54.43537Z","end":"2026-09-11T17:31:54.546807Z","steps":["trace[1557589909] 'process raft request' (duration: 90.051793ms)","trace[1557589909] 'compare' (duration: 21.055255ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:31:54.547228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.326882ms","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-09-11T17:31:54.547752Z","caller":"traceutil/trace.go:171","msg":"trace[1450834636] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5110; }","duration":"111.897092ms","start":"2026-09-11T17:31:54.435837Z","end":"2026-09-11T17:31:54.547735Z","steps":["trace[1450834636] 'agreement among raft nodes before linearized reading' (duration: 111.003446ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:31:54.548106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.14464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-kkbtb-tls\" ","response":"range_response_count:1 size:2301"} {"level":"info","ts":"2026-09-11T17:31:54.548168Z","caller":"traceutil/trace.go:171","msg":"trace[1523548080] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-kkbtb-tls; range_end:; response_count:1; response_revision:5110; }","duration":"107.205311ms","start":"2026-09-11T17:31:54.440947Z","end":"2026-09-11T17:31:54.548152Z","steps":["trace[1523548080] 'agreement among raft nodes before linearized reading' (duration: 107.105749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:31:54.548676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.642388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-11T17:31:54.54873Z","caller":"traceutil/trace.go:171","msg":"trace[641235320] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5110; }","duration":"107.7124ms","start":"2026-09-11T17:31:54.441005Z","end":"2026-09-11T17:31:54.548717Z","steps":["trace[641235320] 'agreement among raft nodes before linearized reading' (duration: 107.580658ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:02.225163Z","caller":"traceutil/trace.go:171","msg":"trace[1507894621] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"286.510557ms","start":"2026-09-11T17:32:01.93863Z","end":"2026-09-11T17:32:02.225141Z","steps":["trace[1507894621] 'process raft request' (duration: 286.354065ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:02.225309Z","caller":"traceutil/trace.go:171","msg":"trace[2070293429] linearizableReadLoop","detail":"{readStateIndex:5431; appliedIndex:5431; }","duration":"241.035362ms","start":"2026-09-11T17:32:01.984252Z","end":"2026-09-11T17:32:02.225288Z","steps":["trace[2070293429] 'read index received' (duration: 241.024721ms)","trace[2070293429] 'applied index is now lower than readState.Index' (duration: 8.401µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:32:02.22546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.183694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:32:02.225694Z","caller":"traceutil/trace.go:171","msg":"trace[330676234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5225; }","duration":"241.499828ms","start":"2026-09-11T17:32:01.984179Z","end":"2026-09-11T17:32:02.225679Z","steps":["trace[330676234] 'agreement among raft nodes before linearized reading' (duration: 241.200104ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:02.235477Z","caller":"traceutil/trace.go:171","msg":"trace[1536916769] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"141.973813ms","start":"2026-09-11T17:32:02.093479Z","end":"2026-09-11T17:32:02.235452Z","steps":["trace[1536916769] 'process raft request' (duration: 141.7939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:02.236119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.954855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-11T17:32:02.236171Z","caller":"traceutil/trace.go:171","msg":"trace[732405806] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5227; }","duration":"245.043056ms","start":"2026-09-11T17:32:01.991113Z","end":"2026-09-11T17:32:02.236156Z","steps":["trace[732405806] 'agreement among raft nodes before linearized reading' (duration: 244.914524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:02.239664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.47455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-09-11T17:32:02.239778Z","caller":"traceutil/trace.go:171","msg":"trace[1485094590] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5227; }","duration":"128.623063ms","start":"2026-09-11T17:32:02.111116Z","end":"2026-09-11T17:32:02.239739Z","steps":["trace[1485094590] 'agreement among raft nodes before linearized reading' (duration: 126.58196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:02.246038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.844646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-678b7bc69d-sm2dt.18d4548e0fdf6889\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-09-11T17:32:02.246189Z","caller":"traceutil/trace.go:171","msg":"trace[334597061] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-678b7bc69d-sm2dt.18d4548e0fdf6889; range_end:; response_count:1; response_revision:5227; }","duration":"135.004589ms","start":"2026-09-11T17:32:02.11112Z","end":"2026-09-11T17:32:02.246125Z","steps":["trace[334597061] 'agreement among raft nodes before linearized reading' (duration: 134.689383ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:02.646057Z","caller":"traceutil/trace.go:171","msg":"trace[1599734932] linearizableReadLoop","detail":"{readStateIndex:5442; appliedIndex:5441; }","duration":"183.145093ms","start":"2026-09-11T17:32:02.462889Z","end":"2026-09-11T17:32:02.646034Z","steps":["trace[1599734932] 'read index received' (duration: 183.057291ms)","trace[1599734932] 'applied index is now lower than readState.Index' (duration: 86.711µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:32:02.646395Z","caller":"traceutil/trace.go:171","msg":"trace[85453139] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"184.621757ms","start":"2026-09-11T17:32:02.461755Z","end":"2026-09-11T17:32:02.646377Z","steps":["trace[85453139] 'process raft request' (duration: 184.118509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:02.646575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.654391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-09-11T17:32:02.646688Z","caller":"traceutil/trace.go:171","msg":"trace[168637461] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5236; }","duration":"183.781694ms","start":"2026-09-11T17:32:02.462862Z","end":"2026-09-11T17:32:02.646644Z","steps":["trace[168637461] 'agreement among raft nodes before linearized reading' (duration: 183.409198ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:02.649532Z","caller":"traceutil/trace.go:171","msg":"trace[1264668203] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"157.723364ms","start":"2026-09-11T17:32:02.491779Z","end":"2026-09-11T17:32:02.649503Z","steps":["trace[1264668203] 'process raft request' (duration: 157.50431ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:02.889521Z","caller":"traceutil/trace.go:171","msg":"trace[1865754081] linearizableReadLoop","detail":"{readStateIndex:5445; appliedIndex:5444; }","duration":"170.882481ms","start":"2026-09-11T17:32:02.718591Z","end":"2026-09-11T17:32:02.889474Z","steps":["trace[1865754081] 'read index received' (duration: 170.593377ms)","trace[1865754081] 'applied index is now lower than readState.Index' (duration: 287.854µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:32:02.889589Z","caller":"traceutil/trace.go:171","msg":"trace[1517054678] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"205.131066ms","start":"2026-09-11T17:32:02.684434Z","end":"2026-09-11T17:32:02.889565Z","steps":["trace[1517054678] 'process raft request' (duration: 204.859441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:02.889771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.150036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-11T17:32:02.889832Z","caller":"traceutil/trace.go:171","msg":"trace[584818900] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5239; }","duration":"171.260337ms","start":"2026-09-11T17:32:02.718553Z","end":"2026-09-11T17:32:02.889813Z","steps":["trace[584818900] 'agreement among raft nodes before linearized reading' (duration: 171.056644ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:14.428599Z","caller":"traceutil/trace.go:171","msg":"trace[806043299] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"190.727434ms","start":"2026-09-11T17:32:14.237815Z","end":"2026-09-11T17:32:14.428543Z","steps":["trace[806043299] 'process raft request' (duration: 190.4308ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:14.428742Z","caller":"traceutil/trace.go:171","msg":"trace[1728373946] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5605; }","duration":"149.629455ms","start":"2026-09-11T17:32:14.279064Z","end":"2026-09-11T17:32:14.428693Z","steps":["trace[1728373946] 'read index received' (duration: 149.585444ms)","trace[1728373946] 'applied index is now lower than readState.Index' (duration: 39.981µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:32:14.429137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.9896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-11T17:32:14.429201Z","caller":"traceutil/trace.go:171","msg":"trace[1733228499] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5394; }","duration":"150.253393ms","start":"2026-09-11T17:32:14.27893Z","end":"2026-09-11T17:32:14.429184Z","steps":["trace[1733228499] 'agreement among raft nodes before linearized reading' (duration: 149.856707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:14.683967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.794011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T17:32:14.684382Z","caller":"traceutil/trace.go:171","msg":"trace[1824427476] linearizableReadLoop","detail":"{readStateIndex:5607; appliedIndex:5605; }","duration":"255.499318ms","start":"2026-09-11T17:32:14.428848Z","end":"2026-09-11T17:32:14.684347Z","steps":["trace[1824427476] 'read index received' (duration: 151.207739ms)","trace[1824427476] 'applied index is now lower than readState.Index' (duration: 104.287259ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:32:14.684826Z","caller":"traceutil/trace.go:171","msg":"trace[887507174] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"401.272634ms","start":"2026-09-11T17:32:14.283526Z","end":"2026-09-11T17:32:14.684798Z","steps":["trace[887507174] 'process raft request' (duration: 296.530847ms)","trace[887507174] 'compare' (duration: 103.650009ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:32:14.684881Z","caller":"traceutil/trace.go:171","msg":"trace[2027020845] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"248.106354ms","start":"2026-09-11T17:32:14.436751Z","end":"2026-09-11T17:32:14.684858Z","steps":["trace[2027020845] 'process raft request' (duration: 247.462394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:14.684919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.173152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:32:14.684956Z","caller":"traceutil/trace.go:171","msg":"trace[272939368] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5400; }","duration":"311.228453ms","start":"2026-09-11T17:32:14.373715Z","end":"2026-09-11T17:32:14.684943Z","steps":["trace[272939368] 'agreement among raft nodes before linearized reading' (duration: 311.138011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:14.684981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:14.373689Z","time spent":"311.287103ms","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-09-11T17:32:14.684982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:14.283501Z","time spent":"401.386205ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:32:14.684376Z","caller":"traceutil/trace.go:171","msg":"trace[338188075] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"399.873461ms","start":"2026-09-11T17:32:14.284465Z","end":"2026-09-11T17:32:14.684339Z","steps":["trace[338188075] 'process raft request' (duration: 399.652048ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:14.6849Z","caller":"traceutil/trace.go:171","msg":"trace[73161513] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"243.696057ms","start":"2026-09-11T17:32:14.441182Z","end":"2026-09-11T17:32:14.684878Z","steps":["trace[73161513] 'process raft request' (duration: 243.114968ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:14.686073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.007403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-767a1684-7fe6-4468-92f4-9e8ece7244a8\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-11T17:32:14.686191Z","caller":"traceutil/trace.go:171","msg":"trace[805690180] range","detail":"{range_begin:/registry/persistentvolumes/pvc-767a1684-7fe6-4468-92f4-9e8ece7244a8; range_end:; response_count:1; response_revision:5400; }","duration":"248.184116ms","start":"2026-09-11T17:32:14.437984Z","end":"2026-09-11T17:32:14.686168Z","steps":["trace[805690180] 'agreement among raft nodes before linearized reading' (duration: 246.991227ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:14.684493Z","caller":"traceutil/trace.go:171","msg":"trace[459039854] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"242.032111ms","start":"2026-09-11T17:32:14.442433Z","end":"2026-09-11T17:32:14.684465Z","steps":["trace[459039854] 'process raft request' (duration: 241.97361ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:14.686197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:14.284428Z","time spent":"400.755756ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:32:14.689935Z","caller":"traceutil/trace.go:171","msg":"trace[912303213] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"243.219929ms","start":"2026-09-11T17:32:14.442001Z","end":"2026-09-11T17:32:14.685221Z","steps":["trace[912303213] 'process raft request' (duration: 242.363456ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:33:03.76773Z","caller":"traceutil/trace.go:171","msg":"trace[524220930] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"109.651264ms","start":"2026-09-11T17:33:03.658032Z","end":"2026-09-11T17:33:03.767683Z","steps":["trace[524220930] 'process raft request' (duration: 45.493234ms)","trace[524220930] 'compare' (duration: 63.991418ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:33:04.511516Z","caller":"traceutil/trace.go:171","msg":"trace[1440884606] linearizableReadLoop","detail":"{readStateIndex:6051; appliedIndex:6050; }","duration":"192.297807ms","start":"2026-09-11T17:33:04.319186Z","end":"2026-09-11T17:33:04.511484Z","steps":["trace[1440884606] 'read index received' (duration: 192.058384ms)","trace[1440884606] 'applied index is now lower than readState.Index' (duration: 238.163µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:33:04.511692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.48213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-11T17:33:04.511723Z","caller":"traceutil/trace.go:171","msg":"trace[515219934] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5828; }","duration":"192.545431ms","start":"2026-09-11T17:33:04.319168Z","end":"2026-09-11T17:33:04.511714Z","steps":["trace[515219934] 'agreement among raft nodes before linearized reading' (duration: 192.428499ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:33:04.511871Z","caller":"traceutil/trace.go:171","msg":"trace[1177878290] transaction","detail":"{read_only:false; response_revision:5828; number_of_response:1; }","duration":"207.025505ms","start":"2026-09-11T17:33:04.304809Z","end":"2026-09-11T17:33:04.511835Z","steps":["trace[1177878290] 'process raft request' (duration: 206.431408ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:33:05.015134Z","caller":"traceutil/trace.go:171","msg":"trace[1898037389] transaction","detail":"{read_only:false; response_revision:5829; number_of_response:1; }","duration":"130.323575ms","start":"2026-09-11T17:33:04.88478Z","end":"2026-09-11T17:33:05.015104Z","steps":["trace[1898037389] 'process raft request' (duration: 130.074502ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:33:05.0515Z","caller":"traceutil/trace.go:171","msg":"trace[2013746294] transaction","detail":"{read_only:false; response_revision:5830; number_of_response:1; }","duration":"164.12503ms","start":"2026-09-11T17:33:04.887344Z","end":"2026-09-11T17:33:05.05147Z","steps":["trace[2013746294] 'process raft request' (duration: 163.882358ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:33:05.195107Z","caller":"traceutil/trace.go:171","msg":"trace[1682380586] transaction","detail":"{read_only:false; response_revision:5831; number_of_response:1; }","duration":"138.246207ms","start":"2026-09-11T17:33:05.056825Z","end":"2026-09-11T17:33:05.195071Z","steps":["trace[1682380586] 'process raft request' (duration: 38.700571ms)","trace[1682380586] 'compare' (duration: 99.292923ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:33:05.19548Z","caller":"traceutil/trace.go:171","msg":"trace[2069034555] transaction","detail":"{read_only:false; response_revision:5832; number_of_response:1; }","duration":"138.335978ms","start":"2026-09-11T17:33:05.057063Z","end":"2026-09-11T17:33:05.195399Z","steps":["trace[2069034555] 'process raft request' (duration: 137.909454ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:36:51.340377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5028} {"level":"info","ts":"2026-09-11T17:36:51.427403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5028,"took":"85.765594ms","hash":1649316842,"current-db-size-bytes":49860608,"current-db-size":"50 MB","current-db-size-in-use-bytes":19865600,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-11T17:36:51.42747Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1649316842,"revision":5028,"compact-revision":2845}