{"level":"warn","ts":"2026-09-11T23:13:36.82178Z","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-11T23:13:36.821859Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.58: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.58:2380","--initial-cluster=instance=https://162.253.55.58:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.58:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.58: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-11T23:13:36.821916Z","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-11T23:13:36.821925Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.58:2380"]} {"level":"info","ts":"2026-09-11T23:13:36.82194Z","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-11T23:13:36.822567Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.58:2379"]} {"level":"info","ts":"2026-09-11T23:13:36.822651Z","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.58:2380"],"listen-peer-urls":["https://162.253.55.58:2380"],"advertise-client-urls":["https://162.253.55.58:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.58:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.58: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-11T23:13:36.826327Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.445467ms"} {"level":"info","ts":"2026-09-11T23:13:36.832616Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51815f4ecef28267","cluster-id":"f8cbfcd78223a537"} {"level":"info","ts":"2026-09-11T23:13:36.832679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 switched to configuration voters=()"} {"level":"info","ts":"2026-09-11T23:13:36.832716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 became follower at term 0"} {"level":"info","ts":"2026-09-11T23:13:36.832725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51815f4ecef28267 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-11T23:13:36.832736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 became follower at term 1"} {"level":"info","ts":"2026-09-11T23:13:36.832766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 switched to configuration voters=(5873080181151924839)"} {"level":"warn","ts":"2026-09-11T23:13:36.841362Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-11T23:13:36.842512Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-11T23:13:36.843627Z","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-11T23:13:36.845787Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51815f4ecef28267","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-11T23:13:36.847323Z","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-11T23:13:36.847392Z","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-11T23:13:36.847413Z","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-11T23:13:36.847392Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51815f4ecef28267","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-11T23:13:36.848229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 switched to configuration voters=(5873080181151924839)"} {"level":"info","ts":"2026-09-11T23:13:36.848327Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f8cbfcd78223a537","local-member-id":"51815f4ecef28267","added-peer-id":"51815f4ecef28267","added-peer-peer-urls":["https://162.253.55.58:2380"]} {"level":"info","ts":"2026-09-11T23:13:36.851138Z","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-11T23:13:36.851245Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.58:2380"} {"level":"info","ts":"2026-09-11T23:13:36.851319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.58:2380"} {"level":"info","ts":"2026-09-11T23:13:36.851564Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51815f4ecef28267","initial-advertise-peer-urls":["https://162.253.55.58:2380"],"listen-peer-urls":["https://162.253.55.58:2380"],"advertise-client-urls":["https://162.253.55.58:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.58:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-11T23:13:36.851616Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-11T23:13:37.033349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 is starting a new election at term 1"} {"level":"info","ts":"2026-09-11T23:13:37.033449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-11T23:13:37.033507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 received MsgPreVoteResp from 51815f4ecef28267 at term 1"} {"level":"info","ts":"2026-09-11T23:13:37.033538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 became candidate at term 2"} {"level":"info","ts":"2026-09-11T23:13:37.033559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 received MsgVoteResp from 51815f4ecef28267 at term 2"} {"level":"info","ts":"2026-09-11T23:13:37.033586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51815f4ecef28267 became leader at term 2"} {"level":"info","ts":"2026-09-11T23:13:37.03361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51815f4ecef28267 elected leader 51815f4ecef28267 at term 2"} {"level":"info","ts":"2026-09-11T23:13:37.034609Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T23:13:37.034754Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51815f4ecef28267","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.58:2379]}","request-path":"/0/members/51815f4ecef28267/attributes","cluster-id":"f8cbfcd78223a537","publish-timeout":"7s"} {"level":"info","ts":"2026-09-11T23:13:37.034967Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T23:13:37.035074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T23:13:37.035434Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-11T23:13:37.035496Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f8cbfcd78223a537","local-member-id":"51815f4ecef28267","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T23:13:37.035508Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-11T23:13:37.035573Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T23:13:37.035611Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T23:13:37.037075Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-11T23:13:37.03752Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.58:2379"} {"level":"warn","ts":"2026-09-11T23:13:41.986758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.75883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T23:13:41.986922Z","caller":"traceutil/trace.go:171","msg":"trace[158772349] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"465.450049ms","start":"2026-09-11T23:13:41.52145Z","end":"2026-09-11T23:13:41.986901Z","steps":["trace[158772349] 'process raft request' (duration: 154.612856ms)","trace[158772349] 'compare' (duration: 309.610606ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:13:41.987006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:13:41.521431Z","time spent":"465.538032ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T23:13:42.381727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.030174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-11T23:13:42.381885Z","caller":"traceutil/trace.go:171","msg":"trace[2052230009] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"427.398016ms","start":"2026-09-11T23:13:41.954431Z","end":"2026-09-11T23:13:42.381829Z","steps":["trace[2052230009] 'read index received' (duration: 217.201677ms)","trace[2052230009] 'applied index is now lower than readState.Index' (duration: 210.195169ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:13:42.381971Z","caller":"traceutil/trace.go:171","msg":"trace[1207800719] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"859.30483ms","start":"2026-09-11T23:13:41.522583Z","end":"2026-09-11T23:13:42.381887Z","steps":["trace[1207800719] 'process raft request' (duration: 649.038489ms)","trace[1207800719] 'compare' (duration: 209.854859ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:13:42.382033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.599143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T23:13:42.382134Z","caller":"traceutil/trace.go:171","msg":"trace[1023400848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:249; }","duration":"427.766098ms","start":"2026-09-11T23:13:41.954338Z","end":"2026-09-11T23:13:42.382104Z","steps":["trace[1023400848] 'agreement among raft nodes before linearized reading' (duration: 427.584462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:13:42.382177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:13:41.954309Z","time spent":"427.858141ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-11T23:13:42.382232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:13:41.522568Z","time spent":"859.554879ms","remote":"127.0.0.1:44476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T23:14:30.371595Z","caller":"traceutil/trace.go:171","msg":"trace[1452485566] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"114.691343ms","start":"2026-09-11T23:14:30.256879Z","end":"2026-09-11T23:14:30.37157Z","steps":["trace[1452485566] 'process raft request' (duration: 113.973161ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:14:35.111398Z","caller":"traceutil/trace.go:171","msg":"trace[7023942] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"124.90826ms","start":"2026-09-11T23:14:34.986447Z","end":"2026-09-11T23:14:35.111355Z","steps":["trace[7023942] 'process raft request' (duration: 121.150186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:14:36.701295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.680267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-plw2c\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-11T23:14:36.701541Z","caller":"traceutil/trace.go:171","msg":"trace[428838950] range","detail":"{range_begin:/registry/pods/kube-system/cilium-plw2c; range_end:; response_count:1; response_revision:666; }","duration":"146.038738ms","start":"2026-09-11T23:14:36.555468Z","end":"2026-09-11T23:14:36.701507Z","steps":["trace[428838950] 'range keys from in-memory index tree' (duration: 145.489632ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:14:36.7482Z","caller":"traceutil/trace.go:171","msg":"trace[171599198] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"137.138565ms","start":"2026-09-11T23:14:36.611002Z","end":"2026-09-11T23:14:36.748141Z","steps":["trace[171599198] 'process raft request' (duration: 136.360382ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:14:36.959996Z","caller":"traceutil/trace.go:171","msg":"trace[809475264] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"211.873693ms","start":"2026-09-11T23:14:36.748087Z","end":"2026-09-11T23:14:36.959961Z","steps":["trace[809475264] 'read index received' (duration: 196.964176ms)","trace[809475264] 'applied index is now lower than readState.Index' (duration: 14.908227ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:14:36.960037Z","caller":"traceutil/trace.go:171","msg":"trace[1749643385] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"236.870727ms","start":"2026-09-11T23:14:36.723127Z","end":"2026-09-11T23:14:36.959998Z","steps":["trace[1749643385] 'process raft request' (duration: 221.865968ms)","trace[1749643385] 'compare' (duration: 14.836024ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:14:36.960332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.431833ms","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-11T23:14:36.960675Z","caller":"traceutil/trace.go:171","msg":"trace[654132679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"252.864296ms","start":"2026-09-11T23:14:36.707782Z","end":"2026-09-11T23:14:36.960646Z","steps":["trace[654132679] 'agreement among raft nodes before linearized reading' (duration: 252.296189ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:14:36.979638Z","caller":"traceutil/trace.go:171","msg":"trace[747799924] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"157.289784ms","start":"2026-09-11T23:14:36.822331Z","end":"2026-09-11T23:14:36.97962Z","steps":["trace[747799924] 'process raft request' (duration: 157.15752ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:14:37.635366Z","caller":"traceutil/trace.go:171","msg":"trace[1363002828] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"236.307553ms","start":"2026-09-11T23:14:37.399002Z","end":"2026-09-11T23:14:37.635309Z","steps":["trace[1363002828] 'process raft request' (duration: 226.75527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:14:38.821346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.024475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T23:14:38.821686Z","caller":"traceutil/trace.go:171","msg":"trace[122310516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"121.245562ms","start":"2026-09-11T23:14:38.700223Z","end":"2026-09-11T23:14:38.821469Z","steps":["trace[122310516] 'range keys from in-memory index tree' (duration: 120.907491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:14:55.388516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.162341ms","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-09-11T23:14:55.388605Z","caller":"traceutil/trace.go:171","msg":"trace[952896892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:842; }","duration":"210.294966ms","start":"2026-09-11T23:14:55.17829Z","end":"2026-09-11T23:14:55.388585Z","steps":["trace[952896892] 'range keys from in-memory index tree' (duration: 210.004366ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:15:02.954105Z","caller":"traceutil/trace.go:171","msg":"trace[99617225] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"123.082089ms","start":"2026-09-11T23:15:02.830991Z","end":"2026-09-11T23:15:02.954073Z","steps":["trace[99617225] 'process raft request' (duration: 122.903504ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:15:52.221354Z","caller":"traceutil/trace.go:171","msg":"trace[1466755165] linearizableReadLoop","detail":"{readStateIndex:1576; appliedIndex:1575; }","duration":"103.266341ms","start":"2026-09-11T23:15:52.118065Z","end":"2026-09-11T23:15:52.221332Z","steps":["trace[1466755165] 'read index received' (duration: 103.210559ms)","trace[1466755165] 'applied index is now lower than readState.Index' (duration: 54.532µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:15:52.221535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.464656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-11T23:15:52.221571Z","caller":"traceutil/trace.go:171","msg":"trace[1220890428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1518; }","duration":"103.55284ms","start":"2026-09-11T23:15:52.118006Z","end":"2026-09-11T23:15:52.221559Z","steps":["trace[1220890428] 'agreement among raft nodes before linearized reading' (duration: 103.460597ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:15:52.221542Z","caller":"traceutil/trace.go:171","msg":"trace[958180511] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"159.479773ms","start":"2026-09-11T23:15:52.061967Z","end":"2026-09-11T23:15:52.221447Z","steps":["trace[958180511] 'process raft request' (duration: 159.232686ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:16:19.446529Z","caller":"traceutil/trace.go:171","msg":"trace[1531377939] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"320.839642ms","start":"2026-09-11T23:16:19.125655Z","end":"2026-09-11T23:16:19.446494Z","steps":["trace[1531377939] 'process raft request' (duration: 274.3954ms)","trace[1531377939] 'compare' (duration: 46.033869ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:16:19.446598Z","caller":"traceutil/trace.go:171","msg":"trace[2092363163] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"108.578982ms","start":"2026-09-11T23:16:19.337994Z","end":"2026-09-11T23:16:19.446573Z","steps":["trace[2092363163] 'process raft request' (duration: 108.469568ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:16:19.446685Z","caller":"traceutil/trace.go:171","msg":"trace[1570427318] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2029; }","duration":"217.7095ms","start":"2026-09-11T23:16:19.228933Z","end":"2026-09-11T23:16:19.446643Z","steps":["trace[1570427318] 'read index received' (duration: 171.199515ms)","trace[1570427318] 'applied index is now lower than readState.Index' (duration: 46.507275ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:16:19.446939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.996219ms","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-09-11T23:16:19.447004Z","caller":"traceutil/trace.go:171","msg":"trace[2007821217] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1946; }","duration":"218.134303ms","start":"2026-09-11T23:16:19.228855Z","end":"2026-09-11T23:16:19.446989Z","steps":["trace[2007821217] 'agreement among raft nodes before linearized reading' (duration: 217.938277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:16:19.44731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:16:19.12564Z","time spent":"321.006468ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T23:16:19.67239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.78963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T23:16:19.672656Z","caller":"traceutil/trace.go:171","msg":"trace[1313263667] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"138.364177ms","start":"2026-09-11T23:16:19.534262Z","end":"2026-09-11T23:16:19.672626Z","steps":["trace[1313263667] 'process raft request' (duration: 36.239206ms)","trace[1313263667] 'compare' (duration: 101.660617ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:16:32.368461Z","caller":"traceutil/trace.go:171","msg":"trace[1990586906] linearizableReadLoop","detail":"{readStateIndex:2140; appliedIndex:2139; }","duration":"126.384707ms","start":"2026-09-11T23:16:32.242025Z","end":"2026-09-11T23:16:32.368409Z","steps":["trace[1990586906] 'read index received' (duration: 97.609534ms)","trace[1990586906] 'applied index is now lower than readState.Index' (duration: 28.773723ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:16:32.368551Z","caller":"traceutil/trace.go:171","msg":"trace[718765295] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"180.483308ms","start":"2026-09-11T23:16:32.188026Z","end":"2026-09-11T23:16:32.368509Z","steps":["trace[718765295] 'process raft request' (duration: 151.525798ms)","trace[718765295] 'compare' (duration: 28.516006ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:16:32.368844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.78388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-2nbqg\" ","response":"range_response_count:1 size:5854"} {"level":"info","ts":"2026-09-11T23:16:32.368951Z","caller":"traceutil/trace.go:171","msg":"trace[541915721] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-2nbqg; range_end:; response_count:1; response_revision:2051; }","duration":"126.915574ms","start":"2026-09-11T23:16:32.242008Z","end":"2026-09-11T23:16:32.368924Z","steps":["trace[541915721] 'agreement among raft nodes before linearized reading' (duration: 126.592294ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:16:32.528993Z","caller":"traceutil/trace.go:171","msg":"trace[1214475729] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"282.56042ms","start":"2026-09-11T23:16:32.246407Z","end":"2026-09-11T23:16:32.528967Z","steps":["trace[1214475729] 'process raft request' (duration: 268.125772ms)","trace[1214475729] 'compare' (duration: 14.328345ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:16:32.608677Z","caller":"traceutil/trace.go:171","msg":"trace[151045389] linearizableReadLoop","detail":"{readStateIndex:2143; appliedIndex:2140; }","duration":"149.608939ms","start":"2026-09-11T23:16:32.459033Z","end":"2026-09-11T23:16:32.608642Z","steps":["trace[151045389] 'read index received' (duration: 55.618178ms)","trace[151045389] 'applied index is now lower than readState.Index' (duration: 93.989131ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:16:32.608776Z","caller":"traceutil/trace.go:171","msg":"trace[1040442840] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"168.818735ms","start":"2026-09-11T23:16:32.439948Z","end":"2026-09-11T23:16:32.608767Z","steps":["trace[1040442840] 'process raft request' (duration: 168.636789ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:16:32.608713Z","caller":"traceutil/trace.go:171","msg":"trace[1331326114] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"229.747048ms","start":"2026-09-11T23:16:32.378942Z","end":"2026-09-11T23:16:32.608689Z","steps":["trace[1331326114] 'process raft request' (duration: 229.448179ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:16:32.608966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.907758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-11T23:16:32.609031Z","caller":"traceutil/trace.go:171","msg":"trace[266854930] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2054; }","duration":"150.056792ms","start":"2026-09-11T23:16:32.45896Z","end":"2026-09-11T23:16:32.609016Z","steps":["trace[266854930] 'agreement among raft nodes before linearized reading' (duration: 149.825685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:16:32.609133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.295874ms","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-09-11T23:16:32.609206Z","caller":"traceutil/trace.go:171","msg":"trace[760108850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2054; }","duration":"107.426567ms","start":"2026-09-11T23:16:32.501764Z","end":"2026-09-11T23:16:32.609191Z","steps":["trace[760108850] 'agreement among raft nodes before linearized reading' (duration: 107.17356ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:16:35.732406Z","caller":"traceutil/trace.go:171","msg":"trace[62365718] linearizableReadLoop","detail":"{readStateIndex:2168; appliedIndex:2167; }","duration":"124.078766ms","start":"2026-09-11T23:16:35.608304Z","end":"2026-09-11T23:16:35.732382Z","steps":["trace[62365718] 'read index received' (duration: 124.006004ms)","trace[62365718] 'applied index is now lower than readState.Index' (duration: 71.382µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:16:35.732739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.409736ms","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-09-11T23:16:35.732788Z","caller":"traceutil/trace.go:171","msg":"trace[1256952357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2079; }","duration":"124.504909ms","start":"2026-09-11T23:16:35.608271Z","end":"2026-09-11T23:16:35.732776Z","steps":["trace[1256952357] 'agreement among raft nodes before linearized reading' (duration: 124.282712ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:16:35.732685Z","caller":"traceutil/trace.go:171","msg":"trace[1665918192] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"133.622842ms","start":"2026-09-11T23:16:35.599005Z","end":"2026-09-11T23:16:35.732627Z","steps":["trace[1665918192] 'process raft request' (duration: 133.22947ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:16:35.953885Z","caller":"traceutil/trace.go:171","msg":"trace[1563625133] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"191.328085ms","start":"2026-09-11T23:16:35.762513Z","end":"2026-09-11T23:16:35.953841Z","steps":["trace[1563625133] 'process raft request' (duration: 191.010865ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:16:35.954205Z","caller":"traceutil/trace.go:171","msg":"trace[88338458] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2169; }","duration":"160.28364ms","start":"2026-09-11T23:16:35.7939Z","end":"2026-09-11T23:16:35.954184Z","steps":["trace[88338458] 'read index received' (duration: 159.467765ms)","trace[88338458] 'applied index is now lower than readState.Index' (duration: 815.035µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:16:35.954502Z","caller":"traceutil/trace.go:171","msg":"trace[881211799] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"187.668802ms","start":"2026-09-11T23:16:35.766738Z","end":"2026-09-11T23:16:35.954407Z","steps":["trace[881211799] 'process raft request' (duration: 187.371962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:16:35.954545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.613921ms","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-09-11T23:16:35.954596Z","caller":"traceutil/trace.go:171","msg":"trace[1972759259] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2082; }","duration":"160.711034ms","start":"2026-09-11T23:16:35.793864Z","end":"2026-09-11T23:16:35.954575Z","steps":["trace[1972759259] 'agreement among raft nodes before linearized reading' (duration: 160.393513ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:16:54.012092Z","caller":"traceutil/trace.go:171","msg":"trace[2116696101] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2276; }","duration":"194.142528ms","start":"2026-09-11T23:16:53.817926Z","end":"2026-09-11T23:16:54.012069Z","steps":["trace[2116696101] 'read index received' (duration: 193.969752ms)","trace[2116696101] 'applied index is now lower than readState.Index' (duration: 165.996µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:16:54.012161Z","caller":"traceutil/trace.go:171","msg":"trace[1915909617] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"211.426935ms","start":"2026-09-11T23:16:53.80071Z","end":"2026-09-11T23:16:54.012136Z","steps":["trace[1915909617] 'process raft request' (duration: 211.193697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:16:54.012417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.449327ms","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-09-11T23:16:54.012571Z","caller":"traceutil/trace.go:171","msg":"trace[865216329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2183; }","duration":"194.668363ms","start":"2026-09-11T23:16:53.817872Z","end":"2026-09-11T23:16:54.012541Z","steps":["trace[865216329] 'agreement among raft nodes before linearized reading' (duration: 194.292272ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:17:00.185502Z","caller":"traceutil/trace.go:171","msg":"trace[1845261352] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"156.835008ms","start":"2026-09-11T23:17:00.028643Z","end":"2026-09-11T23:17:00.185478Z","steps":["trace[1845261352] 'process raft request' (duration: 156.628781ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:17:00.185486Z","caller":"traceutil/trace.go:171","msg":"trace[726495894] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2307; }","duration":"120.297052ms","start":"2026-09-11T23:17:00.065135Z","end":"2026-09-11T23:17:00.185432Z","steps":["trace[726495894] 'read index received' (duration: 120.207759ms)","trace[726495894] 'applied index is now lower than readState.Index' (duration: 85.023µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:17:00.185639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.481817ms","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-11T23:17:00.185682Z","caller":"traceutil/trace.go:171","msg":"trace[1653447457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2213; }","duration":"120.57437ms","start":"2026-09-11T23:17:00.065099Z","end":"2026-09-11T23:17:00.185673Z","steps":["trace[1653447457] 'agreement among raft nodes before linearized reading' (duration: 120.433956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:17:00.186115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.741431ms","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-11T23:17:00.186296Z","caller":"traceutil/trace.go:171","msg":"trace[824637607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2213; }","duration":"107.982098ms","start":"2026-09-11T23:17:00.078289Z","end":"2026-09-11T23:17:00.186271Z","steps":["trace[824637607] 'agreement among raft nodes before linearized reading' (duration: 107.534345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:17:00.312694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.926629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T23:17:00.312906Z","caller":"traceutil/trace.go:171","msg":"trace[1724961868] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"121.706706ms","start":"2026-09-11T23:17:00.191174Z","end":"2026-09-11T23:17:00.312881Z","steps":["trace[1724961868] 'process raft request' (duration: 20.489577ms)","trace[1724961868] 'compare' (duration: 100.753934ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:17:00.312941Z","caller":"traceutil/trace.go:171","msg":"trace[1046999007] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"121.452628ms","start":"2026-09-11T23:17:00.19147Z","end":"2026-09-11T23:17:00.312923Z","steps":["trace[1046999007] 'process raft request' (duration: 121.325584ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:17:00.313263Z","caller":"traceutil/trace.go:171","msg":"trace[321179362] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"121.695816ms","start":"2026-09-11T23:17:00.191535Z","end":"2026-09-11T23:17:00.313231Z","steps":["trace[321179362] 'process raft request' (duration: 121.313364ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:17:00.313329Z","caller":"traceutil/trace.go:171","msg":"trace[488699670] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"120.956842ms","start":"2026-09-11T23:17:00.192358Z","end":"2026-09-11T23:17:00.313314Z","steps":["trace[488699670] 'process raft request' (duration: 120.753566ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:17:00.809602Z","caller":"traceutil/trace.go:171","msg":"trace[957806130] linearizableReadLoop","detail":"{readStateIndex:2313; appliedIndex:2312; }","duration":"109.130904ms","start":"2026-09-11T23:17:00.70045Z","end":"2026-09-11T23:17:00.80958Z","steps":["trace[957806130] 'read index received' (duration: 109.032381ms)","trace[957806130] 'applied index is now lower than readState.Index' (duration: 97.233µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:17:00.810128Z","caller":"traceutil/trace.go:171","msg":"trace[1515639344] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"333.555854ms","start":"2026-09-11T23:17:00.476557Z","end":"2026-09-11T23:17:00.810113Z","steps":["trace[1515639344] 'process raft request' (duration: 332.832651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:17:00.810319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:17:00.476528Z","time spent":"333.637217ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T23:17:00.810673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.220978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T23:17:00.810719Z","caller":"traceutil/trace.go:171","msg":"trace[816079139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2218; }","duration":"110.325041ms","start":"2026-09-11T23:17:00.700383Z","end":"2026-09-11T23:17:00.810708Z","steps":["trace[816079139] 'agreement among raft nodes before linearized reading' (duration: 110.232258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:17:01.17137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.5764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T23:17:01.171506Z","caller":"traceutil/trace.go:171","msg":"trace[422205985] linearizableReadLoop","detail":"{readStateIndex:2314; appliedIndex:2313; }","duration":"361.816244ms","start":"2026-09-11T23:17:00.809672Z","end":"2026-09-11T23:17:01.171488Z","steps":["trace[422205985] 'read index received' (duration: 259.889544ms)","trace[422205985] 'applied index is now lower than readState.Index' (duration: 101.92515ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:17:01.171917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.393258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T23:17:01.172015Z","caller":"traceutil/trace.go:171","msg":"trace[1841831055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2219; }","duration":"359.560333ms","start":"2026-09-11T23:17:00.81243Z","end":"2026-09-11T23:17:01.171991Z","steps":["trace[1841831055] 'agreement among raft nodes before linearized reading' (duration: 359.391958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:17:01.172061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:17:00.812405Z","time spent":"359.648106ms","remote":"127.0.0.1:44418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-11T23:17:01.172395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.82673ms","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-11T23:17:01.172459Z","caller":"traceutil/trace.go:171","msg":"trace[7530979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2219; }","duration":"395.904954ms","start":"2026-09-11T23:17:00.776522Z","end":"2026-09-11T23:17:01.172427Z","steps":["trace[7530979] 'agreement among raft nodes before linearized reading' (duration: 395.750898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:17:01.172496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:17:00.776504Z","time spent":"395.984977ms","remote":"127.0.0.1:44662","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-09-11T23:17:01.172689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.839402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.58\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-11T23:17:01.172722Z","caller":"traceutil/trace.go:171","msg":"trace[348991464] range","detail":"{range_begin:/registry/masterleases/162.253.55.58; range_end:; response_count:1; response_revision:2219; }","duration":"358.886773ms","start":"2026-09-11T23:17:00.813826Z","end":"2026-09-11T23:17:01.172713Z","steps":["trace[348991464] 'agreement among raft nodes before linearized reading' (duration: 358.794761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:17:01.172746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:17:00.813819Z","time spent":"358.921554ms","remote":"127.0.0.1:44434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.58\" "} {"level":"warn","ts":"2026-09-11T23:17:01.173025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.588042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-11T23:17:01.17309Z","caller":"traceutil/trace.go:171","msg":"trace[1120558780] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2219; }","duration":"230.687945ms","start":"2026-09-11T23:17:00.94238Z","end":"2026-09-11T23:17:01.173068Z","steps":["trace[1120558780] 'agreement among raft nodes before linearized reading' (duration: 230.566062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:17:01.172721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.002983ms","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-09-11T23:17:01.17372Z","caller":"traceutil/trace.go:171","msg":"trace[274138976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2219; }","duration":"293.038795ms","start":"2026-09-11T23:17:00.880663Z","end":"2026-09-11T23:17:01.173702Z","steps":["trace[274138976] 'agreement among raft nodes before linearized reading' (duration: 291.88787ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:17:01.171598Z","caller":"traceutil/trace.go:171","msg":"trace[1290133990] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"448.332954ms","start":"2026-09-11T23:17:00.723253Z","end":"2026-09-11T23:17:01.171586Z","steps":["trace[1290133990] 'process raft request' (duration: 346.433825ms)","trace[1290133990] 'compare' (duration: 101.29903ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:17:01.175038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:17:00.723235Z","time spent":"451.74762ms","remote":"127.0.0.1:44662","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-11T23:17:01.399246Z","caller":"traceutil/trace.go:171","msg":"trace[303417961] linearizableReadLoop","detail":"{readStateIndex:2317; appliedIndex:2314; }","duration":"205.980107ms","start":"2026-09-11T23:17:01.193237Z","end":"2026-09-11T23:17:01.399217Z","steps":["trace[303417961] 'read index received' (duration: 155.625422ms)","trace[303417961] 'applied index is now lower than readState.Index' (duration: 50.353485ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:17:01.399315Z","caller":"traceutil/trace.go:171","msg":"trace[461754276] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"222.080558ms","start":"2026-09-11T23:17:01.177212Z","end":"2026-09-11T23:17:01.399293Z","steps":["trace[461754276] 'process raft request' (duration: 221.814289ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:17:01.399613Z","caller":"traceutil/trace.go:171","msg":"trace[1215959469] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"221.721167ms","start":"2026-09-11T23:17:01.177875Z","end":"2026-09-11T23:17:01.399596Z","steps":["trace[1215959469] 'process raft request' (duration: 221.285793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:17:01.399626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.358529ms","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-09-11T23:17:01.399681Z","caller":"traceutil/trace.go:171","msg":"trace[737864658] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2221; }","duration":"206.491843ms","start":"2026-09-11T23:17:01.193171Z","end":"2026-09-11T23:17:01.399663Z","steps":["trace[737864658] 'agreement among raft nodes before linearized reading' (duration: 206.155523ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:18:04.305612Z","caller":"traceutil/trace.go:171","msg":"trace[796626336] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"106.521457ms","start":"2026-09-11T23:18:04.199052Z","end":"2026-09-11T23:18:04.305573Z","steps":["trace[796626336] 'process raft request' (duration: 106.279859ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:18:36.784359Z","caller":"traceutil/trace.go:171","msg":"trace[177889920] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"132.583382ms","start":"2026-09-11T23:18:36.651757Z","end":"2026-09-11T23:18:36.78434Z","steps":["trace[177889920] 'process raft request' (duration: 132.103666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:18:37.198786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.129719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18d4677356bd940e\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-09-11T23:18:37.19891Z","caller":"traceutil/trace.go:171","msg":"trace[1423028042] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18d4677356bd940e; range_end:; response_count:1; response_revision:2845; }","duration":"148.329347ms","start":"2026-09-11T23:18:37.050556Z","end":"2026-09-11T23:18:37.198885Z","steps":["trace[1423028042] 'range keys from in-memory index tree' (duration: 147.911251ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:18:37.35195Z","caller":"traceutil/trace.go:171","msg":"trace[318846706] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"150.272434ms","start":"2026-09-11T23:18:37.201642Z","end":"2026-09-11T23:18:37.351915Z","steps":["trace[318846706] 'process raft request' (duration: 134.474135ms)","trace[318846706] 'compare' (duration: 15.649234ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:20:51.654179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.194355ms","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-09-11T23:20:51.654266Z","caller":"traceutil/trace.go:171","msg":"trace[1068440512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3630; }","duration":"122.324599ms","start":"2026-09-11T23:20:51.531928Z","end":"2026-09-11T23:20:51.654253Z","steps":["trace[1068440512] 'range keys from in-memory index tree' (duration: 122.032769ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:22:45.066885Z","caller":"traceutil/trace.go:171","msg":"trace[733269336] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"107.603879ms","start":"2026-09-11T23:22:44.959263Z","end":"2026-09-11T23:22:45.066867Z","steps":["trace[733269336] 'process raft request' (duration: 102.683011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:22:50.608189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.994365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-11T23:22:50.60834Z","caller":"traceutil/trace.go:171","msg":"trace[1057172296] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"363.192206ms","start":"2026-09-11T23:22:50.245127Z","end":"2026-09-11T23:22:50.608319Z","steps":["trace[1057172296] 'process raft request' (duration: 56.453938ms)","trace[1057172296] 'compare' (duration: 305.749007ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T23:22:50.608399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T23:22:50.245118Z","time spent":"363.255089ms","remote":"127.0.0.1:44470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":924,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T23:22:50.612261Z","caller":"traceutil/trace.go:171","msg":"trace[88852030] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"207.148047ms","start":"2026-09-11T23:22:50.405046Z","end":"2026-09-11T23:22:50.612194Z","steps":["trace[88852030] 'process raft request' (duration: 206.940361ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:22:50.995378Z","caller":"traceutil/trace.go:171","msg":"trace[143226817] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"123.575419ms","start":"2026-09-11T23:22:50.871775Z","end":"2026-09-11T23:22:50.99535Z","steps":["trace[143226817] 'process raft request' (duration: 104.456046ms)","trace[143226817] 'compare' (duration: 18.942387ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T23:22:51.184221Z","caller":"traceutil/trace.go:171","msg":"trace[189572221] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"106.509202ms","start":"2026-09-11T23:22:51.077669Z","end":"2026-09-11T23:22:51.184179Z","steps":["trace[189572221] 'process raft request' (duration: 106.471881ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:22:51.184633Z","caller":"traceutil/trace.go:171","msg":"trace[1458217805] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"108.269918ms","start":"2026-09-11T23:22:51.076352Z","end":"2026-09-11T23:22:51.184622Z","steps":["trace[1458217805] 'process raft request' (duration: 107.557926ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:22:51.184839Z","caller":"traceutil/trace.go:171","msg":"trace[94202141] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"107.453013ms","start":"2026-09-11T23:22:51.077376Z","end":"2026-09-11T23:22:51.184829Z","steps":["trace[94202141] 'process raft request' (duration: 106.669248ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:22:51.184961Z","caller":"traceutil/trace.go:171","msg":"trace[510584786] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"107.291686ms","start":"2026-09-11T23:22:51.077661Z","end":"2026-09-11T23:22:51.184952Z","steps":["trace[510584786] 'process raft request' (duration: 106.44066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T23:22:51.185223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.299089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-11T23:22:51.185296Z","caller":"traceutil/trace.go:171","msg":"trace[938263784] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4901; }","duration":"108.448904ms","start":"2026-09-11T23:22:51.076828Z","end":"2026-09-11T23:22:51.185277Z","steps":["trace[938263784] 'agreement among raft nodes before linearized reading' (duration: 108.171445ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:22:57.815732Z","caller":"traceutil/trace.go:171","msg":"trace[1482305845] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"103.241036ms","start":"2026-09-11T23:22:57.712459Z","end":"2026-09-11T23:22:57.8157Z","steps":["trace[1482305845] 'process raft request' (duration: 103.158833ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:22:58.263777Z","caller":"traceutil/trace.go:171","msg":"trace[593353833] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"171.410737ms","start":"2026-09-11T23:22:58.092331Z","end":"2026-09-11T23:22:58.263741Z","steps":["trace[593353833] 'process raft request' (duration: 171.164599ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:23:37.730038Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2849} {"level":"info","ts":"2026-09-11T23:23:37.80416Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2849,"took":"73.050071ms","hash":2628051155,"current-db-size-bytes":53735424,"current-db-size":"54 MB","current-db-size-in-use-bytes":28610560,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-11T23:23:37.804205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2628051155,"revision":2849,"compact-revision":-1} {"level":"info","ts":"2026-09-11T23:23:44.092584Z","caller":"traceutil/trace.go:171","msg":"trace[1028582438] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"119.836512ms","start":"2026-09-11T23:23:43.972719Z","end":"2026-09-11T23:23:44.092555Z","steps":["trace[1028582438] 'process raft request' (duration: 119.705128ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T23:28:37.739014Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5422} {"level":"info","ts":"2026-09-11T23:28:37.860983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5422,"took":"120.542871ms","hash":685833551,"current-db-size-bytes":53735424,"current-db-size":"54 MB","current-db-size-in-use-bytes":18812928,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-11T23:28:37.861058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":685833551,"revision":5422,"compact-revision":2849}