{"level":"warn","ts":"2026-05-25T00:37:35.689904Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T00:37:35.690023Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-05-25T00:37:35.690188Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T00:37:35.690208Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-25T00:37:35.69023Z","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-05-25T00:37:35.692096Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-05-25T00:37:35.692857Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-05-25T00:37:35.696246Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.032192ms"} {"level":"info","ts":"2026-05-25T00:37:35.70562Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-05-25T00:37:35.70576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T00:37:35.705795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-05-25T00:37:35.705817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T00:37:35.70584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-05-25T00:37:35.705898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-05-25T00:37:35.713411Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T00:37:35.714761Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T00:37:35.715854Z","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-05-25T00:37:35.718182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T00:37:35.718701Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T00:37:35.718831Z","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-05-25T00:37:35.718885Z","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-05-25T00:37:35.718896Z","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-05-25T00:37:35.724325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-05-25T00:37:35.724499Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-25T00:37:35.725723Z","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-05-25T00:37:35.725898Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-25T00:37:35.725946Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-25T00:37:35.726019Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T00:37:35.726053Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T00:37:36.706591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T00:37:36.706669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T00:37:36.706711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-05-25T00:37:36.706734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-05-25T00:37:36.706745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-25T00:37:36.706764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-05-25T00:37:36.706801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-25T00:37:36.707907Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:37:36.708984Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T00:37:36.708993Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T00:37:36.709085Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T00:37:36.709413Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T00:37:36.709516Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T00:37:36.709582Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:37:36.709706Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:37:36.709745Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:37:36.713864Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T00:37:36.714357Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-05-25T00:37:38.238071Z","caller":"traceutil/trace.go:171","msg":"trace[2022934051] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"185.830261ms","start":"2026-05-25T00:37:38.052229Z","end":"2026-05-25T00:37:38.238059Z","steps":["trace[2022934051] 'process raft request' (duration: 185.702897ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:38.238306Z","caller":"traceutil/trace.go:171","msg":"trace[840177997] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"187.036255ms","start":"2026-05-25T00:37:38.051229Z","end":"2026-05-25T00:37:38.238265Z","steps":["trace[840177997] 'process raft request' (duration: 115.465166ms)","trace[840177997] 'compare' (duration: 71.047524ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:37:38.238463Z","caller":"traceutil/trace.go:171","msg":"trace[1681249011] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"186.85677ms","start":"2026-05-25T00:37:38.051594Z","end":"2026-05-25T00:37:38.238451Z","steps":["trace[1681249011] 'process raft request' (duration: 186.286104ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:38.238501Z","caller":"traceutil/trace.go:171","msg":"trace[1801681054] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"183.888878ms","start":"2026-05-25T00:37:38.054535Z","end":"2026-05-25T00:37:38.238424Z","steps":["trace[1801681054] 'process raft request' (duration: 183.418685ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:38.238008Z","caller":"traceutil/trace.go:171","msg":"trace[343321506] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:17; }","duration":"172.237688ms","start":"2026-05-25T00:37:38.065749Z","end":"2026-05-25T00:37:38.237987Z","steps":["trace[343321506] 'read index received' (duration: 101.075191ms)","trace[343321506] 'applied index is now lower than readState.Index' (duration: 71.161667ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:37:38.23875Z","caller":"traceutil/trace.go:171","msg":"trace[1779904114] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"186.814779ms","start":"2026-05-25T00:37:38.051899Z","end":"2026-05-25T00:37:38.238713Z","steps":["trace[1779904114] 'process raft request' (duration: 186.012927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.238908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.130822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-25T00:37:38.239002Z","caller":"traceutil/trace.go:171","msg":"trace[1360360309] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"173.256065ms","start":"2026-05-25T00:37:38.065729Z","end":"2026-05-25T00:37:38.238985Z","steps":["trace[1360360309] 'agreement among raft nodes before linearized reading' (duration: 173.0733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.889475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.451213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-25T00:37:38.889924Z","caller":"traceutil/trace.go:171","msg":"trace[1702309661] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"821.873096ms","start":"2026-05-25T00:37:38.068033Z","end":"2026-05-25T00:37:38.889906Z","steps":["trace[1702309661] 'process raft request' (duration: 343.364814ms)","trace[1702309661] 'compare' (duration: 477.218066ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:37:38.890055Z","caller":"traceutil/trace.go:171","msg":"trace[1551385150] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"821.797724ms","start":"2026-05-25T00:37:38.068247Z","end":"2026-05-25T00:37:38.890045Z","steps":["trace[1551385150] 'process raft request' (duration: 821.488575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.890099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.068239Z","time spent":"821.837365ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T00:37:38.890304Z","caller":"traceutil/trace.go:171","msg":"trace[128286888] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"800.404955ms","start":"2026-05-25T00:37:38.089879Z","end":"2026-05-25T00:37:38.890284Z","steps":["trace[128286888] 'process raft request' (duration: 799.966153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.890421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.089865Z","time spent":"800.500527ms","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T00:37:38.890458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.068017Z","time spent":"821.941067ms","remote":"127.0.0.1:33448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T00:37:38.890572Z","caller":"traceutil/trace.go:171","msg":"trace[1511247511] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"780.457626ms","start":"2026-05-25T00:37:38.110048Z","end":"2026-05-25T00:37:38.890506Z","steps":["trace[1511247511] 'process raft request' (duration: 779.990913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.890792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.110031Z","time spent":"780.665863ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T00:37:38.891711Z","caller":"traceutil/trace.go:171","msg":"trace[417205689] linearizableReadLoop","detail":"{readStateIndex:28; appliedIndex:22; }","duration":"652.929278ms","start":"2026-05-25T00:37:38.238772Z","end":"2026-05-25T00:37:38.891702Z","steps":["trace[417205689] 'read index received' (duration: 172.646558ms)","trace[417205689] 'applied index is now lower than readState.Index' (duration: 480.28127ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:37:38.891777Z","caller":"traceutil/trace.go:171","msg":"trace[652786833] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"779.691945ms","start":"2026-05-25T00:37:38.112079Z","end":"2026-05-25T00:37:38.891771Z","steps":["trace[652786833] 'process raft request' (duration: 778.225565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.891877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.112061Z","time spent":"779.796459ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T00:37:38.891981Z","caller":"traceutil/trace.go:171","msg":"trace[699290021] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"648.99716ms","start":"2026-05-25T00:37:38.242965Z","end":"2026-05-25T00:37:38.891962Z","steps":["trace[699290021] 'process raft request' (duration: 648.549188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.892207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.054333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-j4f67\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-25T00:37:38.892236Z","caller":"traceutil/trace.go:171","msg":"trace[1025694915] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-j4f67; range_end:; response_count:1; response_revision:29; }","duration":"825.119355ms","start":"2026-05-25T00:37:38.067109Z","end":"2026-05-25T00:37:38.892229Z","steps":["trace[1025694915] 'agreement among raft nodes before linearized reading' (duration: 824.94917ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:38.892225Z","caller":"traceutil/trace.go:171","msg":"trace[609186147] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"648.687343ms","start":"2026-05-25T00:37:38.243503Z","end":"2026-05-25T00:37:38.89219Z","steps":["trace[609186147] 'process raft request' (duration: 648.087386ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:38.892271Z","caller":"traceutil/trace.go:171","msg":"trace[414058201] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"649.293569ms","start":"2026-05-25T00:37:38.24293Z","end":"2026-05-25T00:37:38.892224Z","steps":["trace[414058201] 'process raft request' (duration: 648.537858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.892181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.242947Z","time spent":"649.190786ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T00:37:38.892421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.133882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-25T00:37:38.892417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.243493Z","time spent":"648.825486ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T00:37:38.892449Z","caller":"traceutil/trace.go:171","msg":"trace[179353501] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:29; }","duration":"582.190664ms","start":"2026-05-25T00:37:38.310251Z","end":"2026-05-25T00:37:38.892442Z","steps":["trace[179353501] 'agreement among raft nodes before linearized reading' (duration: 582.156143ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:38.892449Z","caller":"traceutil/trace.go:171","msg":"trace[1897859870] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"649.408522ms","start":"2026-05-25T00:37:38.243028Z","end":"2026-05-25T00:37:38.892436Z","steps":["trace[1897859870] 'process raft request' (duration: 648.521678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.892484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.565631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-25T00:37:38.892559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.310238Z","time spent":"582.261225ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-25T00:37:38.89256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.243019Z","time spent":"649.460744ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T00:37:38.892588Z","caller":"traceutil/trace.go:171","msg":"trace[1369749061] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:29; }","duration":"154.688925ms","start":"2026-05-25T00:37:38.737865Z","end":"2026-05-25T00:37:38.892554Z","steps":["trace[1369749061] 'agreement among raft nodes before linearized reading' (duration: 154.53443ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:38.892607Z","caller":"traceutil/trace.go:171","msg":"trace[1311263091] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"643.391037ms","start":"2026-05-25T00:37:38.249165Z","end":"2026-05-25T00:37:38.892556Z","steps":["trace[1311263091] 'process raft request' (duration: 642.482282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.892392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.190011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T00:37:38.892735Z","caller":"traceutil/trace.go:171","msg":"trace[576681869] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:29; }","duration":"647.543141ms","start":"2026-05-25T00:37:38.24517Z","end":"2026-05-25T00:37:38.892713Z","steps":["trace[576681869] 'agreement among raft nodes before linearized reading' (duration: 647.193682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.892838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.245159Z","time spent":"647.658904ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"info","ts":"2026-05-25T00:37:38.892043Z","caller":"traceutil/trace.go:171","msg":"trace[1566546505] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"779.699065ms","start":"2026-05-25T00:37:38.112337Z","end":"2026-05-25T00:37:38.892036Z","steps":["trace[1566546505] 'process raft request' (duration: 779.044537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.89238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.242905Z","time spent":"649.425003ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T00:37:38.892985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.057451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-25T00:37:38.892256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.067096Z","time spent":"825.154576ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-j4f67\" "} {"level":"warn","ts":"2026-05-25T00:37:38.893178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.249155Z","time spent":"643.568981ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T00:37:38.893177Z","caller":"traceutil/trace.go:171","msg":"trace[2132553685] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:29; }","duration":"826.241556ms","start":"2026-05-25T00:37:38.0669Z","end":"2026-05-25T00:37:38.893142Z","steps":["trace[2132553685] 'agreement among raft nodes before linearized reading' (duration: 826.047231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:38.893259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.066894Z","time spent":"826.338188ms","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-05-25T00:37:38.893396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:38.112326Z","time spent":"780.804657ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T00:37:39.05601Z","caller":"traceutil/trace.go:171","msg":"trace[1498940788] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"160.658807ms","start":"2026-05-25T00:37:38.89531Z","end":"2026-05-25T00:37:39.055969Z","steps":["trace[1498940788] 'process raft request' (duration: 160.550344ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:39.056049Z","caller":"traceutil/trace.go:171","msg":"trace[1660610992] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"157.999705ms","start":"2026-05-25T00:37:38.898022Z","end":"2026-05-25T00:37:39.056021Z","steps":["trace[1660610992] 'process raft request' (duration: 157.898102ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:39.056062Z","caller":"traceutil/trace.go:171","msg":"trace[926420420] linearizableReadLoop","detail":"{readStateIndex:36; appliedIndex:33; }","duration":"158.957661ms","start":"2026-05-25T00:37:38.897082Z","end":"2026-05-25T00:37:39.05604Z","steps":["trace[926420420] 'read index received' (duration: 137.076675ms)","trace[926420420] 'applied index is now lower than readState.Index' (duration: 21.880256ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:37:39.056072Z","caller":"traceutil/trace.go:171","msg":"trace[337180589] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"161.140741ms","start":"2026-05-25T00:37:38.89488Z","end":"2026-05-25T00:37:39.056021Z","steps":["trace[337180589] 'process raft request' (duration: 139.265445ms)","trace[337180589] 'compare' (duration: 21.583838ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:37:39.056303Z","caller":"traceutil/trace.go:171","msg":"trace[1843743355] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"160.168224ms","start":"2026-05-25T00:37:38.896123Z","end":"2026-05-25T00:37:39.056291Z","steps":["trace[1843743355] 'process raft request' (duration: 159.770603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:39.056432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.254022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-25T00:37:39.05648Z","caller":"traceutil/trace.go:171","msg":"trace[832347871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:37; }","duration":"158.356645ms","start":"2026-05-25T00:37:38.898116Z","end":"2026-05-25T00:37:39.056473Z","steps":["trace[832347871] 'agreement among raft nodes before linearized reading' (duration: 158.229832ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:39.056324Z","caller":"traceutil/trace.go:171","msg":"trace[1924265346] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"155.99742ms","start":"2026-05-25T00:37:38.900317Z","end":"2026-05-25T00:37:39.056314Z","steps":["trace[1924265346] 'process raft request' (duration: 155.63356ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:39.056626Z","caller":"traceutil/trace.go:171","msg":"trace[968348324] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"155.733253ms","start":"2026-05-25T00:37:38.900862Z","end":"2026-05-25T00:37:39.056595Z","steps":["trace[968348324] 'process raft request' (duration: 155.153497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:39.056594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.378583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-05-25T00:37:39.056734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.718322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T00:37:39.056788Z","caller":"traceutil/trace.go:171","msg":"trace[1273941908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:37; }","duration":"155.848826ms","start":"2026-05-25T00:37:38.900927Z","end":"2026-05-25T00:37:39.056775Z","steps":["trace[1273941908] 'agreement among raft nodes before linearized reading' (duration: 155.667621ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:39.05675Z","caller":"traceutil/trace.go:171","msg":"trace[646002054] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:37; }","duration":"159.673901ms","start":"2026-05-25T00:37:38.897054Z","end":"2026-05-25T00:37:39.056728Z","steps":["trace[646002054] 'agreement among raft nodes before linearized reading' (duration: 159.26011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:39.056856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.95482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T00:37:39.056921Z","caller":"traceutil/trace.go:171","msg":"trace[243083557] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:37; }","duration":"120.099693ms","start":"2026-05-25T00:37:38.936807Z","end":"2026-05-25T00:37:39.056907Z","steps":["trace[243083557] 'agreement among raft nodes before linearized reading' (duration: 119.97404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:39.056819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.782744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T00:37:39.056549Z","caller":"traceutil/trace.go:171","msg":"trace[1219244012] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"155.772304ms","start":"2026-05-25T00:37:38.900747Z","end":"2026-05-25T00:37:39.05652Z","steps":["trace[1219244012] 'process raft request' (duration: 155.250149ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:39.057052Z","caller":"traceutil/trace.go:171","msg":"trace[1066696058] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:37; }","duration":"120.132404ms","start":"2026-05-25T00:37:38.936867Z","end":"2026-05-25T00:37:39.057Z","steps":["trace[1066696058] 'agreement among raft nodes before linearized reading' (duration: 119.722263ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:39.056747Z","caller":"traceutil/trace.go:171","msg":"trace[1273977382] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"156.023821ms","start":"2026-05-25T00:37:38.900617Z","end":"2026-05-25T00:37:39.056641Z","steps":["trace[1273977382] 'process raft request' (duration: 155.358922ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:42.687385Z","caller":"traceutil/trace.go:171","msg":"trace[2125431484] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"294.974268ms","start":"2026-05-25T00:37:42.392379Z","end":"2026-05-25T00:37:42.687353Z","steps":["trace[2125431484] 'process raft request' (duration: 212.333288ms)","trace[2125431484] 'compare' (duration: 82.525797ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:37:42.717174Z","caller":"traceutil/trace.go:171","msg":"trace[1142195541] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"323.38506ms","start":"2026-05-25T00:37:42.393752Z","end":"2026-05-25T00:37:42.717138Z","steps":["trace[1142195541] 'process raft request' (duration: 323.279637ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:42.717255Z","caller":"traceutil/trace.go:171","msg":"trace[1782637370] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"323.253376ms","start":"2026-05-25T00:37:42.393995Z","end":"2026-05-25T00:37:42.717248Z","steps":["trace[1782637370] 'process raft request' (duration: 323.091981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:42.717327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:42.393988Z","time spent":"323.287486ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T00:37:42.717335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:42.393737Z","time spent":"323.528994ms","remote":"127.0.0.1:33136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T00:37:42.717412Z","caller":"traceutil/trace.go:171","msg":"trace[1435606328] transaction","detail":"{read_only:false; number_of_response:0; response_revision:292; }","duration":"279.239675ms","start":"2026-05-25T00:37:42.438122Z","end":"2026-05-25T00:37:42.717362Z","steps":["trace[1435606328] 'process raft request' (duration: 279.023149ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:42.717444Z","caller":"traceutil/trace.go:171","msg":"trace[1327925734] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"323.291497ms","start":"2026-05-25T00:37:42.394095Z","end":"2026-05-25T00:37:42.717386Z","steps":["trace[1327925734] 'process raft request' (duration: 323.025679ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:42.717495Z","caller":"traceutil/trace.go:171","msg":"trace[534662363] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:294; }","duration":"323.678678ms","start":"2026-05-25T00:37:42.393798Z","end":"2026-05-25T00:37:42.717477Z","steps":["trace[534662363] 'read index received' (duration: 211.035131ms)","trace[534662363] 'applied index is now lower than readState.Index' (duration: 112.642697ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:37:42.717221Z","caller":"traceutil/trace.go:171","msg":"trace[1084522861] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"264.017736ms","start":"2026-05-25T00:37:42.453191Z","end":"2026-05-25T00:37:42.717209Z","steps":["trace[1084522861] 'process raft request' (duration: 263.973544ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:42.717582Z","caller":"traceutil/trace.go:171","msg":"trace[1059758143] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"264.273373ms","start":"2026-05-25T00:37:42.453296Z","end":"2026-05-25T00:37:42.717569Z","steps":["trace[1059758143] 'process raft request' (duration: 263.894552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:42.717638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:42.394088Z","time spent":"323.451742ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T00:37:42.717669Z","caller":"traceutil/trace.go:171","msg":"trace[926050059] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"261.573956ms","start":"2026-05-25T00:37:42.456086Z","end":"2026-05-25T00:37:42.71766Z","steps":["trace[926050059] 'process raft request' (duration: 261.33635ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:42.717674Z","caller":"traceutil/trace.go:171","msg":"trace[1459826987] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"262.580005ms","start":"2026-05-25T00:37:42.455063Z","end":"2026-05-25T00:37:42.717643Z","steps":["trace[1459826987] 'process raft request' (duration: 262.330028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:42.717786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.971146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-25T00:37:42.717819Z","caller":"traceutil/trace.go:171","msg":"trace[425097354] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:297; }","duration":"324.038699ms","start":"2026-05-25T00:37:42.39377Z","end":"2026-05-25T00:37:42.717808Z","steps":["trace[425097354] 'agreement among raft nodes before linearized reading' (duration: 323.954266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:42.717848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:42.393758Z","time spent":"324.080749ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-25T00:37:42.71786Z","caller":"traceutil/trace.go:171","msg":"trace[1755486720] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"263.80588ms","start":"2026-05-25T00:37:42.454039Z","end":"2026-05-25T00:37:42.717845Z","steps":["trace[1755486720] 'process raft request' (duration: 263.289365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:42.751546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.741999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-25T00:37:42.75165Z","caller":"traceutil/trace.go:171","msg":"trace[1617876315] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:298; }","duration":"351.861473ms","start":"2026-05-25T00:37:42.399717Z","end":"2026-05-25T00:37:42.751578Z","steps":["trace[1617876315] 'agreement among raft nodes before linearized reading' (duration: 351.706569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:37:42.751677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:37:42.399709Z","time spent":"351.961466ms","remote":"127.0.0.1:33166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-25T00:37:46.915846Z","caller":"traceutil/trace.go:171","msg":"trace[992082756] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"153.320888ms","start":"2026-05-25T00:37:46.7625Z","end":"2026-05-25T00:37:46.915821Z","steps":["trace[992082756] 'process raft request' (duration: 153.134003ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:46.915801Z","caller":"traceutil/trace.go:171","msg":"trace[492652954] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"154.599425ms","start":"2026-05-25T00:37:46.761185Z","end":"2026-05-25T00:37:46.915785Z","steps":["trace[492652954] 'process raft request' (duration: 154.391839ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:46.915949Z","caller":"traceutil/trace.go:171","msg":"trace[445068735] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"149.131947ms","start":"2026-05-25T00:37:46.766796Z","end":"2026-05-25T00:37:46.915928Z","steps":["trace[445068735] 'process raft request' (duration: 148.980253ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:37:46.915745Z","caller":"traceutil/trace.go:171","msg":"trace[833075006] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"155.062579ms","start":"2026-05-25T00:37:46.760639Z","end":"2026-05-25T00:37:46.915702Z","steps":["trace[833075006] 'process raft request' (duration: 95.937125ms)","trace[833075006] 'compare' (duration: 58.852996ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:37:46.918379Z","caller":"traceutil/trace.go:171","msg":"trace[1530983448] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"115.079496ms","start":"2026-05-25T00:37:46.803268Z","end":"2026-05-25T00:37:46.918347Z","steps":["trace[1530983448] 'process raft request' (duration: 114.86042ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:15.950926Z","caller":"traceutil/trace.go:171","msg":"trace[664984796] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"127.041748ms","start":"2026-05-25T00:38:15.823846Z","end":"2026-05-25T00:38:15.950888Z","steps":["trace[664984796] 'process raft request' (duration: 126.244843ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:15.959121Z","caller":"traceutil/trace.go:171","msg":"trace[54842390] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"134.929247ms","start":"2026-05-25T00:38:15.824144Z","end":"2026-05-25T00:38:15.959073Z","steps":["trace[54842390] 'process raft request' (duration: 134.70569ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:15.959333Z","caller":"traceutil/trace.go:171","msg":"trace[827856435] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"133.43096ms","start":"2026-05-25T00:38:15.825888Z","end":"2026-05-25T00:38:15.959319Z","steps":["trace[827856435] 'process raft request' (duration: 133.142971ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:15.959415Z","caller":"traceutil/trace.go:171","msg":"trace[945351783] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"135.190765ms","start":"2026-05-25T00:38:15.824214Z","end":"2026-05-25T00:38:15.959405Z","steps":["trace[945351783] 'process raft request' (duration: 134.762631ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:15.95944Z","caller":"traceutil/trace.go:171","msg":"trace[1396588872] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"120.275205ms","start":"2026-05-25T00:38:15.839098Z","end":"2026-05-25T00:38:15.959373Z","steps":["trace[1396588872] 'process raft request' (duration: 120.13594ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:15.959505Z","caller":"traceutil/trace.go:171","msg":"trace[1573449445] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"131.746897ms","start":"2026-05-25T00:38:15.82775Z","end":"2026-05-25T00:38:15.959497Z","steps":["trace[1573449445] 'process raft request' (duration: 131.335834ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:15.959606Z","caller":"traceutil/trace.go:171","msg":"trace[1739519545] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"124.278982ms","start":"2026-05-25T00:38:15.835307Z","end":"2026-05-25T00:38:15.959586Z","steps":["trace[1739519545] 'process raft request' (duration: 123.829247ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:18.619174Z","caller":"traceutil/trace.go:171","msg":"trace[2112975120] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"108.252234ms","start":"2026-05-25T00:38:18.510876Z","end":"2026-05-25T00:38:18.619128Z","steps":["trace[2112975120] 'process raft request' (duration: 108.057577ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:18.798014Z","caller":"traceutil/trace.go:171","msg":"trace[1038648822] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"174.37776ms","start":"2026-05-25T00:38:18.623613Z","end":"2026-05-25T00:38:18.797991Z","steps":["trace[1038648822] 'process raft request' (duration: 174.249466ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:18.799117Z","caller":"traceutil/trace.go:171","msg":"trace[1513630661] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"175.342951ms","start":"2026-05-25T00:38:18.623759Z","end":"2026-05-25T00:38:18.799102Z","steps":["trace[1513630661] 'process raft request' (duration: 175.266779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:38:23.207834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.638068ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:38:23.208032Z","caller":"traceutil/trace.go:171","msg":"trace[1635938286] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:642; }","duration":"653.871625ms","start":"2026-05-25T00:38:22.554133Z","end":"2026-05-25T00:38:23.208005Z","steps":["trace[1635938286] 'range keys from in-memory index tree' (duration: 653.616627ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:23.208752Z","caller":"traceutil/trace.go:171","msg":"trace[2082073067] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"101.798803ms","start":"2026-05-25T00:38:23.106903Z","end":"2026-05-25T00:38:23.208702Z","steps":["trace[2082073067] 'read index received' (duration: 85.034647ms)","trace[2082073067] 'applied index is now lower than readState.Index' (duration: 16.762096ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:38:23.208897Z","caller":"traceutil/trace.go:171","msg":"trace[1861538304] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"655.548769ms","start":"2026-05-25T00:38:22.553309Z","end":"2026-05-25T00:38:23.208858Z","steps":["trace[1861538304] 'process raft request' (duration: 638.706411ms)","trace[1861538304] 'compare' (duration: 15.580088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:38:23.209045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:38:22.553286Z","time spent":"655.696803ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T00:38:23.209232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.295829ms","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-05-25T00:38:23.209479Z","caller":"traceutil/trace.go:171","msg":"trace[217768088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"102.581848ms","start":"2026-05-25T00:38:23.10688Z","end":"2026-05-25T00:38:23.209461Z","steps":["trace[217768088] 'agreement among raft nodes before linearized reading' (duration: 102.032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:38:23.209233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.226277ms","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-05-25T00:38:23.20959Z","caller":"traceutil/trace.go:171","msg":"trace[335036745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"102.66851ms","start":"2026-05-25T00:38:23.106909Z","end":"2026-05-25T00:38:23.209578Z","steps":["trace[335036745] 'agreement among raft nodes before linearized reading' (duration: 102.03596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:38:38.669403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.089988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6btgt\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-25T00:38:38.669451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.705982ms","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-05-25T00:38:38.669492Z","caller":"traceutil/trace.go:171","msg":"trace[1673440647] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6btgt; range_end:; response_count:1; response_revision:803; }","duration":"102.241642ms","start":"2026-05-25T00:38:38.567232Z","end":"2026-05-25T00:38:38.669474Z","steps":["trace[1673440647] 'range keys from in-memory index tree' (duration: 101.907671ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:38:38.669551Z","caller":"traceutil/trace.go:171","msg":"trace[1576379712] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"144.861137ms","start":"2026-05-25T00:38:38.524671Z","end":"2026-05-25T00:38:38.669533Z","steps":["trace[1576379712] 'range keys from in-memory index tree' (duration: 144.499415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:06.403588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.969878ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T00:40:06.403685Z","caller":"traceutil/trace.go:171","msg":"trace[1887590672] linearizableReadLoop","detail":"{readStateIndex:1983; appliedIndex:1982; }","duration":"508.552553ms","start":"2026-05-25T00:40:05.895116Z","end":"2026-05-25T00:40:06.403668Z","steps":["trace[1887590672] 'read index received' (duration: 309.496562ms)","trace[1887590672] 'applied index is now lower than readState.Index' (duration: 199.054721ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:40:06.403873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.753759ms","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-05-25T00:40:06.403908Z","caller":"traceutil/trace.go:171","msg":"trace[1845649632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1908; }","duration":"508.855421ms","start":"2026-05-25T00:40:05.895044Z","end":"2026-05-25T00:40:06.403899Z","steps":["trace[1845649632] 'agreement among raft nodes before linearized reading' (duration: 508.698857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:06.40394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:40:05.895027Z","time spent":"508.904953ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T00:40:06.439793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.778092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:40:06.439857Z","caller":"traceutil/trace.go:171","msg":"trace[1204674248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1909; }","duration":"206.877115ms","start":"2026-05-25T00:40:06.232967Z","end":"2026-05-25T00:40:06.439844Z","steps":["trace[1204674248] 'agreement among raft nodes before linearized reading' (duration: 206.741731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:06.439989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.057037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T00:40:06.440039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.787919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3272f528-462a-476e-8144-cbc54584f80b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-25T00:40:06.440078Z","caller":"traceutil/trace.go:171","msg":"trace[59539502] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1909; }","duration":"387.224092ms","start":"2026-05-25T00:40:06.052835Z","end":"2026-05-25T00:40:06.440059Z","steps":["trace[59539502] 'agreement among raft nodes before linearized reading' (duration: 387.048187ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:06.440068Z","caller":"traceutil/trace.go:171","msg":"trace[2080759676] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"510.257252ms","start":"2026-05-25T00:40:05.929795Z","end":"2026-05-25T00:40:06.440053Z","steps":["trace[2080759676] 'process raft request' (duration: 509.706427ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:06.440118Z","caller":"traceutil/trace.go:171","msg":"trace[1802004194] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3272f528-462a-476e-8144-cbc54584f80b; range_end:; response_count:1; response_revision:1909; }","duration":"475.918683ms","start":"2026-05-25T00:40:05.964181Z","end":"2026-05-25T00:40:06.440099Z","steps":["trace[1802004194] 'agreement among raft nodes before linearized reading' (duration: 475.645196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:06.440164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:40:05.964162Z","time spent":"475.991375ms","remote":"127.0.0.1:33226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3272f528-462a-476e-8144-cbc54584f80b\" "} {"level":"warn","ts":"2026-05-25T00:40:06.440128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:40:06.052814Z","time spent":"387.302614ms","remote":"127.0.0.1:34474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-25T00:40:06.440193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:40:05.929768Z","time spent":"510.354115ms","remote":"127.0.0.1:33288","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-05-25T00:40:06.44016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.410423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-25T00:40:06.440263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.967126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3272f528-462a-476e-8144-cbc54584f80b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-25T00:40:06.440271Z","caller":"traceutil/trace.go:171","msg":"trace[2004423788] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1909; }","duration":"538.549386ms","start":"2026-05-25T00:40:05.901698Z","end":"2026-05-25T00:40:06.440247Z","steps":["trace[2004423788] 'agreement among raft nodes before linearized reading' (duration: 538.258188ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:06.440301Z","caller":"traceutil/trace.go:171","msg":"trace[481965048] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3272f528-462a-476e-8144-cbc54584f80b; range_end:; response_count:1; response_revision:1909; }","duration":"110.036438ms","start":"2026-05-25T00:40:06.330253Z","end":"2026-05-25T00:40:06.44029Z","steps":["trace[481965048] 'agreement among raft nodes before linearized reading' (duration: 109.916555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:06.440318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:40:05.901688Z","time spent":"538.619649ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-25T00:40:06.440826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.106632ms","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-05-25T00:40:06.440879Z","caller":"traceutil/trace.go:171","msg":"trace[430042516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1909; }","duration":"543.192924ms","start":"2026-05-25T00:40:05.89767Z","end":"2026-05-25T00:40:06.440863Z","steps":["trace[430042516] 'agreement among raft nodes before linearized reading' (duration: 542.019012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:06.440908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:40:05.89765Z","time spent":"543.250995ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T00:40:06.556045Z","caller":"traceutil/trace.go:171","msg":"trace[2040236339] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"147.457665ms","start":"2026-05-25T00:40:06.408557Z","end":"2026-05-25T00:40:06.556015Z","steps":["trace[2040236339] 'process raft request' (duration: 100.922328ms)","trace[2040236339] 'compare' (duration: 46.088404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:40:06.556238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.340336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:40:06.556298Z","caller":"traceutil/trace.go:171","msg":"trace[165566472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1910; }","duration":"114.449849ms","start":"2026-05-25T00:40:06.441837Z","end":"2026-05-25T00:40:06.556287Z","steps":["trace[165566472] 'agreement among raft nodes before linearized reading' (duration: 114.339206ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:06.556143Z","caller":"traceutil/trace.go:171","msg":"trace[1357670401] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1984; }","duration":"113.939765ms","start":"2026-05-25T00:40:06.441882Z","end":"2026-05-25T00:40:06.555822Z","steps":["trace[1357670401] 'read index received' (duration: 67.761569ms)","trace[1357670401] 'applied index is now lower than readState.Index' (duration: 46.177036ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:40:06.592198Z","caller":"traceutil/trace.go:171","msg":"trace[1414252193] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"144.691059ms","start":"2026-05-25T00:40:06.447485Z","end":"2026-05-25T00:40:06.592176Z","steps":["trace[1414252193] 'process raft request' (duration: 144.639397ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:06.592239Z","caller":"traceutil/trace.go:171","msg":"trace[675291280] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"146.709465ms","start":"2026-05-25T00:40:06.445476Z","end":"2026-05-25T00:40:06.592186Z","steps":["trace[675291280] 'process raft request' (duration: 146.608502ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:06.592444Z","caller":"traceutil/trace.go:171","msg":"trace[771127170] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"147.398963ms","start":"2026-05-25T00:40:06.445026Z","end":"2026-05-25T00:40:06.592425Z","steps":["trace[771127170] 'process raft request' (duration: 146.92121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:40.047817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.9957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:40:40.047905Z","caller":"traceutil/trace.go:171","msg":"trace[880710968] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2147; }","duration":"130.215906ms","start":"2026-05-25T00:40:39.91767Z","end":"2026-05-25T00:40:40.047886Z","steps":["trace[880710968] 'count revisions from in-memory index tree' (duration: 129.831875ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:40.048469Z","caller":"traceutil/trace.go:171","msg":"trace[896615584] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"117.762294ms","start":"2026-05-25T00:40:39.930682Z","end":"2026-05-25T00:40:40.048444Z","steps":["trace[896615584] 'process raft request' (duration: 60.19948ms)","trace[896615584] 'compare' (duration: 56.747511ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:40:40.822985Z","caller":"traceutil/trace.go:171","msg":"trace[1037675198] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"181.157841ms","start":"2026-05-25T00:40:40.641803Z","end":"2026-05-25T00:40:40.822961Z","steps":["trace[1037675198] 'process raft request' (duration: 180.683297ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:40.875581Z","caller":"traceutil/trace.go:171","msg":"trace[1465520869] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"222.162783ms","start":"2026-05-25T00:40:40.653333Z","end":"2026-05-25T00:40:40.875496Z","steps":["trace[1465520869] 'process raft request' (duration: 221.965108ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:40.875685Z","caller":"traceutil/trace.go:171","msg":"trace[1771615852] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2232; }","duration":"212.464922ms","start":"2026-05-25T00:40:40.663197Z","end":"2026-05-25T00:40:40.875662Z","steps":["trace[1771615852] 'read index received' (duration: 159.672166ms)","trace[1771615852] 'applied index is now lower than readState.Index' (duration: 52.790716ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:40:40.875859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.635817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T00:40:40.875926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.695533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-25T00:40:40.875954Z","caller":"traceutil/trace.go:171","msg":"trace[1094840029] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2152; }","duration":"212.790181ms","start":"2026-05-25T00:40:40.663142Z","end":"2026-05-25T00:40:40.875932Z","steps":["trace[1094840029] 'agreement among raft nodes before linearized reading' (duration: 212.616676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:40.87596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.091067ms","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-05-25T00:40:40.875982Z","caller":"traceutil/trace.go:171","msg":"trace[1083025410] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2152; }","duration":"196.790846ms","start":"2026-05-25T00:40:40.679171Z","end":"2026-05-25T00:40:40.875962Z","steps":["trace[1083025410] 'agreement among raft nodes before linearized reading' (duration: 196.618221ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:40.876031Z","caller":"traceutil/trace.go:171","msg":"trace[452177064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2152; }","duration":"128.198799ms","start":"2026-05-25T00:40:40.747814Z","end":"2026-05-25T00:40:40.876013Z","steps":["trace[452177064] 'agreement among raft nodes before linearized reading' (duration: 128.011774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:40.876005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.757276ms","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-05-25T00:40:40.876115Z","caller":"traceutil/trace.go:171","msg":"trace[804703762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2152; }","duration":"162.943391ms","start":"2026-05-25T00:40:40.713152Z","end":"2026-05-25T00:40:40.876095Z","steps":["trace[804703762] 'agreement among raft nodes before linearized reading' (duration: 162.684133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:40.876472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.683542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-25T00:40:40.876528Z","caller":"traceutil/trace.go:171","msg":"trace[1269326440] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2152; }","duration":"119.748504ms","start":"2026-05-25T00:40:40.756756Z","end":"2026-05-25T00:40:40.876505Z","steps":["trace[1269326440] 'agreement among raft nodes before linearized reading' (duration: 119.435355ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:41.096635Z","caller":"traceutil/trace.go:171","msg":"trace[508262567] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"215.956337ms","start":"2026-05-25T00:40:40.880647Z","end":"2026-05-25T00:40:41.096603Z","steps":["trace[508262567] 'process raft request' (duration: 215.617487ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:41.096738Z","caller":"traceutil/trace.go:171","msg":"trace[1005166805] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"214.601027ms","start":"2026-05-25T00:40:40.882125Z","end":"2026-05-25T00:40:41.096726Z","steps":["trace[1005166805] 'process raft request' (duration: 214.428462ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:40:41.096635Z","caller":"traceutil/trace.go:171","msg":"trace[1515437638] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"215.189105ms","start":"2026-05-25T00:40:40.881412Z","end":"2026-05-25T00:40:41.096601Z","steps":["trace[1515437638] 'process raft request' (duration: 215.084142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:40:41.408198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.337168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:40:41.40844Z","caller":"traceutil/trace.go:171","msg":"trace[673518171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2157; }","duration":"175.607605ms","start":"2026-05-25T00:40:41.232779Z","end":"2026-05-25T00:40:41.408386Z","steps":["trace[673518171] 'range keys from in-memory index tree' (duration: 175.208864ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:41:45.722441Z","caller":"traceutil/trace.go:171","msg":"trace[555521705] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"120.61137ms","start":"2026-05-25T00:41:45.601805Z","end":"2026-05-25T00:41:45.722416Z","steps":["trace[555521705] 'process raft request' (duration: 120.464806ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:42:18.374377Z","caller":"traceutil/trace.go:171","msg":"trace[1847094736] linearizableReadLoop","detail":"{readStateIndex:2892; appliedIndex:2891; }","duration":"140.348404ms","start":"2026-05-25T00:42:18.234003Z","end":"2026-05-25T00:42:18.374351Z","steps":["trace[1847094736] 'read index received' (duration: 140.141707ms)","trace[1847094736] 'applied index is now lower than readState.Index' (duration: 200.276µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:42:18.374498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.487727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:42:18.374534Z","caller":"traceutil/trace.go:171","msg":"trace[1385020320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2787; }","duration":"140.586151ms","start":"2026-05-25T00:42:18.233938Z","end":"2026-05-25T00:42:18.374524Z","steps":["trace[1385020320] 'agreement among raft nodes before linearized reading' (duration: 140.509819ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:42:18.374432Z","caller":"traceutil/trace.go:171","msg":"trace[209526691] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"208.093539ms","start":"2026-05-25T00:42:18.166316Z","end":"2026-05-25T00:42:18.37441Z","steps":["trace[209526691] 'process raft request' (duration: 207.825641ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:42:18.608642Z","caller":"traceutil/trace.go:171","msg":"trace[810427015] linearizableReadLoop","detail":"{readStateIndex:2893; appliedIndex:2892; }","duration":"232.009189ms","start":"2026-05-25T00:42:18.376594Z","end":"2026-05-25T00:42:18.608603Z","steps":["trace[810427015] 'read index received' (duration: 208.033537ms)","trace[810427015] 'applied index is now lower than readState.Index' (duration: 23.975042ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:42:18.60927Z","caller":"traceutil/trace.go:171","msg":"trace[221737096] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"239.931904ms","start":"2026-05-25T00:42:18.369319Z","end":"2026-05-25T00:42:18.609251Z","steps":["trace[221737096] 'process raft request' (duration: 215.300732ms)","trace[221737096] 'compare' (duration: 23.759335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:42:18.60949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.888067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:42:18.609519Z","caller":"traceutil/trace.go:171","msg":"trace[742327879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2788; }","duration":"232.961859ms","start":"2026-05-25T00:42:18.376551Z","end":"2026-05-25T00:42:18.609513Z","steps":["trace[742327879] 'agreement among raft nodes before linearized reading' (duration: 232.899717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:42:18.6097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.036635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-25T00:42:18.609726Z","caller":"traceutil/trace.go:171","msg":"trace[1769686733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2788; }","duration":"120.101226ms","start":"2026-05-25T00:42:18.489618Z","end":"2026-05-25T00:42:18.609719Z","steps":["trace[1769686733] 'agreement among raft nodes before linearized reading' (duration: 120.007034ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:57.275483Z","caller":"traceutil/trace.go:171","msg":"trace[157947416] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"102.082372ms","start":"2026-05-25T00:45:57.173379Z","end":"2026-05-25T00:45:57.275462Z","steps":["trace[157947416] 'process raft request' (duration: 85.346422ms)","trace[157947416] 'compare' (duration: 16.455863ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:45:57.275686Z","caller":"traceutil/trace.go:171","msg":"trace[1697867839] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"103.605621ms","start":"2026-05-25T00:45:57.172041Z","end":"2026-05-25T00:45:57.275647Z","steps":["trace[1697867839] 'process raft request' (duration: 103.328814ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:57.276149Z","caller":"traceutil/trace.go:171","msg":"trace[727400791] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"101.892018ms","start":"2026-05-25T00:45:57.174237Z","end":"2026-05-25T00:45:57.276129Z","steps":["trace[727400791] 'process raft request' (duration: 101.063147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:02.971327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.048926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-591cb938-aad5-45f1-aaac-7e9d0745bd8f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-25T00:46:02.971485Z","caller":"traceutil/trace.go:171","msg":"trace[2130590453] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-591cb938-aad5-45f1-aaac-7e9d0745bd8f; range_end:; response_count:1; response_revision:4750; }","duration":"170.248711ms","start":"2026-05-25T00:46:02.801214Z","end":"2026-05-25T00:46:02.971463Z","steps":["trace[2130590453] 'range keys from in-memory index tree' (duration: 169.898192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:02.971505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.741978ms","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-05-25T00:46:02.971582Z","caller":"traceutil/trace.go:171","msg":"trace[1485860180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4750; }","duration":"112.851101ms","start":"2026-05-25T00:46:02.858714Z","end":"2026-05-25T00:46:02.971565Z","steps":["trace[1485860180] 'range keys from in-memory index tree' (duration: 112.617435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:02.971689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.823489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T00:46:02.971727Z","caller":"traceutil/trace.go:171","msg":"trace[983034428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4750; }","duration":"317.86935ms","start":"2026-05-25T00:46:02.653849Z","end":"2026-05-25T00:46:02.971718Z","steps":["trace[983034428] 'count revisions from in-memory index tree' (duration: 317.748957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:02.971752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:46:02.653772Z","time spent":"317.970543ms","remote":"127.0.0.1:53812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-25T00:46:02.971902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.820823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-591cb938-aad5-45f1-aaac-7e9d0745bd8f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-25T00:46:02.972Z","caller":"traceutil/trace.go:171","msg":"trace[550445429] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-591cb938-aad5-45f1-aaac-7e9d0745bd8f; range_end:; response_count:1; response_revision:4750; }","duration":"312.957926ms","start":"2026-05-25T00:46:02.659021Z","end":"2026-05-25T00:46:02.971979Z","steps":["trace[550445429] 'range keys from in-memory index tree' (duration: 312.675419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:02.972061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:46:02.659012Z","time spent":"313.038089ms","remote":"127.0.0.1:33226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-591cb938-aad5-45f1-aaac-7e9d0745bd8f\" "} {"level":"info","ts":"2026-05-25T00:46:03.086296Z","caller":"traceutil/trace.go:171","msg":"trace[938133560] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"107.94669ms","start":"2026-05-25T00:46:02.978324Z","end":"2026-05-25T00:46:03.086271Z","steps":["trace[938133560] 'process raft request' (duration: 107.887929ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:03.086345Z","caller":"traceutil/trace.go:171","msg":"trace[623152310] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"109.980022ms","start":"2026-05-25T00:46:02.976354Z","end":"2026-05-25T00:46:03.086334Z","steps":["trace[623152310] 'process raft request' (duration: 98.489261ms)","trace[623152310] 'compare' (duration: 11.205953ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:46:03.086319Z","caller":"traceutil/trace.go:171","msg":"trace[1780376648] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"109.852179ms","start":"2026-05-25T00:46:02.976439Z","end":"2026-05-25T00:46:03.086291Z","steps":["trace[1780376648] 'process raft request' (duration: 109.728675ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:05.173924Z","caller":"traceutil/trace.go:171","msg":"trace[1154450686] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"107.769502ms","start":"2026-05-25T00:46:05.066127Z","end":"2026-05-25T00:46:05.173897Z","steps":["trace[1154450686] 'process raft request' (duration: 107.553227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:16.358026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.972191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:46:16.358101Z","caller":"traceutil/trace.go:171","msg":"trace[514932211] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4918; }","duration":"324.118185ms","start":"2026-05-25T00:46:16.033966Z","end":"2026-05-25T00:46:16.358084Z","steps":["trace[514932211] 'count revisions from in-memory index tree' (duration: 323.900099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:16.358141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:46:16.033948Z","time spent":"324.179966ms","remote":"127.0.0.1:48268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-25T00:46:16.358439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.296565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T00:46:16.358746Z","caller":"traceutil/trace.go:171","msg":"trace[1793709352] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"186.763287ms","start":"2026-05-25T00:46:16.17196Z","end":"2026-05-25T00:46:16.358723Z","steps":["trace[1793709352] 'process raft request' (duration: 186.639404ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:16.358823Z","caller":"traceutil/trace.go:171","msg":"trace[197519651] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"289.711891ms","start":"2026-05-25T00:46:16.069095Z","end":"2026-05-25T00:46:16.358806Z","steps":["trace[197519651] 'process raft request' (duration: 160.960674ms)","trace[197519651] 'compare' (duration: 128.040439ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:46:16.35882Z","caller":"traceutil/trace.go:171","msg":"trace[1515888557] linearizableReadLoop","detail":"{readStateIndex:5103; appliedIndex:5102; }","duration":"263.784675ms","start":"2026-05-25T00:46:16.09502Z","end":"2026-05-25T00:46:16.358804Z","steps":["trace[1515888557] 'read index received' (duration: 135.024298ms)","trace[1515888557] 'applied index is now lower than readState.Index' (duration: 128.758547ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:46:16.358964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.938559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2102"} {"level":"info","ts":"2026-05-25T00:46:16.359013Z","caller":"traceutil/trace.go:171","msg":"trace[275638026] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4920; }","duration":"264.01537ms","start":"2026-05-25T00:46:16.094988Z","end":"2026-05-25T00:46:16.359003Z","steps":["trace[275638026] 'agreement among raft nodes before linearized reading' (duration: 263.868007ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:16.663Z","caller":"traceutil/trace.go:171","msg":"trace[1267242725] linearizableReadLoop","detail":"{readStateIndex:5105; appliedIndex:5104; }","duration":"304.046509ms","start":"2026-05-25T00:46:16.358926Z","end":"2026-05-25T00:46:16.662972Z","steps":["trace[1267242725] 'read index received' (duration: 244.802527ms)","trace[1267242725] 'applied index is now lower than readState.Index' (duration: 59.241032ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:46:16.663058Z","caller":"traceutil/trace.go:171","msg":"trace[1545173707] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"169.103534ms","start":"2026-05-25T00:46:16.493931Z","end":"2026-05-25T00:46:16.663034Z","steps":["trace[1545173707] 'process raft request' (duration: 168.95424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:16.66321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.517042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T00:46:16.663235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.939704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-05-25T00:46:16.663332Z","caller":"traceutil/trace.go:171","msg":"trace[523902508] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4921; }","duration":"344.078527ms","start":"2026-05-25T00:46:16.319233Z","end":"2026-05-25T00:46:16.663312Z","steps":["trace[523902508] 'agreement among raft nodes before linearized reading' (duration: 343.785409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:16.663388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:46:16.319197Z","time spent":"344.179989ms","remote":"127.0.0.1:35958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-25T00:46:16.66327Z","caller":"traceutil/trace.go:171","msg":"trace[716643902] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4921; }","duration":"357.610804ms","start":"2026-05-25T00:46:16.305647Z","end":"2026-05-25T00:46:16.663258Z","steps":["trace[716643902] 'agreement among raft nodes before linearized reading' (duration: 357.485751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:16.663449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.636636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-25T00:46:16.663454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.648485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4788"} {"level":"info","ts":"2026-05-25T00:46:16.663486Z","caller":"traceutil/trace.go:171","msg":"trace[340631142] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4921; }","duration":"271.695238ms","start":"2026-05-25T00:46:16.391782Z","end":"2026-05-25T00:46:16.663477Z","steps":["trace[340631142] 'agreement among raft nodes before linearized reading' (duration: 271.562025ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:16.663499Z","caller":"traceutil/trace.go:171","msg":"trace[1516443359] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4921; }","duration":"297.742647ms","start":"2026-05-25T00:46:16.365744Z","end":"2026-05-25T00:46:16.663487Z","steps":["trace[1516443359] 'agreement among raft nodes before linearized reading' (duration: 297.504161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:16.663479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:46:16.305587Z","time spent":"357.850861ms","remote":"127.0.0.1:33444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-25T00:46:17.041422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.967379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T00:46:17.041696Z","caller":"traceutil/trace.go:171","msg":"trace[1817395778] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"207.348226ms","start":"2026-05-25T00:46:16.83428Z","end":"2026-05-25T00:46:17.041628Z","steps":["trace[1817395778] 'process raft request' (duration: 84.10428ms)","trace[1817395778] 'compare' (duration: 122.843106ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:46:17.042341Z","caller":"traceutil/trace.go:171","msg":"trace[250628497] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"208.043914ms","start":"2026-05-25T00:46:16.834283Z","end":"2026-05-25T00:46:17.042327Z","steps":["trace[250628497] 'process raft request' (duration: 207.212763ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:17.042469Z","caller":"traceutil/trace.go:171","msg":"trace[514835860] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"208.109865ms","start":"2026-05-25T00:46:16.83435Z","end":"2026-05-25T00:46:17.04246Z","steps":["trace[514835860] 'process raft request' (duration: 207.88607ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:17.042578Z","caller":"traceutil/trace.go:171","msg":"trace[1229331491] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5108; }","duration":"208.003033ms","start":"2026-05-25T00:46:16.834548Z","end":"2026-05-25T00:46:17.042551Z","steps":["trace[1229331491] 'read index received' (duration: 83.837283ms)","trace[1229331491] 'applied index is now lower than readState.Index' (duration: 124.16161ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:46:17.042851Z","caller":"traceutil/trace.go:171","msg":"trace[1410915188] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"208.218738ms","start":"2026-05-25T00:46:16.834624Z","end":"2026-05-25T00:46:17.042843Z","steps":["trace[1410915188] 'process raft request' (duration: 207.662114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:17.043041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.486715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-25T00:46:17.043068Z","caller":"traceutil/trace.go:171","msg":"trace[984845217] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4927; }","duration":"208.548467ms","start":"2026-05-25T00:46:16.834513Z","end":"2026-05-25T00:46:17.043061Z","steps":["trace[984845217] 'agreement among raft nodes before linearized reading' (duration: 208.433334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:17.043034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.884124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:46:17.043139Z","caller":"traceutil/trace.go:171","msg":"trace[2099587784] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4927; }","duration":"184.021017ms","start":"2026-05-25T00:46:16.859099Z","end":"2026-05-25T00:46:17.04312Z","steps":["trace[2099587784] 'agreement among raft nodes before linearized reading' (duration: 183.877184ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:17.264548Z","caller":"traceutil/trace.go:171","msg":"trace[1804756784] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"130.829694ms","start":"2026-05-25T00:46:17.133697Z","end":"2026-05-25T00:46:17.264526Z","steps":["trace[1804756784] 'process raft request' (duration: 107.846443ms)","trace[1804756784] 'compare' (duration: 22.488318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:46:43.206508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.296201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T00:46:43.20699Z","caller":"traceutil/trace.go:171","msg":"trace[1179474651] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"241.518149ms","start":"2026-05-25T00:46:42.965331Z","end":"2026-05-25T00:46:43.206849Z","steps":["trace[1179474651] 'process raft request' (duration: 114.791747ms)","trace[1179474651] 'compare' (duration: 126.190408ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:46:43.208469Z","caller":"traceutil/trace.go:171","msg":"trace[1528541847] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"135.784311ms","start":"2026-05-25T00:46:43.072661Z","end":"2026-05-25T00:46:43.208446Z","steps":["trace[1528541847] 'process raft request' (duration: 135.649678ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:53.829119Z","caller":"traceutil/trace.go:171","msg":"trace[114629639] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"132.552224ms","start":"2026-05-25T00:46:53.696545Z","end":"2026-05-25T00:46:53.829097Z","steps":["trace[114629639] 'process raft request' (duration: 80.396337ms)","trace[114629639] 'compare' (duration: 52.048965ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:47:36.762586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2886} {"level":"info","ts":"2026-05-25T00:47:37.105948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2886,"took":"341.873479ms","hash":2591587592,"current-db-size-bytes":53522432,"current-db-size":"54 MB","current-db-size-in-use-bytes":29515776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-25T00:47:37.10603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2591587592,"revision":2886,"compact-revision":-1} {"level":"info","ts":"2026-05-25T00:52:36.780544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5604} {"level":"info","ts":"2026-05-25T00:52:36.873816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5604,"took":"91.424136ms","hash":725412386,"current-db-size-bytes":53522432,"current-db-size":"54 MB","current-db-size-in-use-bytes":18493440,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-25T00:52:36.873906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":725412386,"revision":5604,"compact-revision":2886}