{"level":"warn","ts":"2026-04-27T16:01:17.685723Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-27T16:01:17.685843Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.9:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.9:2380","--initial-cluster=instance=https://199.19.213.9:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.9:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.9: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-04-27T16:01:17.68593Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-27T16:01:17.685948Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.9:2380"]}
{"level":"info","ts":"2026-04-27T16:01:17.685981Z","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-04-27T16:01:17.686858Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"]}
{"level":"info","ts":"2026-04-27T16:01:17.686953Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.9: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-04-27T16:01:17.691473Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.163329ms"}
{"level":"info","ts":"2026-04-27T16:01:17.701066Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3a0c9d65f167b31b","cluster-id":"86e523b598166645"}
{"level":"info","ts":"2026-04-27T16:01:17.70127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=()"}
{"level":"info","ts":"2026-04-27T16:01:17.701407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 0"}
{"level":"info","ts":"2026-04-27T16:01:17.701499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3a0c9d65f167b31b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-27T16:01:17.701564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 1"}
{"level":"info","ts":"2026-04-27T16:01:17.701682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"}
{"level":"warn","ts":"2026-04-27T16:01:17.707289Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-27T16:01:17.708568Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-27T16:01:17.709745Z","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-04-27T16:01:17.711872Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3a0c9d65f167b31b","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-27T16:01:17.713038Z","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-04-27T16:01:17.714641Z","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-04-27T16:01:17.714732Z","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-04-27T16:01:17.71494Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3a0c9d65f167b31b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-27T16:01:17.715964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"}
{"level":"info","ts":"2026-04-27T16:01:17.716553Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","added-peer-id":"3a0c9d65f167b31b","added-peer-peer-urls":["https://199.19.213.9:2380"]}
{"level":"info","ts":"2026-04-27T16:01:17.718243Z","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-04-27T16:01:17.718489Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.9:2380"}
{"level":"info","ts":"2026-04-27T16:01:17.718567Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.9:2380"}
{"level":"info","ts":"2026-04-27T16:01:17.71853Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3a0c9d65f167b31b","initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-27T16:01:17.718735Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-27T16:01:18.702677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b is starting a new election at term 1"}
{"level":"info","ts":"2026-04-27T16:01:18.702759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-27T16:01:18.702818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgPreVoteResp from 3a0c9d65f167b31b at term 1"}
{"level":"info","ts":"2026-04-27T16:01:18.702838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became candidate at term 2"}
{"level":"info","ts":"2026-04-27T16:01:18.70285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgVoteResp from 3a0c9d65f167b31b at term 2"}
{"level":"info","ts":"2026-04-27T16:01:18.70287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became leader at term 2"}
{"level":"info","ts":"2026-04-27T16:01:18.702882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3a0c9d65f167b31b elected leader 3a0c9d65f167b31b at term 2"}
{"level":"info","ts":"2026-04-27T16:01:18.703858Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3a0c9d65f167b31b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.9:2379]}","request-path":"/0/members/3a0c9d65f167b31b/attributes","cluster-id":"86e523b598166645","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-27T16:01:18.704006Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-27T16:01:18.703919Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-27T16:01:18.704199Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-27T16:01:18.704471Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-27T16:01:18.70454Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-27T16:01:18.706594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.9:2379"}
{"level":"info","ts":"2026-04-27T16:01:18.708287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-27T16:01:18.708787Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-27T16:01:18.709109Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-27T16:01:18.709302Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"warn","ts":"2026-04-27T16:01:23.101509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.53218ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12906082672437069595 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/default/instance.18aa4218a4a51f99\" mod_revision:220 > success:<request_put:<key:\"/registry/events/default/instance.18aa4218a4a51f99\" value_size:568 lease:3682710635582293744 >> failure:<request_range:<key:\"/registry/events/default/instance.18aa4218a4a51f99\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-27T16:01:23.101636Z","caller":"traceutil/trace.go:171","msg":"trace[347661125] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"598.808173ms","start":"2026-04-27T16:01:22.502809Z","end":"2026-04-27T16:01:23.101617Z","steps":["trace[347661125] 'process raft request'  (duration: 197.578689ms)","trace[347661125] 'compare'  (duration: 400.414938ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:01:23.101705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:01:22.5028Z","time spent":"598.876955ms","remote":"127.0.0.1:46248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/events/default/instance.18aa4218a4a51f99\" mod_revision:220 > success:<request_put:<key:\"/registry/events/default/instance.18aa4218a4a51f99\" value_size:568 lease:3682710635582293744 >> failure:<request_range:<key:\"/registry/events/default/instance.18aa4218a4a51f99\" > >"}
{"level":"info","ts":"2026-04-27T16:01:23.345018Z","caller":"traceutil/trace.go:171","msg":"trace[879718241] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:232; }","duration":"566.1306ms","start":"2026-04-27T16:01:22.778852Z","end":"2026-04-27T16:01:23.344982Z","steps":["trace[879718241] 'read index received'  (duration: 492.264525ms)","trace[879718241] 'applied index is now lower than readState.Index'  (duration: 73.865115ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:01:23.345132Z","caller":"traceutil/trace.go:171","msg":"trace[1403806820] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"841.404534ms","start":"2026-04-27T16:01:22.503717Z","end":"2026-04-27T16:01:23.345122Z","steps":["trace[1403806820] 'process raft request'  (duration: 767.525459ms)","trace[1403806820] 'compare'  (duration: 73.62083ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:01:23.345222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:01:22.503693Z","time spent":"841.466676ms","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":468,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/rolebindings/kube-system/kubeadm:kubeadm-certs\" mod_revision:0 > success:<request_put:<key:\"/registry/rolebindings/kube-system/kubeadm:kubeadm-certs\" value_size:404 >> failure:<>"}
{"level":"warn","ts":"2026-04-27T16:01:23.345385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.145054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-27T16:01:23.345468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.623611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:01:23.345497Z","caller":"traceutil/trace.go:171","msg":"trace[1591164364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:227; }","duration":"566.687431ms","start":"2026-04-27T16:01:22.778799Z","end":"2026-04-27T16:01:23.345487Z","steps":["trace[1591164364] 'agreement among raft nodes before linearized reading'  (duration: 566.652501ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:01:23.345498Z","caller":"traceutil/trace.go:171","msg":"trace[1368708390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:227; }","duration":"277.330649ms","start":"2026-04-27T16:01:23.068136Z","end":"2026-04-27T16:01:23.345466Z","steps":["trace[1368708390] 'agreement among raft nodes before linearized reading'  (duration: 277.075803ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:01:23.345525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:01:22.778759Z","time spent":"566.760903ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-04-27T16:01:23.345424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.586821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-27T16:01:23.345651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.880606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18aa4218a4a4f0b9\" ","response":"range_response_count:1 size:657"}
{"level":"info","ts":"2026-04-27T16:01:23.345685Z","caller":"traceutil/trace.go:171","msg":"trace[1308050377] range","detail":"{range_begin:/registry/events/default/instance.18aa4218a4a4f0b9; range_end:; response_count:1; response_revision:227; }","duration":"240.945618ms","start":"2026-04-27T16:01:23.104729Z","end":"2026-04-27T16:01:23.345675Z","steps":["trace[1308050377] 'agreement among raft nodes before linearized reading'  (duration: 240.888216ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:01:23.34569Z","caller":"traceutil/trace.go:171","msg":"trace[421898109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:227; }","duration":"215.871137ms","start":"2026-04-27T16:01:23.129791Z","end":"2026-04-27T16:01:23.345662Z","steps":["trace[421898109] 'agreement among raft nodes before linearized reading'  (duration: 215.597661ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:01:50.972434Z","caller":"traceutil/trace.go:171","msg":"trace[794484310] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"300.374363ms","start":"2026-04-27T16:01:50.672034Z","end":"2026-04-27T16:01:50.972408Z","steps":["trace[794484310] 'process raft request'  (duration: 300.240141ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:01:50.972579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:01:50.672014Z","time spent":"300.493985ms","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/namespaces/envoy-gateway-system\" mod_revision:0 > success:<request_put:<key:\"/registry/namespaces/envoy-gateway-system\" value_size:348 >> failure:<>"}
{"level":"info","ts":"2026-04-27T16:01:54.485898Z","caller":"traceutil/trace.go:171","msg":"trace[962354138] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"113.265793ms","start":"2026-04-27T16:01:54.372606Z","end":"2026-04-27T16:01:54.485872Z","steps":["trace[962354138] 'process raft request'  (duration: 112.954428ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:01:55.189578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.973772ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12906082672437070335 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:464 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >>","response":"size:16"}
{"level":"warn","ts":"2026-04-27T16:01:55.19014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.254228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-04-27T16:01:55.190203Z","caller":"traceutil/trace.go:171","msg":"trace[34462583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"602.134413ms","start":"2026-04-27T16:01:54.588052Z","end":"2026-04-27T16:01:55.190186Z","steps":["trace[34462583] 'range keys from in-memory index tree'  (duration: 601.086044ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:01:55.190241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:01:54.588035Z","time spent":"602.187034ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-04-27T16:01:55.190547Z","caller":"traceutil/trace.go:171","msg":"trace[1668149699] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"561.447531ms","start":"2026-04-27T16:01:54.629061Z","end":"2026-04-27T16:01:55.190509Z","steps":["trace[1668149699] 'process raft request'  (duration: 353.404861ms)","trace[1668149699] 'compare'  (duration: 206.77107ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:01:55.190623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:01:54.629039Z","time spent":"561.548624ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:464 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"info","ts":"2026-04-27T16:01:55.191988Z","caller":"traceutil/trace.go:171","msg":"trace[1775889441] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"271.820373ms","start":"2026-04-27T16:01:54.920152Z","end":"2026-04-27T16:01:55.191972Z","steps":["trace[1775889441] 'process raft request'  (duration: 271.766632ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:01:55.192165Z","caller":"traceutil/trace.go:171","msg":"trace[865790897] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"300.798456ms","start":"2026-04-27T16:01:54.891328Z","end":"2026-04-27T16:01:55.192126Z","steps":["trace[865790897] 'process raft request'  (duration: 299.827249ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:01:55.192339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:01:54.891303Z","time spent":"300.947439ms","remote":"127.0.0.1:46256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:613 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036221 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"info","ts":"2026-04-27T16:01:56.684276Z","caller":"traceutil/trace.go:171","msg":"trace[1992761730] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"112.981494ms","start":"2026-04-27T16:01:56.571279Z","end":"2026-04-27T16:01:56.68426Z","steps":["trace[1992761730] 'process raft request'  (duration: 112.906202ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:02:20.143633Z","caller":"traceutil/trace.go:171","msg":"trace[928838656] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"111.11807ms","start":"2026-04-27T16:02:20.032474Z","end":"2026-04-27T16:02:20.143593Z","steps":["trace[928838656] 'process raft request'  (duration: 95.392741ms)","trace[928838656] 'compare'  (duration: 15.436074ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:02:20.143736Z","caller":"traceutil/trace.go:171","msg":"trace[963009285] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"110.683984ms","start":"2026-04-27T16:02:20.032998Z","end":"2026-04-27T16:02:20.143682Z","steps":["trace[963009285] 'process raft request'  (duration: 110.540451ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:02:20.143717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.662189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:02:20.143977Z","caller":"traceutil/trace.go:171","msg":"trace[428372372] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:910; }","duration":"111.972975ms","start":"2026-04-27T16:02:20.031981Z","end":"2026-04-27T16:02:20.143954Z","steps":["trace[428372372] 'agreement among raft nodes before linearized reading'  (duration: 111.65685ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:02:39.875786Z","caller":"traceutil/trace.go:171","msg":"trace[812646108] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"113.676967ms","start":"2026-04-27T16:02:39.762088Z","end":"2026-04-27T16:02:39.875765Z","steps":["trace[812646108] 'process raft request'  (duration: 113.629176ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:02:39.875834Z","caller":"traceutil/trace.go:171","msg":"trace[810156395] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"113.794243ms","start":"2026-04-27T16:02:39.762024Z","end":"2026-04-27T16:02:39.875818Z","steps":["trace[810156395] 'process raft request'  (duration: 108.618614ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:02:40.225786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.512098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:02:40.225934Z","caller":"traceutil/trace.go:171","msg":"trace[4549491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"205.716126ms","start":"2026-04-27T16:02:40.020196Z","end":"2026-04-27T16:02:40.225912Z","steps":["trace[4549491] 'range keys from in-memory index tree'  (duration: 205.418145ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:30.173374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.071366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-04-27T16:03:30.173464Z","caller":"traceutil/trace.go:171","msg":"trace[1606618887] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"120.20685ms","start":"2026-04-27T16:03:30.053238Z","end":"2026-04-27T16:03:30.173444Z","steps":["trace[1606618887] 'range keys from in-memory index tree'  (duration: 119.85466ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:30.173559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.707139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-04-27T16:03:30.173661Z","caller":"traceutil/trace.go:171","msg":"trace[1191978485] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"422.950677ms","start":"2026-04-27T16:03:29.75069Z","end":"2026-04-27T16:03:30.173641Z","steps":["trace[1191978485] 'range keys from in-memory index tree'  (duration: 422.542206ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:30.173745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:03:29.750669Z","time spent":"423.06365ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"warn","ts":"2026-04-27T16:03:30.17391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.317325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:03:30.174424Z","caller":"traceutil/trace.go:171","msg":"trace[424642838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1868; }","duration":"156.864051ms","start":"2026-04-27T16:03:30.017547Z","end":"2026-04-27T16:03:30.174411Z","steps":["trace[424642838] 'range keys from in-memory index tree'  (duration: 156.254234ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:30.174969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.385412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9af77e75-2ec7-498e-9375-8cf8bc04e91a\" ","response":"range_response_count:1 size:3625"}
{"level":"info","ts":"2026-04-27T16:03:30.175043Z","caller":"traceutil/trace.go:171","msg":"trace[1897225221] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9af77e75-2ec7-498e-9375-8cf8bc04e91a; range_end:; response_count:1; response_revision:1868; }","duration":"270.496934ms","start":"2026-04-27T16:03:29.904533Z","end":"2026-04-27T16:03:30.17503Z","steps":["trace[1897225221] 'range keys from in-memory index tree'  (duration: 269.24764ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:03:39.152829Z","caller":"traceutil/trace.go:171","msg":"trace[1328581932] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"206.00338ms","start":"2026-04-27T16:03:38.946795Z","end":"2026-04-27T16:03:39.152798Z","steps":["trace[1328581932] 'process raft request'  (duration: 205.62937ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:03:39.152758Z","caller":"traceutil/trace.go:171","msg":"trace[1323180084] linearizableReadLoop","detail":"{readStateIndex:2011; appliedIndex:2010; }","duration":"133.06379ms","start":"2026-04-27T16:03:39.019666Z","end":"2026-04-27T16:03:39.15273Z","steps":["trace[1323180084] 'read index received'  (duration: 132.614348ms)","trace[1323180084] 'applied index is now lower than readState.Index'  (duration: 448.222µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:03:39.153034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.285876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:03:39.153108Z","caller":"traceutil/trace.go:171","msg":"trace[23840132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1939; }","duration":"133.514271ms","start":"2026-04-27T16:03:39.019578Z","end":"2026-04-27T16:03:39.153093Z","steps":["trace[23840132] 'agreement among raft nodes before linearized reading'  (duration: 133.328477ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:03:39.154428Z","caller":"traceutil/trace.go:171","msg":"trace[256325749] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"127.254229ms","start":"2026-04-27T16:03:39.027147Z","end":"2026-04-27T16:03:39.154402Z","steps":["trace[256325749] 'process raft request'  (duration: 127.108124ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:40.809069Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082672437073025,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-04-27T16:03:40.841513Z","caller":"traceutil/trace.go:171","msg":"trace[297364329] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2019; }","duration":"532.767838ms","start":"2026-04-27T16:03:40.308732Z","end":"2026-04-27T16:03:40.8415Z","steps":["trace[297364329] 'read index received'  (duration: 532.652255ms)","trace[297364329] 'applied index is now lower than readState.Index'  (duration: 115.173µs)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:03:40.841632Z","caller":"traceutil/trace.go:171","msg":"trace[377430409] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"573.70116ms","start":"2026-04-27T16:03:40.267899Z","end":"2026-04-27T16:03:40.841601Z","steps":["trace[377430409] 'process raft request'  (duration: 573.518655ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:40.841666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.923303ms","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-04-27T16:03:40.841686Z","caller":"traceutil/trace.go:171","msg":"trace[596811945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1948; }","duration":"532.985954ms","start":"2026-04-27T16:03:40.308694Z","end":"2026-04-27T16:03:40.84168Z","steps":["trace[596811945] 'agreement among raft nodes before linearized reading'  (duration: 532.865961ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:40.841706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:03:40.308676Z","time spent":"533.024414ms","remote":"127.0.0.1:46436","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-04-27T16:03:40.841753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:03:40.267883Z","time spent":"573.805982ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1936 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-04-27T16:03:40.841841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.482616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-04-27T16:03:40.841858Z","caller":"traceutil/trace.go:171","msg":"trace[1597692803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1948; }","duration":"523.518119ms","start":"2026-04-27T16:03:40.318336Z","end":"2026-04-27T16:03:40.841854Z","steps":["trace[1597692803] 'agreement among raft nodes before linearized reading'  (duration: 523.470417ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:40.841874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:03:40.318323Z","time spent":"523.546389ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-04-27T16:03:44.971207Z","caller":"traceutil/trace.go:171","msg":"trace[1876835596] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"102.04027ms","start":"2026-04-27T16:03:44.869141Z","end":"2026-04-27T16:03:44.971181Z","steps":["trace[1876835596] 'process raft request'  (duration: 101.871296ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:03:44.996144Z","caller":"traceutil/trace.go:171","msg":"trace[1340901983] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"123.841035ms","start":"2026-04-27T16:03:44.872279Z","end":"2026-04-27T16:03:44.99612Z","steps":["trace[1340901983] 'process raft request'  (duration: 123.62244ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:03:44.996147Z","caller":"traceutil/trace.go:171","msg":"trace[2135878575] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"122.297377ms","start":"2026-04-27T16:03:44.873826Z","end":"2026-04-27T16:03:44.996123Z","steps":["trace[2135878575] 'process raft request'  (duration: 122.228514ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:44.996338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.661606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-04-27T16:03:44.996136Z","caller":"traceutil/trace.go:171","msg":"trace[1870730997] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2057; }","duration":"122.44568ms","start":"2026-04-27T16:03:44.873652Z","end":"2026-04-27T16:03:44.996098Z","steps":["trace[1870730997] 'read index received'  (duration: 97.419862ms)","trace[1870730997] 'applied index is now lower than readState.Index'  (duration: 25.024848ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:03:44.996394Z","caller":"traceutil/trace.go:171","msg":"trace[866424408] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1987; }","duration":"122.730987ms","start":"2026-04-27T16:03:44.873648Z","end":"2026-04-27T16:03:44.996379Z","steps":["trace[866424408] 'agreement among raft nodes before linearized reading'  (duration: 122.539853ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:03:48.119304Z","caller":"traceutil/trace.go:171","msg":"trace[340690548] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2081; }","duration":"158.213396ms","start":"2026-04-27T16:03:47.961068Z","end":"2026-04-27T16:03:48.119282Z","steps":["trace[340690548] 'read index received'  (duration: 154.00811ms)","trace[340690548] 'applied index is now lower than readState.Index'  (duration: 4.204165ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:03:48.119435Z","caller":"traceutil/trace.go:171","msg":"trace[122085839] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"160.699499ms","start":"2026-04-27T16:03:47.958728Z","end":"2026-04-27T16:03:48.119427Z","steps":["trace[122085839] 'process raft request'  (duration: 156.36227ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:48.119645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.975357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-04-27T16:03:48.119723Z","caller":"traceutil/trace.go:171","msg":"trace[2143453724] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2008; }","duration":"101.088401ms","start":"2026-04-27T16:03:48.018616Z","end":"2026-04-27T16:03:48.119704Z","steps":["trace[2143453724] 'agreement among raft nodes before linearized reading'  (duration: 100.898616ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:48.11983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.747609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"}
{"level":"info","ts":"2026-04-27T16:03:48.119864Z","caller":"traceutil/trace.go:171","msg":"trace[1002828416] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2008; }","duration":"158.816221ms","start":"2026-04-27T16:03:47.96104Z","end":"2026-04-27T16:03:48.119856Z","steps":["trace[1002828416] 'agreement among raft nodes before linearized reading'  (duration: 158.699907ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:03:48.119928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.07765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:03:48.119965Z","caller":"traceutil/trace.go:171","msg":"trace[734716478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2008; }","duration":"101.139912ms","start":"2026-04-27T16:03:48.018814Z","end":"2026-04-27T16:03:48.119954Z","steps":["trace[734716478] 'agreement among raft nodes before linearized reading'  (duration: 101.090231ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:04:11.448479Z","caller":"traceutil/trace.go:171","msg":"trace[1545227391] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2214; }","duration":"172.183302ms","start":"2026-04-27T16:04:11.276261Z","end":"2026-04-27T16:04:11.448445Z","steps":["trace[1545227391] 'read index received'  (duration: 153.480753ms)","trace[1545227391] 'applied index is now lower than readState.Index'  (duration: 18.701609ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:04:11.448546Z","caller":"traceutil/trace.go:171","msg":"trace[516986046] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"191.017404ms","start":"2026-04-27T16:04:11.257504Z","end":"2026-04-27T16:04:11.448522Z","steps":["trace[516986046] 'process raft request'  (duration: 172.259133ms)","trace[516986046] 'compare'  (duration: 18.569326ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:04:11.448778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.498249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-04-27T16:04:11.448838Z","caller":"traceutil/trace.go:171","msg":"trace[279651534] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2137; }","duration":"172.597401ms","start":"2026-04-27T16:04:11.276223Z","end":"2026-04-27T16:04:11.44882Z","steps":["trace[279651534] 'agreement among raft nodes before linearized reading'  (duration: 172.366146ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:04:11.46415Z","caller":"traceutil/trace.go:171","msg":"trace[710857677] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"103.933095ms","start":"2026-04-27T16:04:11.360174Z","end":"2026-04-27T16:04:11.464107Z","steps":["trace[710857677] 'process raft request'  (duration: 103.668439ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:04:11.464177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.450103ms","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-04-27T16:04:11.464375Z","caller":"traceutil/trace.go:171","msg":"trace[2055330553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2138; }","duration":"133.69283ms","start":"2026-04-27T16:04:11.330665Z","end":"2026-04-27T16:04:11.464358Z","steps":["trace[2055330553] 'agreement among raft nodes before linearized reading'  (duration: 133.31926ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:04:12.179361Z","caller":"traceutil/trace.go:171","msg":"trace[454840173] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"120.63263ms","start":"2026-04-27T16:04:12.058692Z","end":"2026-04-27T16:04:12.179324Z","steps":["trace[454840173] 'process raft request'  (duration: 120.401575ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:04:13.285316Z","caller":"traceutil/trace.go:171","msg":"trace[1156557879] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"154.882822ms","start":"2026-04-27T16:04:13.130401Z","end":"2026-04-27T16:04:13.285284Z","steps":["trace[1156557879] 'read index received'  (duration: 56.431547ms)","trace[1156557879] 'applied index is now lower than readState.Index'  (duration: 98.449655ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:04:13.285377Z","caller":"traceutil/trace.go:171","msg":"trace[141562775] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"261.498623ms","start":"2026-04-27T16:04:13.023848Z","end":"2026-04-27T16:04:13.285346Z","steps":["trace[141562775] 'process raft request'  (duration: 163.055228ms)","trace[141562775] 'compare'  (duration: 98.164409ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:04:13.28566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.22047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"}
{"level":"info","ts":"2026-04-27T16:04:13.285746Z","caller":"traceutil/trace.go:171","msg":"trace[1066597546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2144; }","duration":"155.374073ms","start":"2026-04-27T16:04:13.130349Z","end":"2026-04-27T16:04:13.285723Z","steps":["trace[1066597546] 'agreement among raft nodes before linearized reading'  (duration: 155.103327ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:04:13.286071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.10462ms","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-04-27T16:04:13.286143Z","caller":"traceutil/trace.go:171","msg":"trace[546124388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2144; }","duration":"149.213313ms","start":"2026-04-27T16:04:13.136911Z","end":"2026-04-27T16:04:13.286124Z","steps":["trace[546124388] 'agreement among raft nodes before linearized reading'  (duration: 148.546877ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:04:13.287025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.136698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-04-27T16:04:13.287093Z","caller":"traceutil/trace.go:171","msg":"trace[2004410286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2144; }","duration":"148.25027ms","start":"2026-04-27T16:04:13.138824Z","end":"2026-04-27T16:04:13.287075Z","steps":["trace[2004410286] 'agreement among raft nodes before linearized reading'  (duration: 146.996063ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:04:13.545394Z","caller":"traceutil/trace.go:171","msg":"trace[442581056] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"254.091555ms","start":"2026-04-27T16:04:13.29127Z","end":"2026-04-27T16:04:13.545362Z","steps":["trace[442581056] 'process raft request'  (duration: 238.09779ms)","trace[442581056] 'compare'  (duration: 15.352381ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:04:13.545531Z","caller":"traceutil/trace.go:171","msg":"trace[274676948] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"254.172256ms","start":"2026-04-27T16:04:13.291347Z","end":"2026-04-27T16:04:13.545519Z","steps":["trace[274676948] 'process raft request'  (duration: 253.942031ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:04:13.545789Z","caller":"traceutil/trace.go:171","msg":"trace[1239084424] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2223; }","duration":"249.01112ms","start":"2026-04-27T16:04:13.296752Z","end":"2026-04-27T16:04:13.545763Z","steps":["trace[1239084424] 'read index received'  (duration: 5.108038ms)","trace[1239084424] 'applied index is now lower than readState.Index'  (duration: 243.897331ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:04:13.545975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.196363ms","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-04-27T16:04:13.546039Z","caller":"traceutil/trace.go:171","msg":"trace[1351844895] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2149; }","duration":"249.329575ms","start":"2026-04-27T16:04:13.296696Z","end":"2026-04-27T16:04:13.546026Z","steps":["trace[1351844895] 'agreement among raft nodes before linearized reading'  (duration: 249.167861ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:05:31.576496Z","caller":"traceutil/trace.go:171","msg":"trace[1531834612] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"113.825533ms","start":"2026-04-27T16:05:31.462631Z","end":"2026-04-27T16:05:31.576456Z","steps":["trace[1531834612] 'process raft request'  (duration: 113.408345ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:06:13.012236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.024481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:06:13.012434Z","caller":"traceutil/trace.go:171","msg":"trace[636295860] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2893; }","duration":"107.245016ms","start":"2026-04-27T16:06:12.90517Z","end":"2026-04-27T16:06:13.012415Z","steps":["trace[636295860] 'count revisions from in-memory index tree'  (duration: 106.923689ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:07:13.550402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082672437076292,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-04-27T16:07:14.050834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082672437076292,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-04-27T16:07:14.253005Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.24121018s","expected-duration":"1s"}
{"level":"info","ts":"2026-04-27T16:07:14.253543Z","caller":"traceutil/trace.go:171","msg":"trace[673333278] transaction","detail":"{read_only:false; response_revision:3209; number_of_response:1; }","duration":"1.241815069s","start":"2026-04-27T16:07:13.011696Z","end":"2026-04-27T16:07:14.253511Z","steps":["trace[673333278] 'process raft request'  (duration: 1.241584522s)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:07:14.253728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:13.011676Z","time spent":"1.241938852s","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:3197 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:476 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-04-27T16:07:14.255088Z","caller":"traceutil/trace.go:171","msg":"trace[1465300519] transaction","detail":"{read_only:false; response_revision:3210; number_of_response:1; }","duration":"507.380632ms","start":"2026-04-27T16:07:13.747578Z","end":"2026-04-27T16:07:14.254958Z","steps":["trace[1465300519] 'process raft request'  (duration: 507.030941ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:07:14.25511Z","caller":"traceutil/trace.go:171","msg":"trace[878984008] linearizableReadLoop","detail":"{readStateIndex:3329; appliedIndex:3327; }","duration":"1.205323203s","start":"2026-04-27T16:07:13.04976Z","end":"2026-04-27T16:07:14.255083Z","steps":["trace[878984008] 'read index received'  (duration: 1.203632593s)","trace[878984008] 'applied index is now lower than readState.Index'  (duration: 1.6894ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:07:14.255308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205530209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-27T16:07:14.255369Z","caller":"traceutil/trace.go:171","msg":"trace[190328654] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:3212; }","duration":"1.205645883s","start":"2026-04-27T16:07:13.049708Z","end":"2026-04-27T16:07:14.255354Z","steps":["trace[190328654] 'agreement among raft nodes before linearized reading'  (duration: 1.205525759s)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:07:14.255438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:13.04969Z","time spent":"1.205732055s","remote":"127.0.0.1:35812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "}
{"level":"warn","ts":"2026-04-27T16:07:14.255433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.80497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:07:14.25555Z","caller":"traceutil/trace.go:171","msg":"trace[1208674973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3212; }","duration":"237.963104ms","start":"2026-04-27T16:07:14.017559Z","end":"2026-04-27T16:07:14.255523Z","steps":["trace[1208674973] 'agreement among raft nodes before linearized reading'  (duration: 237.720597ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:07:14.255647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.850383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"}
{"level":"info","ts":"2026-04-27T16:07:14.255749Z","caller":"traceutil/trace.go:171","msg":"trace[376924030] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3212; }","duration":"951.999648ms","start":"2026-04-27T16:07:13.303716Z","end":"2026-04-27T16:07:14.255716Z","steps":["trace[376924030] 'agreement among raft nodes before linearized reading'  (duration: 951.646118ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:07:14.255791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:13.303694Z","time spent":"952.088251ms","remote":"127.0.0.1:46366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "}
{"level":"warn","ts":"2026-04-27T16:07:14.255865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:13.747558Z","time spent":"507.595798ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:3173 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"warn","ts":"2026-04-27T16:07:14.256047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.065467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-04-27T16:07:14.2562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.578247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-27T16:07:14.256262Z","caller":"traceutil/trace.go:171","msg":"trace[2019533206] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:3212; }","duration":"446.673749ms","start":"2026-04-27T16:07:13.809574Z","end":"2026-04-27T16:07:14.256248Z","steps":["trace[2019533206] 'agreement among raft nodes before linearized reading'  (duration: 446.589376ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:07:14.25631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:13.809561Z","time spent":"446.736922ms","remote":"127.0.0.1:36104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "}
{"level":"warn","ts":"2026-04-27T16:07:14.25611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.962658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:07:14.256387Z","caller":"traceutil/trace.go:171","msg":"trace[1871095838] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:3212; }","duration":"775.353386ms","start":"2026-04-27T16:07:13.48084Z","end":"2026-04-27T16:07:14.256193Z","steps":["trace[1871095838] 'agreement among raft nodes before linearized reading'  (duration: 774.49598ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:07:14.256437Z","caller":"traceutil/trace.go:171","msg":"trace[652323551] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:3212; }","duration":"185.311739ms","start":"2026-04-27T16:07:14.071106Z","end":"2026-04-27T16:07:14.256417Z","steps":["trace[652323551] 'agreement among raft nodes before linearized reading'  (duration: 184.968459ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:07:14.256534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:13.480821Z","time spent":"775.692126ms","remote":"127.0.0.1:59174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":15,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "}
{"level":"warn","ts":"2026-04-27T16:07:14.257983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18494288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-04-27T16:07:14.258064Z","caller":"traceutil/trace.go:171","msg":"trace[871329300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3212; }","duration":"1.185488887s","start":"2026-04-27T16:07:13.072552Z","end":"2026-04-27T16:07:14.25804Z","steps":["trace[871329300] 'agreement among raft nodes before linearized reading'  (duration: 1.182724384s)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:07:14.258128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:13.072541Z","time spent":"1.18557447s","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-04-27T16:10:15.621259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.526494ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12906082672437080441 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:4858 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-27T16:10:15.621422Z","caller":"traceutil/trace.go:171","msg":"trace[1880725853] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5114; }","duration":"202.351304ms","start":"2026-04-27T16:10:15.41905Z","end":"2026-04-27T16:10:15.621401Z","steps":["trace[1880725853] 'read index received'  (duration: 42.34µs)","trace[1880725853] 'applied index is now lower than readState.Index'  (duration: 202.307624ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:10:15.621721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.660222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-04-27T16:10:15.621799Z","caller":"traceutil/trace.go:171","msg":"trace[593253518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4929; }","duration":"202.806375ms","start":"2026-04-27T16:10:15.418974Z","end":"2026-04-27T16:10:15.621781Z","steps":["trace[593253518] 'agreement among raft nodes before linearized reading'  (duration: 202.540039ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:10:15.621911Z","caller":"traceutil/trace.go:171","msg":"trace[1016730999] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"354.507373ms","start":"2026-04-27T16:10:15.267366Z","end":"2026-04-27T16:10:15.621874Z","steps":["trace[1016730999] 'process raft request'  (duration: 65.266433ms)","trace[1016730999] 'compare'  (duration: 288.393631ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:10:15.622026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:10:15.267349Z","time spent":"354.602855ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:4858 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-04-27T16:10:16.012854Z","caller":"traceutil/trace.go:171","msg":"trace[1225214844] linearizableReadLoop","detail":"{readStateIndex:5120; appliedIndex:5119; }","duration":"146.221875ms","start":"2026-04-27T16:10:15.866608Z","end":"2026-04-27T16:10:16.01283Z","steps":["trace[1225214844] 'read index received'  (duration: 146.043271ms)","trace[1225214844] 'applied index is now lower than readState.Index'  (duration: 177.444µs)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:10:16.01293Z","caller":"traceutil/trace.go:171","msg":"trace[454965323] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"146.676115ms","start":"2026-04-27T16:10:15.866217Z","end":"2026-04-27T16:10:16.012893Z","steps":["trace[454965323] 'process raft request'  (duration: 146.47227ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:10:16.013156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.527142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-04-27T16:10:16.013196Z","caller":"traceutil/trace.go:171","msg":"trace[1141980661] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4934; }","duration":"146.602444ms","start":"2026-04-27T16:10:15.866583Z","end":"2026-04-27T16:10:16.013186Z","steps":["trace[1141980661] 'agreement among raft nodes before linearized reading'  (duration: 146.378599ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:10:20.46991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.546464ms","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-04-27T16:10:20.469978Z","caller":"traceutil/trace.go:171","msg":"trace[1321177256] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5003; }","duration":"107.626246ms","start":"2026-04-27T16:10:20.362335Z","end":"2026-04-27T16:10:20.469961Z","steps":["trace[1321177256] 'range keys from in-memory index tree'  (duration: 107.384921ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:10:29.934915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.484327ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12906082672437080864 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:5018 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-27T16:10:29.935021Z","caller":"traceutil/trace.go:171","msg":"trace[160520645] linearizableReadLoop","detail":"{readStateIndex:5305; appliedIndex:5304; }","duration":"396.63563ms","start":"2026-04-27T16:10:29.538348Z","end":"2026-04-27T16:10:29.934983Z","steps":["trace[160520645] 'read index received'  (duration: 249.00769ms)","trace[160520645] 'applied index is now lower than readState.Index'  (duration: 147.6271ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:10:29.935174Z","caller":"traceutil/trace.go:171","msg":"trace[1025667904] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"469.652717ms","start":"2026-04-27T16:10:29.465512Z","end":"2026-04-27T16:10:29.935165Z","steps":["trace[1025667904] 'process raft request'  (duration: 321.850373ms)","trace[1025667904] 'compare'  (duration: 147.18954ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:10:29.935215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:10:29.465486Z","time spent":"469.706969ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:5018 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"warn","ts":"2026-04-27T16:10:29.935357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.007808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-27T16:10:29.935377Z","caller":"traceutil/trace.go:171","msg":"trace[1967186798] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:5112; }","duration":"397.029978ms","start":"2026-04-27T16:10:29.53834Z","end":"2026-04-27T16:10:29.93537Z","steps":["trace[1967186798] 'agreement among raft nodes before linearized reading'  (duration: 396.990917ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:10:29.935396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:10:29.538293Z","time spent":"397.097741ms","remote":"127.0.0.1:36064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "}
{"level":"warn","ts":"2026-04-27T16:10:29.935564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.972256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-04-27T16:10:29.935582Z","caller":"traceutil/trace.go:171","msg":"trace[1278848351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5112; }","duration":"242.020296ms","start":"2026-04-27T16:10:29.693556Z","end":"2026-04-27T16:10:29.935576Z","steps":["trace[1278848351] 'agreement among raft nodes before linearized reading'  (duration: 241.940215ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:10:29.935657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.61316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"warn","ts":"2026-04-27T16:10:29.935678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.376584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-04-27T16:10:29.935683Z","caller":"traceutil/trace.go:171","msg":"trace[1783877487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5112; }","duration":"128.670712ms","start":"2026-04-27T16:10:29.807005Z","end":"2026-04-27T16:10:29.935675Z","steps":["trace[1783877487] 'agreement among raft nodes before linearized reading'  (duration: 128.592441ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:10:29.935697Z","caller":"traceutil/trace.go:171","msg":"trace[36390828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5112; }","duration":"167.429585ms","start":"2026-04-27T16:10:29.768262Z","end":"2026-04-27T16:10:29.935691Z","steps":["trace[36390828] 'agreement among raft nodes before linearized reading'  (duration: 167.370564ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:10:29.935793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.769642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-04-27T16:10:29.93581Z","caller":"traceutil/trace.go:171","msg":"trace[1896825542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5112; }","duration":"127.801883ms","start":"2026-04-27T16:10:29.808002Z","end":"2026-04-27T16:10:29.935803Z","steps":["trace[1896825542] 'agreement among raft nodes before linearized reading'  (duration: 127.752482ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:10:30.218273Z","caller":"traceutil/trace.go:171","msg":"trace[1937875368] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"110.257936ms","start":"2026-04-27T16:10:30.107987Z","end":"2026-04-27T16:10:30.218245Z","steps":["trace[1937875368] 'process raft request'  (duration: 102.345732ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:10:30.253118Z","caller":"traceutil/trace.go:171","msg":"trace[430496059] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"143.986827ms","start":"2026-04-27T16:10:30.109106Z","end":"2026-04-27T16:10:30.253092Z","steps":["trace[430496059] 'process raft request'  (duration: 143.881175ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:10:30.253575Z","caller":"traceutil/trace.go:171","msg":"trace[2128943544] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"144.757173ms","start":"2026-04-27T16:10:30.108799Z","end":"2026-04-27T16:10:30.253556Z","steps":["trace[2128943544] 'process raft request'  (duration: 144.037597ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:10:30.253702Z","caller":"traceutil/trace.go:171","msg":"trace[894070465] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"141.982003ms","start":"2026-04-27T16:10:30.11171Z","end":"2026-04-27T16:10:30.253692Z","steps":["trace[894070465] 'process raft request'  (duration: 141.338409ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:10:30.253618Z","caller":"traceutil/trace.go:171","msg":"trace[1493858558] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"133.004155ms","start":"2026-04-27T16:10:30.120562Z","end":"2026-04-27T16:10:30.253566Z","steps":["trace[1493858558] 'process raft request'  (duration: 132.866762ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:11:18.731236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2924}
{"level":"info","ts":"2026-04-27T16:11:18.823771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2924,"took":"90.293603ms","hash":3933783825,"current-db-size-bytes":53166080,"current-db-size":"53 MB","current-db-size-in-use-bytes":28299264,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-04-27T16:11:18.823835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3933783825,"revision":2924,"compact-revision":-1}
{"level":"info","ts":"2026-04-27T16:14:06.253208Z","caller":"traceutil/trace.go:171","msg":"trace[812378861] transaction","detail":"{read_only:false; response_revision:6669; number_of_response:1; }","duration":"107.783999ms","start":"2026-04-27T16:14:06.145379Z","end":"2026-04-27T16:14:06.253163Z","steps":["trace[812378861] 'process raft request'  (duration: 107.584475ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:14:06.282493Z","caller":"traceutil/trace.go:171","msg":"trace[721785065] transaction","detail":"{read_only:false; response_revision:6670; number_of_response:1; }","duration":"137.079501ms","start":"2026-04-27T16:14:06.145379Z","end":"2026-04-27T16:14:06.282459Z","steps":["trace[721785065] 'process raft request'  (duration: 136.930468ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:14:41.969013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.529187ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12906082672437085575 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:331b9dcfac6fc586>","response":"size:40"}
{"level":"info","ts":"2026-04-27T16:14:48.633939Z","caller":"traceutil/trace.go:171","msg":"trace[547664472] transaction","detail":"{read_only:false; response_revision:6971; number_of_response:1; }","duration":"114.688455ms","start":"2026-04-27T16:14:48.51922Z","end":"2026-04-27T16:14:48.633908Z","steps":["trace[547664472] 'process raft request'  (duration: 114.508221ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:14:48.862567Z","caller":"traceutil/trace.go:171","msg":"trace[1701769123] transaction","detail":"{read_only:false; response_revision:6973; number_of_response:1; }","duration":"158.017137ms","start":"2026-04-27T16:14:48.704501Z","end":"2026-04-27T16:14:48.862518Z","steps":["trace[1701769123] 'process raft request'  (duration: 146.383603ms)","trace[1701769123] 'compare'  (duration: 11.071723ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:14:49.542007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.464448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-27T16:14:49.542126Z","caller":"traceutil/trace.go:171","msg":"trace[1747761663] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:6974; }","duration":"181.658762ms","start":"2026-04-27T16:14:49.360442Z","end":"2026-04-27T16:14:49.5421Z","steps":["trace[1747761663] 'count revisions from in-memory index tree'  (duration: 181.365796ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:14:49.542047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.582378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-27T16:14:49.542124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.20645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-27T16:14:49.542234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.320612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-04-27T16:14:49.542237Z","caller":"traceutil/trace.go:171","msg":"trace[333737829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6974; }","duration":"355.338082ms","start":"2026-04-27T16:14:49.186877Z","end":"2026-04-27T16:14:49.542215Z","steps":["trace[333737829] 'range keys from in-memory index tree'  (duration: 355.17057ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:14:49.542279Z","caller":"traceutil/trace.go:171","msg":"trace[1797551777] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6974; }","duration":"356.381765ms","start":"2026-04-27T16:14:49.185882Z","end":"2026-04-27T16:14:49.542263Z","steps":["trace[1797551777] 'range keys from in-memory index tree'  (duration: 356.037838ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:14:49.542316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:14:49.185867Z","time spent":"356.442115ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-27T16:14:49.542166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.901633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107267"}
{"level":"info","ts":"2026-04-27T16:14:49.542227Z","caller":"traceutil/trace.go:171","msg":"trace[1717906812] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:6974; }","duration":"356.815983ms","start":"2026-04-27T16:14:49.185388Z","end":"2026-04-27T16:14:49.542204Z","steps":["trace[1717906812] 'count revisions from in-memory index tree'  (duration: 356.473156ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:14:49.542537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:14:49.185363Z","time spent":"357.129439ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "}
{"level":"warn","ts":"2026-04-27T16:14:49.54229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:14:49.186873Z","time spent":"355.407155ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-27T16:14:49.542732Z","caller":"traceutil/trace.go:171","msg":"trace[85093043] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6974; }","duration":"343.213079ms","start":"2026-04-27T16:14:49.199207Z","end":"2026-04-27T16:14:49.54242Z","steps":["trace[85093043] 'range keys from in-memory index tree'  (duration: 342.693379ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:14:49.542907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:14:49.199198Z","time spent":"343.660168ms","remote":"127.0.0.1:46256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":2,"response size":107290,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "}
{"level":"warn","ts":"2026-04-27T16:15:00.960918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.269585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:15:00.961015Z","caller":"traceutil/trace.go:171","msg":"trace[446023027] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:7035; }","duration":"112.425588ms","start":"2026-04-27T16:15:00.848565Z","end":"2026-04-27T16:15:00.96099Z","steps":["trace[446023027] 'count revisions from in-memory index tree'  (duration: 112.184562ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:16:18.73708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5513}
{"level":"info","ts":"2026-04-27T16:16:18.823538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5513,"took":"84.951764ms","hash":1773945616,"current-db-size-bytes":53166080,"current-db-size":"53 MB","current-db-size-in-use-bytes":18477056,"current-db-size-in-use":"18 MB"}
{"level":"info","ts":"2026-04-27T16:16:18.823617Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1773945616,"revision":5513,"compact-revision":2924}
{"level":"warn","ts":"2026-04-27T16:16:56.891555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.484318ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12906082672437087649 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:7637 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-27T16:16:56.892183Z","caller":"traceutil/trace.go:171","msg":"trace[1372537705] transaction","detail":"{read_only:false; response_revision:7646; number_of_response:1; }","duration":"204.569212ms","start":"2026-04-27T16:16:56.68758Z","end":"2026-04-27T16:16:56.892149Z","steps":["trace[1372537705] 'process raft request'  (duration: 99.364201ms)","trace[1372537705] 'compare'  (duration: 104.290954ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:16:56.892238Z","caller":"traceutil/trace.go:171","msg":"trace[690411533] transaction","detail":"{read_only:false; response_revision:7647; number_of_response:1; }","duration":"203.283163ms","start":"2026-04-27T16:16:56.688935Z","end":"2026-04-27T16:16:56.892218Z","steps":["trace[690411533] 'process raft request'  (duration: 202.811198ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:17:37.44448Z","caller":"traceutil/trace.go:171","msg":"trace[1551349484] transaction","detail":"{read_only:false; response_revision:7849; number_of_response:1; }","duration":"110.206827ms","start":"2026-04-27T16:17:37.334248Z","end":"2026-04-27T16:17:37.444455Z","steps":["trace[1551349484] 'process raft request'  (duration: 110.051541ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:17:37.445477Z","caller":"traceutil/trace.go:171","msg":"trace[1237786050] linearizableReadLoop","detail":"{readStateIndex:8143; appliedIndex:8141; }","duration":"108.783384ms","start":"2026-04-27T16:17:37.336673Z","end":"2026-04-27T16:17:37.445457Z","steps":["trace[1237786050] 'read index received'  (duration: 107.671142ms)","trace[1237786050] 'applied index is now lower than readState.Index'  (duration: 1.111222ms)"],"step_count":2}
{"level":"info","ts":"2026-04-27T16:17:37.445647Z","caller":"traceutil/trace.go:171","msg":"trace[444446871] transaction","detail":"{read_only:false; response_revision:7850; number_of_response:1; }","duration":"110.170025ms","start":"2026-04-27T16:17:37.335465Z","end":"2026-04-27T16:17:37.445635Z","steps":["trace[444446871] 'process raft request'  (duration: 109.882526ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:17:37.446171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.462744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak.199-19-213-9.nip.io-tls\" ","response":"range_response_count:1 size:4354"}
{"level":"info","ts":"2026-04-27T16:17:37.44622Z","caller":"traceutil/trace.go:171","msg":"trace[1877579412] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak.199-19-213-9.nip.io-tls; range_end:; response_count:1; response_revision:7850; }","duration":"109.547596ms","start":"2026-04-27T16:17:37.336658Z","end":"2026-04-27T16:17:37.446206Z","steps":["trace[1877579412] 'agreement among raft nodes before linearized reading'  (duration: 109.35813ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:17:37.661382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.324681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-27T16:17:37.661457Z","caller":"traceutil/trace.go:171","msg":"trace[909012264] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:7852; }","duration":"121.458124ms","start":"2026-04-27T16:17:37.539983Z","end":"2026-04-27T16:17:37.661441Z","steps":["trace[909012264] 'range keys from in-memory index tree'  (duration: 121.220758ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-27T16:17:37.661834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.999093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/sh.helm.release.v1.keycloak.v1\" ","response":"range_response_count:1 size:74828"}
{"level":"info","ts":"2026-04-27T16:17:37.661864Z","caller":"traceutil/trace.go:171","msg":"trace[537836353] range","detail":"{range_begin:/registry/secrets/auth-system/sh.helm.release.v1.keycloak.v1; range_end:; response_count:1; response_revision:7852; }","duration":"107.062064ms","start":"2026-04-27T16:17:37.554792Z","end":"2026-04-27T16:17:37.661854Z","steps":["trace[537836353] 'range keys from in-memory index tree'  (duration: 106.826547ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:17:37.662077Z","caller":"traceutil/trace.go:171","msg":"trace[1078523569] linearizableReadLoop","detail":"{readStateIndex:8146; appliedIndex:8145; }","duration":"101.399749ms","start":"2026-04-27T16:17:37.560664Z","end":"2026-04-27T16:17:37.662064Z","steps":["trace[1078523569] 'read index received'  (duration: 82.2586ms)","trace[1078523569] 'applied index is now lower than readState.Index'  (duration: 19.139879ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-27T16:17:37.662241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.594595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak.199-19-213-9.nip.io-tls\" ","response":"range_response_count:1 size:4354"}
{"level":"info","ts":"2026-04-27T16:17:37.662274Z","caller":"traceutil/trace.go:171","msg":"trace[2028270282] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak.199-19-213-9.nip.io-tls; range_end:; response_count:1; response_revision:7853; }","duration":"101.691668ms","start":"2026-04-27T16:17:37.560574Z","end":"2026-04-27T16:17:37.662265Z","steps":["trace[2028270282] 'agreement among raft nodes before linearized reading'  (duration: 101.535944ms)"],"step_count":1}
{"level":"info","ts":"2026-04-27T16:17:37.662538Z","caller":"traceutil/trace.go:171","msg":"trace[176976311] transaction","detail":"{read_only:false; response_revision:7853; number_of_response:1; }","duration":"104.603472ms","start":"2026-04-27T16:17:37.557873Z","end":"2026-04-27T16:17:37.662477Z","steps":["trace[176976311] 'process raft request'  (duration: 85.056652ms)","trace[176976311] 'compare'  (duration: 18.573992ms)"],"step_count":2}