{"level":"warn","ts":"2026-08-10T15:53:41.844635Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T15:53:41.846011Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.223:2380","--initial-cluster=instance=https://199.19.213.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-10T15:53:41.846202Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T15:53:41.846229Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-08-10T15:53:41.849033Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-10T15:53:41.850058Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"]} {"level":"info","ts":"2026-08-10T15:53:41.850276Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-10T15:53:41.856584Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.160163ms"} {"level":"info","ts":"2026-08-10T15:53:41.869179Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"12dd93b2fab6fcb","cluster-id":"8ac3bdb9c2c26b53"} {"level":"info","ts":"2026-08-10T15:53:41.869312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T15:53:41.869377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 0"} {"level":"info","ts":"2026-08-10T15:53:41.869395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 12dd93b2fab6fcb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T15:53:41.869409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 1"} {"level":"info","ts":"2026-08-10T15:53:41.869501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"warn","ts":"2026-08-10T15:53:41.879646Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T15:53:41.881059Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T15:53:41.882096Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-10T15:53:41.884203Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"12dd93b2fab6fcb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T15:53:41.88541Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"12dd93b2fab6fcb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T15:53:41.885619Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-10T15:53:41.885842Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-10T15:53:41.886077Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-10T15:53:41.886517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"info","ts":"2026-08-10T15:53:41.886798Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","added-peer-id":"12dd93b2fab6fcb","added-peer-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-08-10T15:53:41.899718Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-08-10T15:53:41.899812Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-08-10T15:53:41.899487Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-10T15:53:41.901169Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"12dd93b2fab6fcb","initial-advertise-peer-urls":["https://199.19.213.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T15:53:41.90125Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T15:53:42.26997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T15:53:42.270056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T15:53:42.270092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgPreVoteResp from 12dd93b2fab6fcb at term 1"} {"level":"info","ts":"2026-08-10T15:53:42.270122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became candidate at term 2"} {"level":"info","ts":"2026-08-10T15:53:42.27014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgVoteResp from 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-08-10T15:53:42.270165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became leader at term 2"} {"level":"info","ts":"2026-08-10T15:53:42.270194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 12dd93b2fab6fcb elected leader 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-08-10T15:53:42.271717Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T15:53:42.273201Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T15:53:42.273196Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"12dd93b2fab6fcb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.223:2379]}","request-path":"/0/members/12dd93b2fab6fcb/attributes","cluster-id":"8ac3bdb9c2c26b53","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T15:53:42.275059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T15:53:42.275596Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T15:53:42.27573Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T15:53:42.277239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.223:2379"} {"level":"info","ts":"2026-08-10T15:53:42.28767Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T15:53:42.288168Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T15:53:42.288283Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T15:53:42.288322Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-08-10T15:53:56.01395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.336241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:53:56.014216Z","caller":"traceutil/trace.go:171","msg":"trace[1801811558] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"477.633197ms","start":"2026-08-10T15:53:55.536515Z","end":"2026-08-10T15:53:56.014148Z","steps":["trace[1801811558] 'process raft request' (duration: 191.725233ms)","trace[1801811558] 'compare' (duration: 283.638257ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:53:56.014521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:53:55.53649Z","time spent":"477.875512ms","remote":"127.0.0.1:44618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:53:56.397277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.807568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-10T15:53:56.397378Z","caller":"traceutil/trace.go:171","msg":"trace[834818907] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:399; }","duration":"283.011802ms","start":"2026-08-10T15:53:56.114346Z","end":"2026-08-10T15:53:56.397358Z","steps":["trace[834818907] 'range keys from in-memory index tree' (duration: 282.694975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:53:56.397553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.412712ms","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-08-10T15:53:56.397648Z","caller":"traceutil/trace.go:171","msg":"trace[589203118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"192.565256ms","start":"2026-08-10T15:53:56.205059Z","end":"2026-08-10T15:53:56.397624Z","steps":["trace[589203118] 'range keys from in-memory index tree' (duration: 192.299568ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:08.10897Z","caller":"traceutil/trace.go:171","msg":"trace[215033625] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"131.015412ms","start":"2026-08-10T15:54:07.977924Z","end":"2026-08-10T15:54:08.108939Z","steps":["trace[215033625] 'process raft request' (duration: 130.90415ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:08.109246Z","caller":"traceutil/trace.go:171","msg":"trace[1164896828] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"132.863338ms","start":"2026-08-10T15:54:07.976094Z","end":"2026-08-10T15:54:08.108957Z","steps":["trace[1164896828] 'process raft request' (duration: 79.500217ms)","trace[1164896828] 'compare' (duration: 52.962133ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:54:08.478246Z","caller":"traceutil/trace.go:171","msg":"trace[1578003731] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"135.08006ms","start":"2026-08-10T15:54:08.343129Z","end":"2026-08-10T15:54:08.478209Z","steps":["trace[1578003731] 'process raft request' (duration: 73.13655ms)","trace[1578003731] 'compare' (duration: 61.782076ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:54:08.479874Z","caller":"traceutil/trace.go:171","msg":"trace[1115983875] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"131.100612ms","start":"2026-08-10T15:54:08.348743Z","end":"2026-08-10T15:54:08.479843Z","steps":["trace[1115983875] 'process raft request' (duration: 130.96362ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:08.676649Z","caller":"traceutil/trace.go:171","msg":"trace[1527696981] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"102.462726ms","start":"2026-08-10T15:54:08.574166Z","end":"2026-08-10T15:54:08.676629Z","steps":["trace[1527696981] 'read index received' (duration: 18.169707ms)","trace[1527696981] 'applied index is now lower than readState.Index' (duration: 84.292189ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:54:08.676861Z","caller":"traceutil/trace.go:171","msg":"trace[1359033619] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"106.817603ms","start":"2026-08-10T15:54:08.569873Z","end":"2026-08-10T15:54:08.676691Z","steps":["trace[1359033619] 'process raft request' (duration: 22.485753ms)","trace[1359033619] 'compare' (duration: 84.167056ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:54:08.676902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.673501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-10T15:54:08.676976Z","caller":"traceutil/trace.go:171","msg":"trace[2064758622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"102.842795ms","start":"2026-08-10T15:54:08.574116Z","end":"2026-08-10T15:54:08.676958Z","steps":["trace[2064758622] 'agreement among raft nodes before linearized reading' (duration: 102.660681ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:21.892363Z","caller":"traceutil/trace.go:171","msg":"trace[1869408074] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"291.398545ms","start":"2026-08-10T15:54:21.600917Z","end":"2026-08-10T15:54:21.892316Z","steps":["trace[1869408074] 'process raft request' (duration: 270.101673ms)","trace[1869408074] 'compare' (duration: 21.050378ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:54:21.893613Z","caller":"traceutil/trace.go:171","msg":"trace[175718894] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"286.000303ms","start":"2026-08-10T15:54:21.607596Z","end":"2026-08-10T15:54:21.893596Z","steps":["trace[175718894] 'process raft request' (duration: 285.667787ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:21.893869Z","caller":"traceutil/trace.go:171","msg":"trace[1797650885] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"276.531704ms","start":"2026-08-10T15:54:21.617297Z","end":"2026-08-10T15:54:21.893829Z","steps":["trace[1797650885] 'process raft request' (duration: 276.341421ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:21.893904Z","caller":"traceutil/trace.go:171","msg":"trace[712205802] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"283.16458ms","start":"2026-08-10T15:54:21.610682Z","end":"2026-08-10T15:54:21.893847Z","steps":["trace[712205802] 'process raft request' (duration: 282.764273ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:21.894937Z","caller":"traceutil/trace.go:171","msg":"trace[1406450391] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"282.281584ms","start":"2026-08-10T15:54:21.611683Z","end":"2026-08-10T15:54:21.893965Z","steps":["trace[1406450391] 'process raft request' (duration: 281.826484ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:21.895006Z","caller":"traceutil/trace.go:171","msg":"trace[1119173554] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"270.607793ms","start":"2026-08-10T15:54:21.62436Z","end":"2026-08-10T15:54:21.894968Z","steps":["trace[1119173554] 'process raft request' (duration: 269.379269ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:21.899635Z","caller":"traceutil/trace.go:171","msg":"trace[871033109] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"262.096031ms","start":"2026-08-10T15:54:21.637495Z","end":"2026-08-10T15:54:21.899591Z","steps":["trace[871033109] 'process raft request' (duration: 257.592396ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:21.900075Z","caller":"traceutil/trace.go:171","msg":"trace[1948885033] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"143.110809ms","start":"2026-08-10T15:54:21.756941Z","end":"2026-08-10T15:54:21.900052Z","steps":["trace[1948885033] 'process raft request' (duration: 142.915515ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:22.168606Z","caller":"traceutil/trace.go:171","msg":"trace[562515149] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"133.017171ms","start":"2026-08-10T15:54:22.035565Z","end":"2026-08-10T15:54:22.168582Z","steps":["trace[562515149] 'process raft request' (duration: 78.330899ms)","trace[562515149] 'compare' (duration: 54.512239ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:54:22.169183Z","caller":"traceutil/trace.go:171","msg":"trace[76967826] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"124.799036ms","start":"2026-08-10T15:54:22.044374Z","end":"2026-08-10T15:54:22.169173Z","steps":["trace[76967826] 'process raft request' (duration: 124.537352ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:27.557794Z","caller":"traceutil/trace.go:171","msg":"trace[1517358103] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"205.153101ms","start":"2026-08-10T15:54:27.352621Z","end":"2026-08-10T15:54:27.557775Z","steps":["trace[1517358103] 'process raft request' (duration: 204.707963ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:28.246054Z","caller":"traceutil/trace.go:171","msg":"trace[387327928] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"213.428786ms","start":"2026-08-10T15:54:28.032599Z","end":"2026-08-10T15:54:28.246028Z","steps":["trace[387327928] 'process raft request' (duration: 211.854957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:54:28.470501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.871099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:54:28.470592Z","caller":"traceutil/trace.go:171","msg":"trace[365289711] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"334.416782ms","start":"2026-08-10T15:54:28.136162Z","end":"2026-08-10T15:54:28.470579Z","steps":["trace[365289711] 'process raft request' (duration: 222.774039ms)","trace[365289711] 'compare' (duration: 110.695545ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:54:28.470646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:54:28.136136Z","time spent":"334.480255ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:54:28.475318Z","caller":"traceutil/trace.go:171","msg":"trace[587480719] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"172.706719ms","start":"2026-08-10T15:54:28.302595Z","end":"2026-08-10T15:54:28.475301Z","steps":["trace[587480719] 'process raft request' (duration: 172.563537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:54:29.182496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.346776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:54:29.182606Z","caller":"traceutil/trace.go:171","msg":"trace[1501070605] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"372.796798ms","start":"2026-08-10T15:54:28.809793Z","end":"2026-08-10T15:54:29.18259Z","steps":["trace[1501070605] 'read index received' (duration: 145.305819ms)","trace[1501070605] 'applied index is now lower than readState.Index' (duration: 227.489659ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:54:29.182927Z","caller":"traceutil/trace.go:171","msg":"trace[1436931449] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"688.238058ms","start":"2026-08-10T15:54:28.494622Z","end":"2026-08-10T15:54:29.18286Z","steps":["trace[1436931449] 'process raft request' (duration: 460.432485ms)","trace[1436931449] 'compare' (duration: 227.088831ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:54:29.183135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.284686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-10T15:54:29.183194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:54:28.494588Z","time spent":"688.474483ms","remote":"127.0.0.1:44778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:54:29.183242Z","caller":"traceutil/trace.go:171","msg":"trace[1214657789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"373.433489ms","start":"2026-08-10T15:54:28.809781Z","end":"2026-08-10T15:54:29.183215Z","steps":["trace[1214657789] 'agreement among raft nodes before linearized reading' (duration: 373.063462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:54:29.183306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:54:28.809729Z","time spent":"373.563582ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-10T15:54:29.242588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.560841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:54:29.242629Z","caller":"traceutil/trace.go:171","msg":"trace[1748355277] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"365.884498ms","start":"2026-08-10T15:54:28.876693Z","end":"2026-08-10T15:54:29.242578Z","steps":["trace[1748355277] 'process raft request' (duration: 364.839778ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:29.242687Z","caller":"traceutil/trace.go:171","msg":"trace[1496791700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"231.787716ms","start":"2026-08-10T15:54:29.010877Z","end":"2026-08-10T15:54:29.242665Z","steps":["trace[1496791700] 'agreement among raft nodes before linearized reading' (duration: 231.601343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:54:29.242827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:54:28.876649Z","time spent":"366.07252ms","remote":"127.0.0.1:44678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:54:29.242833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.638042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-10T15:54:29.242936Z","caller":"traceutil/trace.go:171","msg":"trace[1695169438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"401.783314ms","start":"2026-08-10T15:54:28.841132Z","end":"2026-08-10T15:54:29.242915Z","steps":["trace[1695169438] 'agreement among raft nodes before linearized reading' (duration: 401.470128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:54:29.243075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:54:28.841116Z","time spent":"401.944326ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T15:54:29.243012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.898534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-10T15:54:29.243538Z","caller":"traceutil/trace.go:171","msg":"trace[325038278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"431.77738ms","start":"2026-08-10T15:54:28.811719Z","end":"2026-08-10T15:54:29.243496Z","steps":["trace[325038278] 'agreement among raft nodes before linearized reading' (duration: 430.71817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:54:29.24374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:54:28.811666Z","time spent":"432.049636ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-10T15:54:30.779529Z","caller":"traceutil/trace.go:171","msg":"trace[1557573563] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"128.376893ms","start":"2026-08-10T15:54:30.65113Z","end":"2026-08-10T15:54:30.779507Z","steps":["trace[1557573563] 'process raft request' (duration: 128.235311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:54:43.607082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.165677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-10T15:54:43.607132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.33325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-10T15:54:43.607156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.455879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-10T15:54:43.607188Z","caller":"traceutil/trace.go:171","msg":"trace[2094826304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"281.294639ms","start":"2026-08-10T15:54:43.325876Z","end":"2026-08-10T15:54:43.60717Z","steps":["trace[2094826304] 'range keys from in-memory index tree' (duration: 280.992233ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:43.607239Z","caller":"traceutil/trace.go:171","msg":"trace[2137299308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"268.553501ms","start":"2026-08-10T15:54:43.338654Z","end":"2026-08-10T15:54:43.607207Z","steps":["trace[2137299308] 'range keys from in-memory index tree' (duration: 268.359708ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:43.607304Z","caller":"traceutil/trace.go:171","msg":"trace[1565417006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"247.536682ms","start":"2026-08-10T15:54:43.359748Z","end":"2026-08-10T15:54:43.607285Z","steps":["trace[1565417006] 'range keys from in-memory index tree' (duration: 247.146386ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:54:58.020048Z","caller":"traceutil/trace.go:171","msg":"trace[500130436] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"100.136341ms","start":"2026-08-10T15:54:57.919863Z","end":"2026-08-10T15:54:58.02Z","steps":["trace[500130436] 'process raft request' (duration: 99.159154ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:55:37.992939Z","caller":"traceutil/trace.go:171","msg":"trace[163629520] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"114.286883ms","start":"2026-08-10T15:55:37.878623Z","end":"2026-08-10T15:55:37.99291Z","steps":["trace[163629520] 'process raft request' (duration: 113.978825ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:02.932907Z","caller":"traceutil/trace.go:171","msg":"trace[1068322766] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"270.140183ms","start":"2026-08-10T15:56:02.662436Z","end":"2026-08-10T15:56:02.932576Z","steps":["trace[1068322766] 'process raft request' (duration: 269.914688ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:02.974228Z","caller":"traceutil/trace.go:171","msg":"trace[245250624] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"310.526065ms","start":"2026-08-10T15:56:02.663686Z","end":"2026-08-10T15:56:02.974212Z","steps":["trace[245250624] 'process raft request' (duration: 310.367492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:02.974389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:02.663661Z","time spent":"310.665058ms","remote":"127.0.0.1:44838","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-08-10T15:56:03.226309Z","caller":"traceutil/trace.go:171","msg":"trace[1906812639] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"247.058512ms","start":"2026-08-10T15:56:02.97922Z","end":"2026-08-10T15:56:03.226278Z","steps":["trace[1906812639] 'process raft request' (duration: 241.37091ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:03.260853Z","caller":"traceutil/trace.go:171","msg":"trace[1058810550] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1960; }","duration":"270.904148ms","start":"2026-08-10T15:56:02.989923Z","end":"2026-08-10T15:56:03.260827Z","steps":["trace[1058810550] 'read index received' (duration: 230.682041ms)","trace[1058810550] 'applied index is now lower than readState.Index' (duration: 40.221107ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:03.260912Z","caller":"traceutil/trace.go:171","msg":"trace[916077796] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"279.1784ms","start":"2026-08-10T15:56:02.981697Z","end":"2026-08-10T15:56:03.260876Z","steps":["trace[916077796] 'process raft request' (duration: 278.996426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:03.261135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.170214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-10T15:56:03.261208Z","caller":"traceutil/trace.go:171","msg":"trace[646153515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1892; }","duration":"271.297037ms","start":"2026-08-10T15:56:02.989893Z","end":"2026-08-10T15:56:03.26119Z","steps":["trace[646153515] 'agreement among raft nodes before linearized reading' (duration: 271.042021ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:15.627308Z","caller":"traceutil/trace.go:171","msg":"trace[839041115] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"134.070931ms","start":"2026-08-10T15:56:15.493186Z","end":"2026-08-10T15:56:15.627257Z","steps":["trace[839041115] 'process raft request' (duration: 119.989732ms)","trace[839041115] 'compare' (duration: 13.569767ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:19.229847Z","caller":"traceutil/trace.go:171","msg":"trace[781551005] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"236.346857ms","start":"2026-08-10T15:56:18.993476Z","end":"2026-08-10T15:56:19.229823Z","steps":["trace[781551005] 'process raft request' (duration: 236.200054ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:19.241065Z","caller":"traceutil/trace.go:171","msg":"trace[503328220] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2098; }","duration":"155.53135ms","start":"2026-08-10T15:56:19.085512Z","end":"2026-08-10T15:56:19.241044Z","steps":["trace[503328220] 'read index received' (duration: 144.830869ms)","trace[503328220] 'applied index is now lower than readState.Index' (duration: 10.699551ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:19.24135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.765965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-10T15:56:19.241394Z","caller":"traceutil/trace.go:171","msg":"trace[949604396] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2025; }","duration":"155.97453ms","start":"2026-08-10T15:56:19.085401Z","end":"2026-08-10T15:56:19.241376Z","steps":["trace[949604396] 'agreement among raft nodes before linearized reading' (duration: 155.725094ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:19.241573Z","caller":"traceutil/trace.go:171","msg":"trace[101633293] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"219.241843ms","start":"2026-08-10T15:56:19.022296Z","end":"2026-08-10T15:56:19.241538Z","steps":["trace[101633293] 'process raft request' (duration: 218.605139ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:22.153907Z","caller":"traceutil/trace.go:171","msg":"trace[1456429776] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"146.170589ms","start":"2026-08-10T15:56:22.0077Z","end":"2026-08-10T15:56:22.153871Z","steps":["trace[1456429776] 'process raft request' (duration: 145.924373ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:22.202579Z","caller":"traceutil/trace.go:171","msg":"trace[1969104897] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"140.898812ms","start":"2026-08-10T15:56:22.06165Z","end":"2026-08-10T15:56:22.202549Z","steps":["trace[1969104897] 'process raft request' (duration: 120.742731ms)","trace[1969104897] 'compare' (duration: 20.003307ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:43.550258Z","caller":"traceutil/trace.go:171","msg":"trace[857227349] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2238; }","duration":"188.073633ms","start":"2026-08-10T15:56:43.362154Z","end":"2026-08-10T15:56:43.550228Z","steps":["trace[857227349] 'read index received' (duration: 4.941846ms)","trace[857227349] 'applied index is now lower than readState.Index' (duration: 183.131017ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:43.550554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.363938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:56:43.550627Z","caller":"traceutil/trace.go:171","msg":"trace[1397236057] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2161; }","duration":"188.520711ms","start":"2026-08-10T15:56:43.362088Z","end":"2026-08-10T15:56:43.550609Z","steps":["trace[1397236057] 'agreement among raft nodes before linearized reading' (duration: 188.310887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:43.550691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.978542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-10T15:56:43.55077Z","caller":"traceutil/trace.go:171","msg":"trace[934355478] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"129.138136ms","start":"2026-08-10T15:56:43.421619Z","end":"2026-08-10T15:56:43.550757Z","steps":["trace[934355478] 'agreement among raft nodes before linearized reading' (duration: 128.82894ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:56:43.550857Z","caller":"traceutil/trace.go:171","msg":"trace[426224447] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"223.80714ms","start":"2026-08-10T15:56:43.326993Z","end":"2026-08-10T15:56:43.5508Z","steps":["trace[426224447] 'process raft request' (duration: 208.615073ms)","trace[426224447] 'compare' (duration: 14.510802ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:56:43.551183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.421967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-10T15:56:43.55124Z","caller":"traceutil/trace.go:171","msg":"trace[727151511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2161; }","duration":"126.500309ms","start":"2026-08-10T15:56:43.424725Z","end":"2026-08-10T15:56:43.551225Z","steps":["trace[727151511] 'agreement among raft nodes before linearized reading' (duration: 126.351296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:43.785676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.253585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:56:43.785818Z","caller":"traceutil/trace.go:171","msg":"trace[1650533805] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"230.043605ms","start":"2026-08-10T15:56:43.555754Z","end":"2026-08-10T15:56:43.785797Z","steps":["trace[1650533805] 'process raft request' (duration: 116.580415ms)","trace[1650533805] 'compare' (duration: 113.117162ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:43.968909Z","caller":"traceutil/trace.go:171","msg":"trace[1810722589] linearizableReadLoop","detail":"{readStateIndex:2242; appliedIndex:2241; }","duration":"283.395061ms","start":"2026-08-10T15:56:43.685489Z","end":"2026-08-10T15:56:43.968884Z","steps":["trace[1810722589] 'read index received' (duration: 283.179396ms)","trace[1810722589] 'applied index is now lower than readState.Index' (duration: 214.475µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:56:43.969024Z","caller":"traceutil/trace.go:171","msg":"trace[650716516] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"413.1472ms","start":"2026-08-10T15:56:43.55584Z","end":"2026-08-10T15:56:43.968987Z","steps":["trace[650716516] 'process raft request' (duration: 412.883225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:43.96925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:56:43.555817Z","time spent":"413.288823ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:56:43.96947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.945943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127377"} {"level":"info","ts":"2026-08-10T15:56:43.969607Z","caller":"traceutil/trace.go:171","msg":"trace[126364373] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2163; }","duration":"284.152057ms","start":"2026-08-10T15:56:43.685423Z","end":"2026-08-10T15:56:43.969575Z","steps":["trace[126364373] 'agreement among raft nodes before linearized reading' (duration: 283.638757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:56:43.98404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.380203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-10T15:56:43.984116Z","caller":"traceutil/trace.go:171","msg":"trace[986461452] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2164; }","duration":"292.502367ms","start":"2026-08-10T15:56:43.691598Z","end":"2026-08-10T15:56:43.9841Z","steps":["trace[986461452] 'agreement among raft nodes before linearized reading' (duration: 292.332813ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:18.187782Z","caller":"traceutil/trace.go:171","msg":"trace[1363159853] linearizableReadLoop","detail":"{readStateIndex:2876; appliedIndex:2875; }","duration":"458.206406ms","start":"2026-08-10T15:58:17.729539Z","end":"2026-08-10T15:58:18.187745Z","steps":["trace[1363159853] 'read index received' (duration: 457.918481ms)","trace[1363159853] 'applied index is now lower than readState.Index' (duration: 286.835µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:18.188233Z","caller":"traceutil/trace.go:171","msg":"trace[314776607] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"460.406018ms","start":"2026-08-10T15:58:17.727808Z","end":"2026-08-10T15:58:18.188214Z","steps":["trace[314776607] 'process raft request' (duration: 459.734126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:18.188389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:17.727788Z","time spent":"460.492109ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:18.188782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.235746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-08-10T15:58:18.188844Z","caller":"traceutil/trace.go:171","msg":"trace[1951535776] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2775; }","duration":"459.298338ms","start":"2026-08-10T15:58:17.729529Z","end":"2026-08-10T15:58:18.188827Z","steps":["trace[1951535776] 'agreement among raft nodes before linearized reading' (duration: 459.111484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:18.188896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:17.729487Z","time spent":"459.3954ms","remote":"127.0.0.1:44780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1342,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-08-10T15:58:18.18914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.016928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:18.189184Z","caller":"traceutil/trace.go:171","msg":"trace[778317780] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2775; }","duration":"454.071049ms","start":"2026-08-10T15:58:17.735101Z","end":"2026-08-10T15:58:18.189172Z","steps":["trace[778317780] 'agreement among raft nodes before linearized reading' (duration: 453.982197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:18.189223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:17.735071Z","time spent":"454.13896ms","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-10T15:58:18.540789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.110637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-10T15:58:18.540868Z","caller":"traceutil/trace.go:171","msg":"trace[1683706730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2778; }","duration":"110.238648ms","start":"2026-08-10T15:58:18.430614Z","end":"2026-08-10T15:58:18.540853Z","steps":["trace[1683706730] 'range keys from in-memory index tree' (duration: 109.893352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:23.068423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055708196074357824,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-10T15:58:23.182758Z","caller":"traceutil/trace.go:171","msg":"trace[905500585] linearizableReadLoop","detail":"{readStateIndex:2898; appliedIndex:2897; }","duration":"686.358324ms","start":"2026-08-10T15:58:22.496366Z","end":"2026-08-10T15:58:23.182724Z","steps":["trace[905500585] 'read index received' (duration: 686.262372ms)","trace[905500585] 'applied index is now lower than readState.Index' (duration: 94.432µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:23.183148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.754242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-10T15:58:23.18327Z","caller":"traceutil/trace.go:171","msg":"trace[935673522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2796; }","duration":"686.907365ms","start":"2026-08-10T15:58:22.496339Z","end":"2026-08-10T15:58:23.183247Z","steps":["trace[935673522] 'agreement among raft nodes before linearized reading' (duration: 686.498187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:23.183342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:22.49633Z","time spent":"686.998427ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-10T15:58:23.183794Z","caller":"traceutil/trace.go:171","msg":"trace[450598198] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"689.032774ms","start":"2026-08-10T15:58:22.494589Z","end":"2026-08-10T15:58:23.183621Z","steps":["trace[450598198] 'process raft request' (duration: 687.890783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:23.184134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:22.494565Z","time spent":"689.399261ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:23.243307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.518087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-10T15:58:23.254269Z","caller":"traceutil/trace.go:171","msg":"trace[1376188234] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"175.13745ms","start":"2026-08-10T15:58:23.079071Z","end":"2026-08-10T15:58:23.254208Z","steps":["trace[1376188234] 'process raft request' (duration: 163.929527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:23.254511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.075506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-10T15:58:23.254622Z","caller":"traceutil/trace.go:171","msg":"trace[1680426779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2801; }","duration":"184.30877ms","start":"2026-08-10T15:58:23.070287Z","end":"2026-08-10T15:58:23.254596Z","steps":["trace[1680426779] 'agreement among raft nodes before linearized reading' (duration: 183.964214ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:23.254969Z","caller":"traceutil/trace.go:171","msg":"trace[2062649070] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"186.269868ms","start":"2026-08-10T15:58:23.068683Z","end":"2026-08-10T15:58:23.254953Z","steps":["trace[2062649070] 'process raft request' (duration: 173.990336ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:23.255042Z","caller":"traceutil/trace.go:171","msg":"trace[1291548401] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"173.331854ms","start":"2026-08-10T15:58:23.081694Z","end":"2026-08-10T15:58:23.255026Z","steps":["trace[1291548401] 'process raft request' (duration: 161.360629ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:23.255167Z","caller":"traceutil/trace.go:171","msg":"trace[549120576] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"184.858671ms","start":"2026-08-10T15:58:23.070293Z","end":"2026-08-10T15:58:23.255151Z","steps":["trace[549120576] 'process raft request' (duration: 172.555359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:23.255296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.011483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-10T15:58:23.255329Z","caller":"traceutil/trace.go:171","msg":"trace[1735185430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2801; }","duration":"178.086164ms","start":"2026-08-10T15:58:23.077235Z","end":"2026-08-10T15:58:23.255321Z","steps":["trace[1735185430] 'agreement among raft nodes before linearized reading' (duration: 177.990462ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:23.255415Z","caller":"traceutil/trace.go:171","msg":"trace[1819775704] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"176.874162ms","start":"2026-08-10T15:58:23.078528Z","end":"2026-08-10T15:58:23.255402Z","steps":["trace[1819775704] 'process raft request' (duration: 164.423717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:23.255907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.865169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-10T15:58:23.255995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.061423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:23.256144Z","caller":"traceutil/trace.go:171","msg":"trace[25112608] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2801; }","duration":"187.250606ms","start":"2026-08-10T15:58:23.068875Z","end":"2026-08-10T15:58:23.256125Z","steps":["trace[25112608] 'agreement among raft nodes before linearized reading' (duration: 187.052452ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:23.260848Z","caller":"traceutil/trace.go:171","msg":"trace[1714671608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2801; }","duration":"187.059263ms","start":"2026-08-10T15:58:23.068962Z","end":"2026-08-10T15:58:23.256021Z","steps":["trace[1714671608] 'agreement among raft nodes before linearized reading' (duration: 186.572634ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T15:58:23.263376Z","caller":"traceutil/trace.go:171","msg":"trace[150774941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2801; }","duration":"174.646789ms","start":"2026-08-10T15:58:23.068746Z","end":"2026-08-10T15:58:23.243393Z","steps":["trace[150774941] 'agreement among raft nodes before linearized reading' (duration: 174.352863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.103649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.492435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T15:58:24.10391Z","caller":"traceutil/trace.go:171","msg":"trace[222677573] linearizableReadLoop","detail":"{readStateIndex:2908; appliedIndex:2904; }","duration":"571.393323ms","start":"2026-08-10T15:58:23.532501Z","end":"2026-08-10T15:58:24.103894Z","steps":["trace[222677573] 'read index received' (duration: 85.4785ms)","trace[222677573] 'applied index is now lower than readState.Index' (duration: 485.913903ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T15:58:24.104041Z","caller":"traceutil/trace.go:171","msg":"trace[1041369673] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"837.068955ms","start":"2026-08-10T15:58:23.266926Z","end":"2026-08-10T15:58:24.103995Z","steps":["trace[1041369673] 'process raft request' (duration: 836.895452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.106025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:23.26692Z","time spent":"838.93046ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:58:24.104164Z","caller":"traceutil/trace.go:171","msg":"trace[249713138] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"837.197587ms","start":"2026-08-10T15:58:23.266921Z","end":"2026-08-10T15:58:24.104119Z","steps":["trace[249713138] 'process raft request' (duration: 836.938412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.10639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:23.266884Z","time spent":"839.45738ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T15:58:24.104224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.717909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T15:58:24.106711Z","caller":"traceutil/trace.go:171","msg":"trace[670130269] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2806; }","duration":"574.258396ms","start":"2026-08-10T15:58:23.532439Z","end":"2026-08-10T15:58:24.106698Z","steps":["trace[670130269] 'agreement among raft nodes before linearized reading' (duration: 571.749239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.106786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:23.532408Z","time spent":"574.344738ms","remote":"127.0.0.1:51846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-08-10T15:58:24.104453Z","caller":"traceutil/trace.go:171","msg":"trace[282652447] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"837.640586ms","start":"2026-08-10T15:58:23.266797Z","end":"2026-08-10T15:58:24.104438Z","steps":["trace[282652447] 'process raft request' (duration: 351.280535ms)","trace[282652447] 'compare' (duration: 484.622998ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T15:58:24.107161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:23.266763Z","time spent":"840.370457ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T15:58:24.104505Z","caller":"traceutil/trace.go:171","msg":"trace[130600214] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"837.689927ms","start":"2026-08-10T15:58:23.266805Z","end":"2026-08-10T15:58:24.104495Z","steps":["trace[130600214] 'process raft request' (duration: 836.939983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.10528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.629953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-10T15:58:24.108331Z","caller":"traceutil/trace.go:171","msg":"trace[740540931] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2807; }","duration":"413.71731ms","start":"2026-08-10T15:58:23.694602Z","end":"2026-08-10T15:58:24.108319Z","steps":["trace[740540931] 'agreement among raft nodes before linearized reading' (duration: 410.563781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.108415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:23.694584Z","time spent":"413.800971ms","remote":"127.0.0.1:44780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-10T15:58:24.105321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.315699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T15:58:24.108748Z","caller":"traceutil/trace.go:171","msg":"trace[1698101695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2807; }","duration":"140.768793ms","start":"2026-08-10T15:58:23.967972Z","end":"2026-08-10T15:58:24.108741Z","steps":["trace[1698101695] 'agreement among raft nodes before linearized reading' (duration: 137.332458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.10544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.337623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-10T15:58:24.10896Z","caller":"traceutil/trace.go:171","msg":"trace[311685734] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2807; }","duration":"232.88553ms","start":"2026-08-10T15:58:23.876068Z","end":"2026-08-10T15:58:24.108953Z","steps":["trace[311685734] 'agreement among raft nodes before linearized reading' (duration: 229.269632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T15:58:24.109054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T15:58:23.266788Z","time spent":"841.01731ms","remote":"127.0.0.1:44838","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-08-10T15:58:24.52699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.775277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T15:58:24.527544Z","caller":"traceutil/trace.go:171","msg":"trace[1877054308] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2808; }","duration":"103.898228ms","start":"2026-08-10T15:58:24.423631Z","end":"2026-08-10T15:58:24.527529Z","steps":["trace[1877054308] 'count revisions from in-memory index tree' (duration: 102.694814ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:02:15.783574Z","caller":"traceutil/trace.go:171","msg":"trace[1412321562] linearizableReadLoop","detail":"{readStateIndex:4827; appliedIndex:4826; }","duration":"122.805107ms","start":"2026-08-10T16:02:15.660731Z","end":"2026-08-10T16:02:15.783536Z","steps":["trace[1412321562] 'read index received' (duration: 103.872435ms)","trace[1412321562] 'applied index is now lower than readState.Index' (duration: 18.931692ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T16:02:15.783933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.112303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-10T16:02:15.783977Z","caller":"traceutil/trace.go:171","msg":"trace[2021569134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4653; }","duration":"123.249126ms","start":"2026-08-10T16:02:15.660715Z","end":"2026-08-10T16:02:15.783964Z","steps":["trace[2021569134] 'agreement among raft nodes before linearized reading' (duration: 122.95088ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:02:15.786176Z","caller":"traceutil/trace.go:171","msg":"trace[412042919] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"125.217689ms","start":"2026-08-10T16:02:15.660929Z","end":"2026-08-10T16:02:15.786147Z","steps":["trace[412042919] 'process raft request' (duration: 125.100637ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:02:15.787683Z","caller":"traceutil/trace.go:171","msg":"trace[375660219] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"123.611962ms","start":"2026-08-10T16:02:15.660029Z","end":"2026-08-10T16:02:15.783641Z","steps":["trace[375660219] 'process raft request' (duration: 104.558278ms)","trace[375660219] 'compare' (duration: 18.784299ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T16:02:17.943851Z","caller":"traceutil/trace.go:171","msg":"trace[128176361] linearizableReadLoop","detail":"{readStateIndex:4897; appliedIndex:4895; }","duration":"137.264963ms","start":"2026-08-10T16:02:17.806558Z","end":"2026-08-10T16:02:17.943823Z","steps":["trace[128176361] 'read index received' (duration: 48.185799ms)","trace[128176361] 'applied index is now lower than readState.Index' (duration: 89.078114ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T16:02:17.944024Z","caller":"traceutil/trace.go:171","msg":"trace[1678865122] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"145.8657ms","start":"2026-08-10T16:02:17.798107Z","end":"2026-08-10T16:02:17.943972Z","steps":["trace[1678865122] 'process raft request' (duration: 144.295482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T16:02:17.944122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.537378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-10T16:02:17.944172Z","caller":"traceutil/trace.go:171","msg":"trace[1724769829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4719; }","duration":"137.617449ms","start":"2026-08-10T16:02:17.806541Z","end":"2026-08-10T16:02:17.944159Z","steps":["trace[1724769829] 'agreement among raft nodes before linearized reading' (duration: 137.402735ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:02:24.502668Z","caller":"traceutil/trace.go:171","msg":"trace[747470493] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"380.361489ms","start":"2026-08-10T16:02:24.122271Z","end":"2026-08-10T16:02:24.502633Z","steps":["trace[747470493] 'process raft request' (duration: 380.113745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T16:02:24.502953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T16:02:24.122245Z","time spent":"380.576482ms","remote":"127.0.0.1:45038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9560,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T16:02:24.536582Z","caller":"traceutil/trace.go:171","msg":"trace[659308708] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"403.186926ms","start":"2026-08-10T16:02:24.133355Z","end":"2026-08-10T16:02:24.536542Z","steps":["trace[659308708] 'process raft request' (duration: 402.939562ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:02:24.536636Z","caller":"traceutil/trace.go:171","msg":"trace[395243018] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"203.471704ms","start":"2026-08-10T16:02:24.333121Z","end":"2026-08-10T16:02:24.536593Z","steps":["trace[395243018] 'process raft request' (duration: 203.335112ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:02:24.536761Z","caller":"traceutil/trace.go:171","msg":"trace[875562012] linearizableReadLoop","detail":"{readStateIndex:5000; appliedIndex:4998; }","duration":"403.247688ms","start":"2026-08-10T16:02:24.133492Z","end":"2026-08-10T16:02:24.536739Z","steps":["trace[875562012] 'read index received' (duration: 368.913684ms)","trace[875562012] 'applied index is now lower than readState.Index' (duration: 34.332853ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T16:02:24.536823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T16:02:24.133328Z","time spent":"403.341979ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1436,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T16:02:24.536998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.486721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-08-10T16:02:24.537039Z","caller":"traceutil/trace.go:171","msg":"trace[565075087] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4824; }","duration":"403.569933ms","start":"2026-08-10T16:02:24.13346Z","end":"2026-08-10T16:02:24.53703Z","steps":["trace[565075087] 'agreement among raft nodes before linearized reading' (duration: 403.419111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T16:02:24.537063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T16:02:24.133452Z","time spent":"403.603724ms","remote":"127.0.0.1:44678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1344,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" "} {"level":"warn","ts":"2026-08-10T16:02:24.544095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.957395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T16:02:24.544172Z","caller":"traceutil/trace.go:171","msg":"trace[985448452] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4825; }","duration":"260.115248ms","start":"2026-08-10T16:02:24.284039Z","end":"2026-08-10T16:02:24.544154Z","steps":["trace[985448452] 'agreement among raft nodes before linearized reading' (duration: 259.995065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T16:02:24.544723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.99132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-08-10T16:02:24.544774Z","caller":"traceutil/trace.go:171","msg":"trace[182528103] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4825; }","duration":"348.0843ms","start":"2026-08-10T16:02:24.196678Z","end":"2026-08-10T16:02:24.544763Z","steps":["trace[182528103] 'agreement among raft nodes before linearized reading' (duration: 347.149634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T16:02:24.544807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T16:02:24.196656Z","time spent":"348.139411ms","remote":"127.0.0.1:44694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207441,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-08-10T16:02:25.154051Z","caller":"traceutil/trace.go:171","msg":"trace[630442300] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"105.154356ms","start":"2026-08-10T16:02:25.048869Z","end":"2026-08-10T16:02:25.154024Z","steps":["trace[630442300] 'process raft request' (duration: 98.657855ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:02:27.96348Z","caller":"traceutil/trace.go:171","msg":"trace[1661575672] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"178.101483ms","start":"2026-08-10T16:02:27.785341Z","end":"2026-08-10T16:02:27.963443Z","steps":["trace[1661575672] 'process raft request' (duration: 167.115697ms)","trace[1661575672] 'compare' (duration: 10.612249ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T16:02:38.162076Z","caller":"traceutil/trace.go:171","msg":"trace[653186370] linearizableReadLoop","detail":"{readStateIndex:5209; appliedIndex:5208; }","duration":"200.178163ms","start":"2026-08-10T16:02:37.961863Z","end":"2026-08-10T16:02:38.162042Z","steps":["trace[653186370] 'read index received' (duration: 188.288001ms)","trace[653186370] 'applied index is now lower than readState.Index' (duration: 11.888562ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T16:02:38.162282Z","caller":"traceutil/trace.go:171","msg":"trace[1443037773] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"203.672472ms","start":"2026-08-10T16:02:37.958583Z","end":"2026-08-10T16:02:38.162256Z","steps":["trace[1443037773] 'process raft request' (duration: 191.624078ms)","trace[1443037773] 'compare' (duration: 11.26173ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T16:02:38.162974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.233264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-10T16:02:38.163072Z","caller":"traceutil/trace.go:171","msg":"trace[439739820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5024; }","duration":"134.386557ms","start":"2026-08-10T16:02:38.028664Z","end":"2026-08-10T16:02:38.163051Z","steps":["trace[439739820] 'agreement among raft nodes before linearized reading' (duration: 133.643695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T16:02:38.163584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.691428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T16:02:38.163662Z","caller":"traceutil/trace.go:171","msg":"trace[1616620267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5024; }","duration":"201.79258ms","start":"2026-08-10T16:02:37.961851Z","end":"2026-08-10T16:02:38.163643Z","steps":["trace[1616620267] 'agreement among raft nodes before linearized reading' (duration: 201.652407ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:02:38.332013Z","caller":"traceutil/trace.go:171","msg":"trace[1043317511] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"162.204619ms","start":"2026-08-10T16:02:38.169775Z","end":"2026-08-10T16:02:38.33198Z","steps":["trace[1043317511] 'process raft request' (duration: 101.240875ms)","trace[1043317511] 'compare' (duration: 60.73719ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T16:02:38.332393Z","caller":"traceutil/trace.go:171","msg":"trace[613404196] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"160.930238ms","start":"2026-08-10T16:02:38.17138Z","end":"2026-08-10T16:02:38.33231Z","steps":["trace[613404196] 'process raft request' (duration: 160.549501ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:02:38.332477Z","caller":"traceutil/trace.go:171","msg":"trace[790629862] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"155.495045ms","start":"2026-08-10T16:02:38.176946Z","end":"2026-08-10T16:02:38.332441Z","steps":["trace[790629862] 'process raft request' (duration: 155.316992ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:03:42.323273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2902} {"level":"info","ts":"2026-08-10T16:03:42.41991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2902,"took":"95.023649ms","hash":3661105711,"current-db-size-bytes":54763520,"current-db-size":"55 MB","current-db-size-in-use-bytes":29167616,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-10T16:03:42.420006Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3661105711,"revision":2902,"compact-revision":-1} {"level":"info","ts":"2026-08-10T16:06:17.060556Z","caller":"traceutil/trace.go:171","msg":"trace[1775373155] linearizableReadLoop","detail":"{readStateIndex:6841; appliedIndex:6840; }","duration":"227.831402ms","start":"2026-08-10T16:06:16.832706Z","end":"2026-08-10T16:06:17.060537Z","steps":["trace[1775373155] 'read index received' (duration: 227.742249ms)","trace[1775373155] 'applied index is now lower than readState.Index' (duration: 87.883µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T16:06:17.060669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.948477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T16:06:17.060689Z","caller":"traceutil/trace.go:171","msg":"trace[791478109] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:6604; }","duration":"228.026688ms","start":"2026-08-10T16:06:16.832655Z","end":"2026-08-10T16:06:17.060681Z","steps":["trace[791478109] 'agreement among raft nodes before linearized reading' (duration: 227.963517ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:06:17.060674Z","caller":"traceutil/trace.go:171","msg":"trace[1403879380] transaction","detail":"{read_only:false; response_revision:6604; number_of_response:1; }","duration":"303.640434ms","start":"2026-08-10T16:06:16.756967Z","end":"2026-08-10T16:06:17.060607Z","steps":["trace[1403879380] 'process raft request' (duration: 303.453487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T16:06:17.060917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T16:06:16.756946Z","time spent":"303.84914ms","remote":"127.0.0.1:44838","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-08-10T16:06:24.772259Z","caller":"traceutil/trace.go:171","msg":"trace[787160427] transaction","detail":"{read_only:false; response_revision:6649; number_of_response:1; }","duration":"114.806021ms","start":"2026-08-10T16:06:24.657426Z","end":"2026-08-10T16:06:24.772232Z","steps":["trace[787160427] 'process raft request' (duration: 114.607545ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T16:08:42.329606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5548} {"level":"info","ts":"2026-08-10T16:08:42.452851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5548,"took":"122.094567ms","hash":2145123887,"current-db-size-bytes":54763520,"current-db-size":"55 MB","current-db-size-in-use-bytes":18432000,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-10T16:08:42.452915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2145123887,"revision":5548,"compact-revision":2902}