{"level":"warn","ts":"2026-05-14T03:00:41.60053Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T03:00:41.600633Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.47: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.47:2380","--initial-cluster=instance=https://199.19.213.47:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.47:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.47:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-14T03:00:41.600707Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T03:00:41.600728Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-05-14T03:00:41.600752Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T03:00:41.601628Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"]} {"level":"info","ts":"2026-05-14T03:00:41.601755Z","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.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.47:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-14T03:00:41.60802Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.005521ms"} {"level":"info","ts":"2026-05-14T03:00:41.616113Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"944e7e1987ec6eaf","cluster-id":"cb5c24893c5cbfa7"} {"level":"info","ts":"2026-05-14T03:00:41.616219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T03:00:41.616246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 0"} {"level":"info","ts":"2026-05-14T03:00:41.616261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 944e7e1987ec6eaf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T03:00:41.616273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 1"} {"level":"info","ts":"2026-05-14T03:00:41.616317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"warn","ts":"2026-05-14T03:00:41.623894Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T03:00:41.625129Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T03:00:41.626151Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-14T03:00:41.628238Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"944e7e1987ec6eaf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T03:00:41.628693Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T03:00:41.628758Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T03:00:41.628765Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T03:00:41.628707Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"944e7e1987ec6eaf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T03:00:41.631541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"info","ts":"2026-05-14T03:00:41.631989Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","added-peer-id":"944e7e1987ec6eaf","added-peer-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-05-14T03:00:41.633605Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T03:00:41.633732Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-05-14T03:00:41.633804Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-05-14T03:00:41.633942Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"944e7e1987ec6eaf","initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T03:00:41.633969Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T03:00:42.41729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T03:00:42.417362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T03:00:42.417421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgPreVoteResp from 944e7e1987ec6eaf at term 1"} {"level":"info","ts":"2026-05-14T03:00:42.41744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became candidate at term 2"} {"level":"info","ts":"2026-05-14T03:00:42.417454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgVoteResp from 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-05-14T03:00:42.41747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became leader at term 2"} {"level":"info","ts":"2026-05-14T03:00:42.417489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 944e7e1987ec6eaf elected leader 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-05-14T03:00:42.418579Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T03:00:42.419541Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"944e7e1987ec6eaf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.47:2379]}","request-path":"/0/members/944e7e1987ec6eaf/attributes","cluster-id":"cb5c24893c5cbfa7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T03:00:42.419556Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T03:00:42.419939Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T03:00:42.419977Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T03:00:42.420027Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T03:00:42.420082Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T03:00:42.420118Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T03:00:42.422655Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.47:2379"} {"level":"info","ts":"2026-05-14T03:00:42.422762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T03:00:42.425655Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T03:00:55.080137Z","caller":"traceutil/trace.go:171","msg":"trace[1077246198] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"113.755343ms","start":"2026-05-14T03:00:54.966355Z","end":"2026-05-14T03:00:55.080111Z","steps":["trace[1077246198] 'process raft request' (duration: 113.594719ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:00:55.110628Z","caller":"traceutil/trace.go:171","msg":"trace[1443020987] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"141.126715ms","start":"2026-05-14T03:00:54.969484Z","end":"2026-05-14T03:00:55.110611Z","steps":["trace[1443020987] 'process raft request' (duration: 141.020362ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:01:18.309627Z","caller":"traceutil/trace.go:171","msg":"trace[365838763] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"266.652461ms","start":"2026-05-14T03:01:18.042946Z","end":"2026-05-14T03:01:18.309598Z","steps":["trace[365838763] 'process raft request' (duration: 174.946182ms)","trace[365838763] 'compare' (duration: 91.080113ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:01:18.310211Z","caller":"traceutil/trace.go:171","msg":"trace[1751102358] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"230.767013ms","start":"2026-05-14T03:01:18.07942Z","end":"2026-05-14T03:01:18.310187Z","steps":["trace[1751102358] 'read index received' (duration: 138.496171ms)","trace[1751102358] 'applied index is now lower than readState.Index' (duration: 92.269892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:01:18.310663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.223045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T03:01:18.310788Z","caller":"traceutil/trace.go:171","msg":"trace[1122000207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"231.393479ms","start":"2026-05-14T03:01:18.079378Z","end":"2026-05-14T03:01:18.310772Z","steps":["trace[1122000207] 'agreement among raft nodes before linearized reading' (duration: 231.151433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:01:18.310898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.908815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T03:01:18.31102Z","caller":"traceutil/trace.go:171","msg":"trace[1514048888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"185.090999ms","start":"2026-05-14T03:01:18.125906Z","end":"2026-05-14T03:01:18.310997Z","steps":["trace[1514048888] 'agreement among raft nodes before linearized reading' (duration: 184.626987ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:01:18.310486Z","caller":"traceutil/trace.go:171","msg":"trace[1016575229] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"248.06629ms","start":"2026-05-14T03:01:18.062407Z","end":"2026-05-14T03:01:18.310474Z","steps":["trace[1016575229] 'process raft request' (duration: 247.010614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:01:18.781452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.631397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:01:18.78172Z","caller":"traceutil/trace.go:171","msg":"trace[72800564] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"460.201334ms","start":"2026-05-14T03:01:18.321502Z","end":"2026-05-14T03:01:18.781703Z","steps":["trace[72800564] 'read index received' (duration: 350.46509ms)","trace[72800564] 'applied index is now lower than readState.Index' (duration: 109.735334ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:01:18.78182Z","caller":"traceutil/trace.go:171","msg":"trace[1654767224] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"466.734598ms","start":"2026-05-14T03:01:18.315073Z","end":"2026-05-14T03:01:18.781807Z","steps":["trace[1654767224] 'process raft request' (duration: 356.782878ms)","trace[1654767224] 'compare' (duration: 108.482043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:01:18.782115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:01:18.315049Z","time spent":"467.023216ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:01:18.781889Z","caller":"traceutil/trace.go:171","msg":"trace[1170157507] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"466.737358ms","start":"2026-05-14T03:01:18.315119Z","end":"2026-05-14T03:01:18.781857Z","steps":["trace[1170157507] 'process raft request' (duration: 466.523843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:01:18.782406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:01:18.315085Z","time spent":"467.231301ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:01:18.781923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.401828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:01:18.782588Z","caller":"traceutil/trace.go:171","msg":"trace[38632529] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:622; }","duration":"461.103926ms","start":"2026-05-14T03:01:18.321471Z","end":"2026-05-14T03:01:18.782575Z","steps":["trace[38632529] 'agreement among raft nodes before linearized reading' (duration: 460.395218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:01:18.782641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:01:18.321455Z","time spent":"461.175468ms","remote":"127.0.0.1:55926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-14T03:01:34.378383Z","caller":"traceutil/trace.go:171","msg":"trace[1032813778] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"243.281123ms","start":"2026-05-14T03:01:34.135083Z","end":"2026-05-14T03:01:34.378364Z","steps":["trace[1032813778] 'read index received' (duration: 243.027886ms)","trace[1032813778] 'applied index is now lower than readState.Index' (duration: 252.567µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:01:34.378615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.520158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-14T03:01:34.378603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.856037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:01:34.378651Z","caller":"traceutil/trace.go:171","msg":"trace[1936882827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"243.569419ms","start":"2026-05-14T03:01:34.135071Z","end":"2026-05-14T03:01:34.378641Z","steps":["trace[1936882827] 'agreement among raft nodes before linearized reading' (duration: 243.404505ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:01:34.378679Z","caller":"traceutil/trace.go:171","msg":"trace[584303438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"133.981291ms","start":"2026-05-14T03:01:34.244673Z","end":"2026-05-14T03:01:34.378654Z","steps":["trace[584303438] 'agreement among raft nodes before linearized reading' (duration: 133.848147ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:01:34.378439Z","caller":"traceutil/trace.go:171","msg":"trace[1841853156] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"251.936179ms","start":"2026-05-14T03:01:34.126474Z","end":"2026-05-14T03:01:34.37841Z","steps":["trace[1841853156] 'process raft request' (duration: 251.781584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:02:56.579087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.503008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:02:56.579232Z","caller":"traceutil/trace.go:171","msg":"trace[1279216045] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"334.46127ms","start":"2026-05-14T03:02:56.24475Z","end":"2026-05-14T03:02:56.579212Z","steps":["trace[1279216045] 'process raft request' (duration: 19.737856ms)","trace[1279216045] 'compare' (duration: 314.374315ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:02:56.579332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:02:56.244731Z","time spent":"334.549604ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:02:56.57942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.784293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:02:56.579485Z","caller":"traceutil/trace.go:171","msg":"trace[1704473444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"333.872874ms","start":"2026-05-14T03:02:56.245596Z","end":"2026-05-14T03:02:56.579469Z","steps":["trace[1704473444] 'agreement among raft nodes before linearized reading' (duration: 333.734861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:02:56.579531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:02:56.245591Z","time spent":"333.929267ms","remote":"127.0.0.1:55668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T03:02:56.579278Z","caller":"traceutil/trace.go:171","msg":"trace[961927658] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"333.586848ms","start":"2026-05-14T03:02:56.245614Z","end":"2026-05-14T03:02:56.5792Z","steps":["trace[961927658] 'read index received' (duration: 18.751961ms)","trace[961927658] 'applied index is now lower than readState.Index' (duration: 314.833097ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:02:56.702295Z","caller":"traceutil/trace.go:171","msg":"trace[276165266] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"122.403776ms","start":"2026-05-14T03:02:56.579866Z","end":"2026-05-14T03:02:56.702269Z","steps":["trace[276165266] 'read index received' (duration: 105.73357ms)","trace[276165266] 'applied index is now lower than readState.Index' (duration: 16.668926ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:02:56.702388Z","caller":"traceutil/trace.go:171","msg":"trace[1405880114] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"343.312623ms","start":"2026-05-14T03:02:56.359052Z","end":"2026-05-14T03:02:56.702365Z","steps":["trace[1405880114] 'process raft request' (duration: 326.612106ms)","trace[1405880114] 'compare' (duration: 16.495841ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:02:56.702549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:02:56.35903Z","time spent":"343.411916ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:02:56.702589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.193203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-343c6679-2c6e-402a-afbe-48375c4ad81d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-14T03:02:56.702622Z","caller":"traceutil/trace.go:171","msg":"trace[1492751024] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-343c6679-2c6e-402a-afbe-48375c4ad81d; range_end:; response_count:1; response_revision:1875; }","duration":"195.315166ms","start":"2026-05-14T03:02:56.507298Z","end":"2026-05-14T03:02:56.702613Z","steps":["trace[1492751024] 'agreement among raft nodes before linearized reading' (duration: 195.082411ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:02:57.132402Z","caller":"traceutil/trace.go:171","msg":"trace[862621389] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"170.102773ms","start":"2026-05-14T03:02:56.962281Z","end":"2026-05-14T03:02:57.132383Z","steps":["trace[862621389] 'read index received' (duration: 169.915329ms)","trace[862621389] 'applied index is now lower than readState.Index' (duration: 186.644µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:02:57.132468Z","caller":"traceutil/trace.go:171","msg":"trace[22918877] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"190.031505ms","start":"2026-05-14T03:02:56.942406Z","end":"2026-05-14T03:02:57.132438Z","steps":["trace[22918877] 'process raft request' (duration: 189.849651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:02:57.132866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.561195ms","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-05-14T03:02:57.13302Z","caller":"traceutil/trace.go:171","msg":"trace[831057926] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1877; }","duration":"170.745081ms","start":"2026-05-14T03:02:56.962257Z","end":"2026-05-14T03:02:57.133002Z","steps":["trace[831057926] 'agreement among raft nodes before linearized reading' (duration: 170.204406ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:10.904006Z","caller":"traceutil/trace.go:171","msg":"trace[1248611141] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"181.836436ms","start":"2026-05-14T03:03:10.722151Z","end":"2026-05-14T03:03:10.903988Z","steps":["trace[1248611141] 'process raft request' (duration: 181.725944ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:27.571711Z","caller":"traceutil/trace.go:171","msg":"trace[713439161] linearizableReadLoop","detail":"{readStateIndex:2177; appliedIndex:2176; }","duration":"158.078822ms","start":"2026-05-14T03:03:27.413613Z","end":"2026-05-14T03:03:27.571692Z","steps":["trace[713439161] 'read index received' (duration: 157.893547ms)","trace[713439161] 'applied index is now lower than readState.Index' (duration: 184.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:03:27.571865Z","caller":"traceutil/trace.go:171","msg":"trace[665768119] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"177.003958ms","start":"2026-05-14T03:03:27.394838Z","end":"2026-05-14T03:03:27.571841Z","steps":["trace[665768119] 'process raft request' (duration: 176.730582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:03:27.572106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.446772ms","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-05-14T03:03:27.572173Z","caller":"traceutil/trace.go:171","msg":"trace[482370949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2102; }","duration":"158.617057ms","start":"2026-05-14T03:03:27.413542Z","end":"2026-05-14T03:03:27.572159Z","steps":["trace[482370949] 'agreement among raft nodes before linearized reading' (duration: 158.432262ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:27.695955Z","caller":"traceutil/trace.go:171","msg":"trace[1160765188] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"120.073397ms","start":"2026-05-14T03:03:27.575834Z","end":"2026-05-14T03:03:27.695908Z","steps":["trace[1160765188] 'process raft request' (duration: 72.37389ms)","trace[1160765188] 'compare' (duration: 47.358808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:03:27.955655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.215466ms","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-05-14T03:03:27.955798Z","caller":"traceutil/trace.go:171","msg":"trace[698196164] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2104; }","duration":"151.403571ms","start":"2026-05-14T03:03:27.804367Z","end":"2026-05-14T03:03:27.955771Z","steps":["trace[698196164] 'count revisions from in-memory index tree' (duration: 151.136614ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:27.974375Z","caller":"traceutil/trace.go:171","msg":"trace[1634830764] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"137.965865ms","start":"2026-05-14T03:03:27.836385Z","end":"2026-05-14T03:03:27.974351Z","steps":["trace[1634830764] 'process raft request' (duration: 137.805891ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:28.206171Z","caller":"traceutil/trace.go:171","msg":"trace[1853279556] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2179; }","duration":"252.563569ms","start":"2026-05-14T03:03:27.95359Z","end":"2026-05-14T03:03:28.206154Z","steps":["trace[1853279556] 'read index received' (duration: 20.648421ms)","trace[1853279556] 'applied index is now lower than readState.Index' (duration: 231.914348ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:03:28.206349Z","caller":"traceutil/trace.go:171","msg":"trace[1973781629] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"266.007683ms","start":"2026-05-14T03:03:27.940317Z","end":"2026-05-14T03:03:28.206325Z","steps":["trace[1973781629] 'process raft request' (duration: 253.472411ms)","trace[1973781629] 'compare' (duration: 12.279316ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:03:28.206577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.965779ms","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-05-14T03:03:28.206672Z","caller":"traceutil/trace.go:171","msg":"trace[671101628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2106; }","duration":"253.093741ms","start":"2026-05-14T03:03:27.953561Z","end":"2026-05-14T03:03:28.206655Z","steps":["trace[671101628] 'agreement among raft nodes before linearized reading' (duration: 252.836685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:03:28.2067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.349866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T03:03:28.206775Z","caller":"traceutil/trace.go:171","msg":"trace[395555451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2106; }","duration":"249.469368ms","start":"2026-05-14T03:03:27.957291Z","end":"2026-05-14T03:03:28.20676Z","steps":["trace[395555451] 'agreement among raft nodes before linearized reading' (duration: 249.228822ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:28.502838Z","caller":"traceutil/trace.go:171","msg":"trace[1507747325] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"291.950269ms","start":"2026-05-14T03:03:28.21086Z","end":"2026-05-14T03:03:28.50281Z","steps":["trace[1507747325] 'process raft request' (duration: 291.737524ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:28.542679Z","caller":"traceutil/trace.go:171","msg":"trace[109455698] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"296.353773ms","start":"2026-05-14T03:03:28.246314Z","end":"2026-05-14T03:03:28.542668Z","steps":["trace[109455698] 'process raft request' (duration: 296.24932ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:28.542672Z","caller":"traceutil/trace.go:171","msg":"trace[139236233] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"329.968546ms","start":"2026-05-14T03:03:28.212662Z","end":"2026-05-14T03:03:28.54263Z","steps":["trace[139236233] 'process raft request' (duration: 329.770711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:03:28.542844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:03:28.212642Z","time spent":"330.107029ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:03:28.543046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.109378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:03:28.543094Z","caller":"traceutil/trace.go:171","msg":"trace[298517519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2109; }","duration":"298.217191ms","start":"2026-05-14T03:03:28.244869Z","end":"2026-05-14T03:03:28.543086Z","steps":["trace[298517519] 'agreement among raft nodes before linearized reading' (duration: 298.121328ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:28.542967Z","caller":"traceutil/trace.go:171","msg":"trace[223963938] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2181; }","duration":"297.670117ms","start":"2026-05-14T03:03:28.244923Z","end":"2026-05-14T03:03:28.542593Z","steps":["trace[223963938] 'read index received' (duration: 257.861005ms)","trace[223963938] 'applied index is now lower than readState.Index' (duration: 39.808382ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:03:28.54366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.221848ms","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-05-14T03:03:28.543703Z","caller":"traceutil/trace.go:171","msg":"trace[733095909] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2109; }","duration":"171.30609ms","start":"2026-05-14T03:03:28.372383Z","end":"2026-05-14T03:03:28.543689Z","steps":["trace[733095909] 'agreement among raft nodes before linearized reading' (duration: 171.199197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:03:28.543999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.638868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-14T03:03:28.544024Z","caller":"traceutil/trace.go:171","msg":"trace[1671867797] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2109; }","duration":"206.695519ms","start":"2026-05-14T03:03:28.337321Z","end":"2026-05-14T03:03:28.544017Z","steps":["trace[1671867797] 'agreement among raft nodes before linearized reading' (duration: 206.200457ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:03:28.66484Z","caller":"traceutil/trace.go:171","msg":"trace[564549892] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"117.663562ms","start":"2026-05-14T03:03:28.54716Z","end":"2026-05-14T03:03:28.664823Z","steps":["trace[564549892] 'process raft request' (duration: 116.168234ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:04:39.828291Z","caller":"traceutil/trace.go:171","msg":"trace[977083210] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"158.55657ms","start":"2026-05-14T03:04:39.669714Z","end":"2026-05-14T03:04:39.82827Z","steps":["trace[977083210] 'process raft request' (duration: 158.444477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:05:12.297645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.51067ms","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-05-14T03:05:12.297745Z","caller":"traceutil/trace.go:171","msg":"trace[1564065072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2771; }","duration":"124.656365ms","start":"2026-05-14T03:05:12.173075Z","end":"2026-05-14T03:05:12.297731Z","steps":["trace[1564065072] 'range keys from in-memory index tree' (duration: 124.365747ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:05:12.644445Z","caller":"traceutil/trace.go:171","msg":"trace[307415456] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"106.990462ms","start":"2026-05-14T03:05:12.537335Z","end":"2026-05-14T03:05:12.644325Z","steps":["trace[307415456] 'process raft request' (duration: 101.364641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:39.636604Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767344395989031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:07:40.136871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767344395989031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:07:40.637652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767344395989031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:07:40.936404Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.817665175s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T03:07:40.936982Z","caller":"traceutil/trace.go:171","msg":"trace[63985567] transaction","detail":"{read_only:false; response_revision:3639; number_of_response:1; }","duration":"1.818300978s","start":"2026-05-14T03:07:39.118652Z","end":"2026-05-14T03:07:40.936952Z","steps":["trace[63985567] 'process raft request' (duration: 1.818124215s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:40.937216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:39.118631Z","time spent":"1.818469602s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:40.937732Z","caller":"traceutil/trace.go:171","msg":"trace[696420022] transaction","detail":"{read_only:false; response_revision:3641; number_of_response:1; }","duration":"530.8415ms","start":"2026-05-14T03:07:40.406864Z","end":"2026-05-14T03:07:40.937705Z","steps":["trace[696420022] 'process raft request' (duration: 530.764258ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:40.937945Z","caller":"traceutil/trace.go:171","msg":"trace[274667493] transaction","detail":"{read_only:false; response_revision:3640; number_of_response:1; }","duration":"1.612309246s","start":"2026-05-14T03:07:39.325447Z","end":"2026-05-14T03:07:40.937756Z","steps":["trace[274667493] 'process raft request' (duration: 1.61199249s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:40.938179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:39.325425Z","time spent":"1.612669304s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:40.938516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:40.40683Z","time spent":"531.005594ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:40.941744Z","caller":"traceutil/trace.go:171","msg":"trace[146078149] linearizableReadLoop","detail":"{readStateIndex:3774; appliedIndex:3770; }","duration":"1.805806847s","start":"2026-05-14T03:07:39.135918Z","end":"2026-05-14T03:07:40.941725Z","steps":["trace[146078149] 'read index received' (duration: 1.800911955s)","trace[146078149] 'applied index is now lower than readState.Index' (duration: 4.894062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:40.942166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697821862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:07:40.942278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246023456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-14T03:07:40.94235Z","caller":"traceutil/trace.go:171","msg":"trace[98464841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3642; }","duration":"1.246132738s","start":"2026-05-14T03:07:39.696199Z","end":"2026-05-14T03:07:40.942332Z","steps":["trace[98464841] 'agreement among raft nodes before linearized reading' (duration: 1.245944203s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:40.942411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:39.696183Z","time spent":"1.246212759s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:40.942223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.616579075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:07:40.942328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379222458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:07:40.942477Z","caller":"traceutil/trace.go:171","msg":"trace[1522309711] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:3642; }","duration":"1.616864061s","start":"2026-05-14T03:07:39.325599Z","end":"2026-05-14T03:07:40.942463Z","steps":["trace[1522309711] 'agreement among raft nodes before linearized reading' (duration: 1.616575255s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:40.942635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:39.325591Z","time spent":"1.617031725s","remote":"127.0.0.1:55246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:07:40.942647Z","caller":"traceutil/trace.go:171","msg":"trace[259767812] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:3642; }","duration":"1.379591056s","start":"2026-05-14T03:07:39.562989Z","end":"2026-05-14T03:07:40.94258Z","steps":["trace[259767812] 'agreement among raft nodes before linearized reading' (duration: 1.379172927s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:40.942711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116121504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T03:07:40.942821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:39.562925Z","time spent":"1.379869701s","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:40.942848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.654057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-14T03:07:40.942878Z","caller":"traceutil/trace.go:171","msg":"trace[2063956525] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:3642; }","duration":"1.116327517s","start":"2026-05-14T03:07:39.826539Z","end":"2026-05-14T03:07:40.942866Z","steps":["trace[2063956525] 'agreement among raft nodes before linearized reading' (duration: 1.116037942s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:40.942897Z","caller":"traceutil/trace.go:171","msg":"trace[440554433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3642; }","duration":"246.718009ms","start":"2026-05-14T03:07:40.696164Z","end":"2026-05-14T03:07:40.942882Z","steps":["trace[440554433] 'agreement among raft nodes before linearized reading' (duration: 246.581087ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:40.942295Z","caller":"traceutil/trace.go:171","msg":"trace[1243492826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3642; }","duration":"1.697949775s","start":"2026-05-14T03:07:39.244304Z","end":"2026-05-14T03:07:40.942254Z","steps":["trace[1243492826] 'agreement among raft nodes before linearized reading' (duration: 1.697818802s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:40.942948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:39.244293Z","time spent":"1.698648879s","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:07:40.942905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:39.826518Z","time spent":"1.116378718s","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:40.942539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247371003s","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-05-14T03:07:40.943377Z","caller":"traceutil/trace.go:171","msg":"trace[781858830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3642; }","duration":"1.248324983s","start":"2026-05-14T03:07:39.695037Z","end":"2026-05-14T03:07:40.943362Z","steps":["trace[781858830] 'agreement among raft nodes before linearized reading' (duration: 1.247244571s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:40.943485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:39.694968Z","time spent":"1.248499958s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:40.943544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807595915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:40.943608Z","caller":"traceutil/trace.go:171","msg":"trace[318288878] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:3642; }","duration":"1.807700856s","start":"2026-05-14T03:07:39.135894Z","end":"2026-05-14T03:07:40.943595Z","steps":["trace[318288878] 'agreement among raft nodes before linearized reading' (duration: 1.806104403s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:40.943641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:39.135887Z","time spent":"1.807743386s","remote":"127.0.0.1:41142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:07:40.943688Z","caller":"traceutil/trace.go:171","msg":"trace[881515499] transaction","detail":"{read_only:false; response_revision:3642; number_of_response:1; }","duration":"149.746981ms","start":"2026-05-14T03:07:40.792339Z","end":"2026-05-14T03:07:40.942086Z","steps":["trace[881515499] 'process raft request' (duration: 149.20851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:40.944148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.913656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:07:40.944261Z","caller":"traceutil/trace.go:171","msg":"trace[1356451135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3642; }","duration":"294.08371ms","start":"2026-05-14T03:07:40.650164Z","end":"2026-05-14T03:07:40.944247Z","steps":["trace[1356451135] 'agreement among raft nodes before linearized reading' (duration: 293.078888ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:45.359832Z","caller":"traceutil/trace.go:171","msg":"trace[940570728] transaction","detail":"{read_only:false; response_revision:3665; number_of_response:1; }","duration":"410.065604ms","start":"2026-05-14T03:07:44.949748Z","end":"2026-05-14T03:07:45.359814Z","steps":["trace[940570728] 'process raft request' (duration: 409.927891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.35995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:44.949726Z","time spent":"410.159677ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:45.374675Z","caller":"traceutil/trace.go:171","msg":"trace[2053635210] linearizableReadLoop","detail":"{readStateIndex:3801; appliedIndex:3798; }","duration":"415.483348ms","start":"2026-05-14T03:07:44.959175Z","end":"2026-05-14T03:07:45.374659Z","steps":["trace[2053635210] 'read index received' (duration: 400.612156ms)","trace[2053635210] 'applied index is now lower than readState.Index' (duration: 14.870442ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:45.374709Z","caller":"traceutil/trace.go:171","msg":"trace[1000048737] transaction","detail":"{read_only:false; response_revision:3666; number_of_response:1; }","duration":"423.943976ms","start":"2026-05-14T03:07:44.95075Z","end":"2026-05-14T03:07:45.374694Z","steps":["trace[1000048737] 'process raft request' (duration: 423.760733ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:45.374824Z","caller":"traceutil/trace.go:171","msg":"trace[1186393972] transaction","detail":"{read_only:false; response_revision:3668; number_of_response:1; }","duration":"421.357723ms","start":"2026-05-14T03:07:44.953451Z","end":"2026-05-14T03:07:45.374808Z","steps":["trace[1186393972] 'process raft request' (duration: 421.162968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.374881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.325312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:07:45.374894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.746446ms","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-05-14T03:07:45.374928Z","caller":"traceutil/trace.go:171","msg":"trace[163390514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3668; }","duration":"130.369823ms","start":"2026-05-14T03:07:45.244545Z","end":"2026-05-14T03:07:45.374915Z","steps":["trace[163390514] 'agreement among raft nodes before linearized reading' (duration: 130.296912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.374923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:44.953433Z","time spent":"421.430984ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:45.37493Z","caller":"traceutil/trace.go:171","msg":"trace[814538099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3668; }","duration":"407.819607ms","start":"2026-05-14T03:07:44.9671Z","end":"2026-05-14T03:07:45.37492Z","steps":["trace[814538099] 'agreement among raft nodes before linearized reading' (duration: 407.685354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.375008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.54129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-14T03:07:45.375018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:44.967087Z","time spent":"407.92623ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:45.375024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.366636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-14T03:07:45.375025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.362977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:07:45.375045Z","caller":"traceutil/trace.go:171","msg":"trace[1585933928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3668; }","duration":"406.600502ms","start":"2026-05-14T03:07:44.968435Z","end":"2026-05-14T03:07:45.375036Z","steps":["trace[1585933928] 'agreement among raft nodes before linearized reading' (duration: 406.51821ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:45.375059Z","caller":"traceutil/trace.go:171","msg":"trace[1703980111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3668; }","duration":"385.438867ms","start":"2026-05-14T03:07:44.98961Z","end":"2026-05-14T03:07:45.375049Z","steps":["trace[1703980111] 'agreement among raft nodes before linearized reading' (duration: 385.330395ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:45.375062Z","caller":"traceutil/trace.go:171","msg":"trace[440827329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3668; }","duration":"406.421278ms","start":"2026-05-14T03:07:44.968632Z","end":"2026-05-14T03:07:45.375054Z","steps":["trace[440827329] 'agreement among raft nodes before linearized reading' (duration: 406.306795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.375073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:44.968424Z","time spent":"406.640603ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:45.37506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.852646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-14T03:07:45.375091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:44.968623Z","time spent":"406.460699ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:45.375087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:44.989593Z","time spent":"385.486818ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T03:07:45.375132Z","caller":"traceutil/trace.go:171","msg":"trace[453360160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3668; }","duration":"415.972779ms","start":"2026-05-14T03:07:44.959144Z","end":"2026-05-14T03:07:45.375117Z","steps":["trace[453360160] 'agreement among raft nodes before linearized reading' (duration: 415.679123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.375175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:44.959135Z","time spent":"416.0206ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T03:07:45.374768Z","caller":"traceutil/trace.go:171","msg":"trace[1539709235] transaction","detail":"{read_only:false; response_revision:3667; number_of_response:1; }","duration":"423.869456ms","start":"2026-05-14T03:07:44.95089Z","end":"2026-05-14T03:07:45.374759Z","steps":["trace[1539709235] 'process raft request' (duration: 423.699992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.375272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:44.950862Z","time spent":"424.369066ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:45.375392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:44.950741Z","time spent":"424.050358ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:45.945195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.725001ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:45.945305Z","caller":"traceutil/trace.go:171","msg":"trace[381169713] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3668; }","duration":"411.847823ms","start":"2026-05-14T03:07:45.53344Z","end":"2026-05-14T03:07:45.945288Z","steps":["trace[381169713] 'range keys from in-memory index tree' (duration: 411.69896ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:45.946191Z","caller":"traceutil/trace.go:171","msg":"trace[759139122] transaction","detail":"{read_only:false; response_revision:3669; number_of_response:1; }","duration":"567.16124ms","start":"2026-05-14T03:07:45.379003Z","end":"2026-05-14T03:07:45.946165Z","steps":["trace[759139122] 'process raft request' (duration: 551.284616ms)","trace[759139122] 'compare' (duration: 15.357834ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:45.946416Z","caller":"traceutil/trace.go:171","msg":"trace[813669038] transaction","detail":"{read_only:false; response_revision:3674; number_of_response:1; }","duration":"562.922682ms","start":"2026-05-14T03:07:45.38348Z","end":"2026-05-14T03:07:45.946402Z","steps":["trace[813669038] 'process raft request' (duration: 562.888331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.9463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:45.378988Z","time spent":"567.256483ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:45.946437Z","caller":"traceutil/trace.go:171","msg":"trace[1339109736] transaction","detail":"{read_only:false; response_revision:3670; number_of_response:1; }","duration":"567.297404ms","start":"2026-05-14T03:07:45.379115Z","end":"2026-05-14T03:07:45.946413Z","steps":["trace[1339109736] 'process raft request' (duration: 567.11542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.946511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:45.383466Z","time spent":"562.987972ms","remote":"127.0.0.1:55714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:45.946545Z","caller":"traceutil/trace.go:171","msg":"trace[616536510] transaction","detail":"{read_only:false; response_revision:3671; number_of_response:1; }","duration":"567.187881ms","start":"2026-05-14T03:07:45.379349Z","end":"2026-05-14T03:07:45.946537Z","steps":["trace[616536510] 'process raft request' (duration: 566.930346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.946543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:45.37911Z","time spent":"567.376215ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:45.946595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:45.379341Z","time spent":"567.223162ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:45.946523Z","caller":"traceutil/trace.go:171","msg":"trace[1496934304] transaction","detail":"{read_only:false; response_revision:3672; number_of_response:1; }","duration":"567.050808ms","start":"2026-05-14T03:07:45.379463Z","end":"2026-05-14T03:07:45.946514Z","steps":["trace[1496934304] 'process raft request' (duration: 566.845264ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:45.946673Z","caller":"traceutil/trace.go:171","msg":"trace[261245938] transaction","detail":"{read_only:false; response_revision:3673; number_of_response:1; }","duration":"567.15623ms","start":"2026-05-14T03:07:45.379508Z","end":"2026-05-14T03:07:45.946664Z","steps":["trace[261245938] 'process raft request' (duration: 566.829373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:45.946693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:45.379445Z","time spent":"567.230302ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:45.946732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:45.379483Z","time spent":"567.209522ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:48.185582Z","caller":"traceutil/trace.go:171","msg":"trace[974981051] transaction","detail":"{read_only:false; response_revision:3675; number_of_response:1; }","duration":"821.24883ms","start":"2026-05-14T03:07:47.364308Z","end":"2026-05-14T03:07:48.185557Z","steps":["trace[974981051] 'process raft request' (duration: 821.080566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:48.18572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.364286Z","time spent":"821.363812ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:50.104901Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.919471817s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T03:07:50.106823Z","caller":"traceutil/trace.go:171","msg":"trace[1359440412] linearizableReadLoop","detail":"{readStateIndex:3812; appliedIndex:3808; }","duration":"2.182076868s","start":"2026-05-14T03:07:47.924726Z","end":"2026-05-14T03:07:50.106803Z","steps":["trace[1359440412] 'read index received' (duration: 260.718242ms)","trace[1359440412] 'applied index is now lower than readState.Index' (duration: 1.921357826s)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:50.107097Z","caller":"traceutil/trace.go:171","msg":"trace[267781979] transaction","detail":"{read_only:false; response_revision:3676; number_of_response:1; }","duration":"2.727931985s","start":"2026-05-14T03:07:47.379153Z","end":"2026-05-14T03:07:50.107085Z","steps":["trace[267781979] 'process raft request' (duration: 2.727443006s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:50.10729Z","caller":"traceutil/trace.go:171","msg":"trace[1160805831] transaction","detail":"{read_only:false; response_revision:3677; number_of_response:1; }","duration":"2.726577238s","start":"2026-05-14T03:07:47.380661Z","end":"2026-05-14T03:07:50.107239Z","steps":["trace[1160805831] 'process raft request' (duration: 2.726072777s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:50.107182Z","caller":"traceutil/trace.go:171","msg":"trace[749567762] transaction","detail":"{read_only:false; response_revision:3678; number_of_response:1; }","duration":"2.726469845s","start":"2026-05-14T03:07:47.380697Z","end":"2026-05-14T03:07:50.107167Z","steps":["trace[749567762] 'process raft request' (duration: 2.726077397s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.107498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.38068Z","time spent":"2.726759942s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:50.107287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.182550408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-05-14T03:07:50.10759Z","caller":"traceutil/trace.go:171","msg":"trace[1630314007] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret; range_end:; response_count:1; response_revision:3678; }","duration":"2.182903605s","start":"2026-05-14T03:07:47.924671Z","end":"2026-05-14T03:07:50.107575Z","steps":["trace[1630314007] 'agreement among raft nodes before linearized reading' (duration: 2.182509777s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.107661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.924649Z","time spent":"2.182984766s","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":1249,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret\" "} {"level":"warn","ts":"2026-05-14T03:07:50.107798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.37914Z","time spent":"2.728591471s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:50.107449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.380647Z","time spent":"2.726709231s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:50.116403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871885742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:50.116499Z","caller":"traceutil/trace.go:171","msg":"trace[138008649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3679; }","duration":"1.872004674s","start":"2026-05-14T03:07:48.244478Z","end":"2026-05-14T03:07:50.116483Z","steps":["trace[138008649] 'agreement among raft nodes before linearized reading' (duration: 1.871891472s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.116568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:48.244466Z","time spent":"1.872092616s","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:07:50.11658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.166127411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-14T03:07:50.116633Z","caller":"traceutil/trace.go:171","msg":"trace[899312110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3679; }","duration":"2.166193113s","start":"2026-05-14T03:07:47.950431Z","end":"2026-05-14T03:07:50.116624Z","steps":["trace[899312110] 'agreement among raft nodes before linearized reading' (duration: 2.166099871s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.116661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.950426Z","time spent":"2.166226924s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:50.116703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589294047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-14T03:07:50.116745Z","caller":"traceutil/trace.go:171","msg":"trace[1769704825] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3679; }","duration":"1.589388769s","start":"2026-05-14T03:07:48.527348Z","end":"2026-05-14T03:07:50.116737Z","steps":["trace[1769704825] 'agreement among raft nodes before linearized reading' (duration: 1.589254996s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.116429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.166012639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-14T03:07:50.11682Z","caller":"traceutil/trace.go:171","msg":"trace[1702414141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3679; }","duration":"2.166427489s","start":"2026-05-14T03:07:47.950381Z","end":"2026-05-14T03:07:50.116809Z","steps":["trace[1702414141] 'agreement among raft nodes before linearized reading' (duration: 2.165959189s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.117001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.950369Z","time spent":"2.166618912s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T03:07:50.116496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.165666031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-14T03:07:50.11701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.166038789s","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-05-14T03:07:50.117302Z","caller":"traceutil/trace.go:171","msg":"trace[1579327300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3679; }","duration":"2.166378387s","start":"2026-05-14T03:07:47.950912Z","end":"2026-05-14T03:07:50.11729Z","steps":["trace[1579327300] 'agreement among raft nodes before linearized reading' (duration: 2.165461217s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:50.117287Z","caller":"traceutil/trace.go:171","msg":"trace[1563469483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3679; }","duration":"2.166444979s","start":"2026-05-14T03:07:47.950806Z","end":"2026-05-14T03:07:50.117251Z","steps":["trace[1563469483] 'agreement among raft nodes before linearized reading' (duration: 2.165641272s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.117456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.950797Z","time spent":"2.166642882s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T03:07:50.116839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.165762355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-14T03:07:50.11757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.950894Z","time spent":"2.166434108s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T03:07:50.117586Z","caller":"traceutil/trace.go:171","msg":"trace[478334561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3679; }","duration":"2.16651477s","start":"2026-05-14T03:07:47.951059Z","end":"2026-05-14T03:07:50.117574Z","steps":["trace[478334561] 'agreement among raft nodes before linearized reading' (duration: 2.165731703s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.117623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:47.951015Z","time spent":"2.166598052s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:50.116769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:48.527328Z","time spent":"1.589433889s","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-14T03:07:50.116417Z","caller":"traceutil/trace.go:171","msg":"trace[2070774711] transaction","detail":"{read_only:false; response_revision:3679; number_of_response:1; }","duration":"620.108268ms","start":"2026-05-14T03:07:49.496284Z","end":"2026-05-14T03:07:50.116393Z","steps":["trace[2070774711] 'process raft request' (duration: 619.990125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.117904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:49.496232Z","time spent":"621.517207ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:50.331663Z","caller":"traceutil/trace.go:171","msg":"trace[997883433] transaction","detail":"{read_only:false; response_revision:3680; number_of_response:1; }","duration":"210.852122ms","start":"2026-05-14T03:07:50.120792Z","end":"2026-05-14T03:07:50.331645Z","steps":["trace[997883433] 'process raft request' (duration: 117.076598ms)","trace[997883433] 'compare' (duration: 93.557259ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:50.331727Z","caller":"traceutil/trace.go:171","msg":"trace[1508811156] transaction","detail":"{read_only:false; response_revision:3685; number_of_response:1; }","duration":"140.483812ms","start":"2026-05-14T03:07:50.191221Z","end":"2026-05-14T03:07:50.331705Z","steps":["trace[1508811156] 'process raft request' (duration: 140.451671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.331778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.673247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:50.331663Z","caller":"traceutil/trace.go:171","msg":"trace[965736761] linearizableReadLoop","detail":"{readStateIndex:3814; appliedIndex:3813; }","duration":"210.540844ms","start":"2026-05-14T03:07:50.1211Z","end":"2026-05-14T03:07:50.33164Z","steps":["trace[965736761] 'read index received' (duration: 116.72712ms)","trace[965736761] 'applied index is now lower than readState.Index' (duration: 93.811334ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:50.331813Z","caller":"traceutil/trace.go:171","msg":"trace[424985277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3685; }","duration":"211.410403ms","start":"2026-05-14T03:07:50.120395Z","end":"2026-05-14T03:07:50.331806Z","steps":["trace[424985277] 'agreement among raft nodes before linearized reading' (duration: 211.309871ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:50.331871Z","caller":"traceutil/trace.go:171","msg":"trace[1004112030] transaction","detail":"{read_only:false; response_revision:3681; number_of_response:1; }","duration":"210.530185ms","start":"2026-05-14T03:07:50.121333Z","end":"2026-05-14T03:07:50.331864Z","steps":["trace[1004112030] 'process raft request' (duration: 210.217847ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:50.331971Z","caller":"traceutil/trace.go:171","msg":"trace[882745063] transaction","detail":"{read_only:false; response_revision:3683; number_of_response:1; }","duration":"209.85719ms","start":"2026-05-14T03:07:50.122106Z","end":"2026-05-14T03:07:50.331963Z","steps":["trace[882745063] 'process raft request' (duration: 209.512333ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:50.332036Z","caller":"traceutil/trace.go:171","msg":"trace[1936260577] transaction","detail":"{read_only:false; response_revision:3682; number_of_response:1; }","duration":"210.403092ms","start":"2026-05-14T03:07:50.121625Z","end":"2026-05-14T03:07:50.332028Z","steps":["trace[1936260577] 'process raft request' (duration: 209.966112ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:50.332073Z","caller":"traceutil/trace.go:171","msg":"trace[1257928953] transaction","detail":"{read_only:false; response_revision:3684; number_of_response:1; }","duration":"208.519441ms","start":"2026-05-14T03:07:50.123527Z","end":"2026-05-14T03:07:50.332046Z","steps":["trace[1257928953] 'process raft request' (duration: 208.125143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:50.332297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.588125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-14T03:07:50.332371Z","caller":"traceutil/trace.go:171","msg":"trace[1093717499] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3685; }","duration":"210.635196ms","start":"2026-05-14T03:07:50.121684Z","end":"2026-05-14T03:07:50.332319Z","steps":["trace[1093717499] 'agreement among raft nodes before linearized reading' (duration: 210.34176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:51.165669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767344395989144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:07:53.244726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000166588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-14T03:07:53.244815Z","caller":"traceutil/trace.go:171","msg":"trace[77590226] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000318022s","start":"2026-05-14T03:07:51.244478Z","end":"2026-05-14T03:07:53.244796Z","steps":["trace[77590226] 'agreement among raft nodes before linearized reading' (duration: 2.000194689s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:53.244863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:51.244459Z","time spent":"2.000391712s","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:07:54.661371Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.547970578s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-14T03:07:54.986883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000157938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-14T03:07:54.986978Z","caller":"traceutil/trace.go:171","msg":"trace[1296133186] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000320552s","start":"2026-05-14T03:07:52.986636Z","end":"2026-05-14T03:07:54.986957Z","steps":["trace[1296133186] 'agreement among raft nodes before linearized reading' (duration: 2.000204819s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:54.987239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.986608Z","time spent":"2.000612078s","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/14 03:07:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-14T03:07:55.245955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000389276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-14T03:07:55.246069Z","caller":"traceutil/trace.go:171","msg":"trace[729475805] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000548609s","start":"2026-05-14T03:07:53.245498Z","end":"2026-05-14T03:07:55.246047Z","steps":["trace[729475805] 'agreement among raft nodes before linearized reading' (duration: 2.000412876s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:55.246126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:53.245487Z","time spent":"2.000628791s","remote":"127.0.0.1:55668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/14 03:07:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-14T03:07:55.887022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.470009ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:55.88716Z","caller":"traceutil/trace.go:171","msg":"trace[479596623] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3685; }","duration":"353.634662ms","start":"2026-05-14T03:07:55.533504Z","end":"2026-05-14T03:07:55.887138Z","steps":["trace[479596623] 'range keys from in-memory index tree' (duration: 353.456619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.125295Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.463575428s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-14T03:07:56.425904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.980003737s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-14T03:07:56.42617Z","caller":"traceutil/trace.go:171","msg":"trace[846764721] linearizableReadLoop","detail":"{readStateIndex:3820; appliedIndex:3819; }","duration":"5.760580722s","start":"2026-05-14T03:07:50.665523Z","end":"2026-05-14T03:07:56.426104Z","steps":["trace[846764721] 'read index received' (duration: 780.35213ms)","trace[846764721] 'applied index is now lower than readState.Index' (duration: 4.980227792s)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:56.426322Z","caller":"traceutil/trace.go:171","msg":"trace[1345817748] transaction","detail":"{read_only:false; response_revision:3686; number_of_response:1; }","duration":"4.313006927s","start":"2026-05-14T03:07:52.113298Z","end":"2026-05-14T03:07:56.426305Z","steps":["trace[1345817748] 'process raft request' (duration: 4.312715581s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.426426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.113275Z","time spent":"4.313097179s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:56.696241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.030695334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:56.696328Z","caller":"traceutil/trace.go:171","msg":"trace[1690723793] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:3686; }","duration":"6.030842647s","start":"2026-05-14T03:07:50.665459Z","end":"2026-05-14T03:07:56.696302Z","steps":["trace[1690723793] 'agreement among raft nodes before linearized reading' (duration: 5.760778326s)","trace[1690723793] 'count revisions from in-memory index tree' (duration: 269.944189ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:56.69638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:50.665434Z","time spent":"6.03093269s","remote":"127.0.0.1:41280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:56.696476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.057181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:07:56.696896Z","caller":"traceutil/trace.go:171","msg":"trace[1602651674] transaction","detail":"{read_only:false; response_revision:3688; number_of_response:1; }","duration":"4.583052318s","start":"2026-05-14T03:07:52.113832Z","end":"2026-05-14T03:07:56.696884Z","steps":["trace[1602651674] 'process raft request' (duration: 4.582744882s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.696957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.113744Z","time spent":"4.58317789s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:56.697029Z","caller":"traceutil/trace.go:171","msg":"trace[1702313732] transaction","detail":"{read_only:false; response_revision:3687; number_of_response:1; }","duration":"4.583390455s","start":"2026-05-14T03:07:52.113631Z","end":"2026-05-14T03:07:56.697021Z","steps":["trace[1702313732] 'process raft request' (duration: 4.31266734s)","trace[1702313732] 'compare' (duration: 269.911578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:56.697069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.113614Z","time spent":"4.583433416s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:56.697175Z","caller":"traceutil/trace.go:171","msg":"trace[1992596589] transaction","detail":"{read_only:false; response_revision:3690; number_of_response:1; }","duration":"4.359747763s","start":"2026-05-14T03:07:52.337403Z","end":"2026-05-14T03:07:56.697151Z","steps":["trace[1992596589] 'process raft request' (duration: 4.359309194s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:56.697265Z","caller":"traceutil/trace.go:171","msg":"trace[1988369768] transaction","detail":"{read_only:false; response_revision:3691; number_of_response:1; }","duration":"2.34755083s","start":"2026-05-14T03:07:54.349694Z","end":"2026-05-14T03:07:56.697245Z","steps":["trace[1988369768] 'process raft request' (duration: 2.347070239s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.697314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.337352Z","time spent":"4.359889027s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:56.697178Z","caller":"traceutil/trace.go:171","msg":"trace[2126684437] transaction","detail":"{read_only:false; response_revision:3689; number_of_response:1; }","duration":"4.448494885s","start":"2026-05-14T03:07:52.248656Z","end":"2026-05-14T03:07:56.697151Z","steps":["trace[2126684437] 'process raft request' (duration: 4.448003445s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.697505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.248633Z","time spent":"4.448815842s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:56.697513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:54.349667Z","time spent":"2.347674832s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:56.697897Z","caller":"traceutil/trace.go:171","msg":"trace[513565368] linearizableReadLoop","detail":"{readStateIndex:3827; appliedIndex:3820; }","duration":"271.678485ms","start":"2026-05-14T03:07:56.426199Z","end":"2026-05-14T03:07:56.697878Z","steps":["trace[513565368] 'read index received' (duration: 41.191µs)","trace[513565368] 'applied index is now lower than readState.Index' (duration: 271.635554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:56.697998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.912416452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:56.698052Z","caller":"traceutil/trace.go:171","msg":"trace[612576357] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy; range_end:; response_count:0; response_revision:3691; }","duration":"5.912503354s","start":"2026-05-14T03:07:50.785533Z","end":"2026-05-14T03:07:56.698036Z","steps":["trace[612576357] 'agreement among raft nodes before linearized reading' (duration: 5.912420412s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.698105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:50.785517Z","time spent":"5.912574925s","remote":"127.0.0.1:56138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":107,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy\" "} {"level":"warn","ts":"2026-05-14T03:07:56.698151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:54.989822Z","time spent":"1.708327937s","remote":"127.0.0.1:55782","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-14T03:07:56.698334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.864218024s","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-05-14T03:07:56.698396Z","caller":"traceutil/trace.go:171","msg":"trace[59549186] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3691; }","duration":"2.864319156s","start":"2026-05-14T03:07:53.834063Z","end":"2026-05-14T03:07:56.698382Z","steps":["trace[59549186] 'agreement among raft nodes before linearized reading' (duration: 2.864225794s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.69845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:53.834041Z","time spent":"2.864394509s","remote":"127.0.0.1:55288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:56.698421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.811156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:07:56.698565Z","caller":"traceutil/trace.go:171","msg":"trace[1619901072] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3691; }","duration":"325.00481ms","start":"2026-05-14T03:07:56.373544Z","end":"2026-05-14T03:07:56.698549Z","steps":["trace[1619901072] 'agreement among raft nodes before linearized reading' (duration: 324.801925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.698731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.919323123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:07:56.698792Z","caller":"traceutil/trace.go:171","msg":"trace[1547004125] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:3691; }","duration":"2.919432446s","start":"2026-05-14T03:07:53.779344Z","end":"2026-05-14T03:07:56.698777Z","steps":["trace[1547004125] 'agreement among raft nodes before linearized reading' (duration: 2.919326703s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.698823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.214524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:07:56.698838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:53.77932Z","time spent":"2.919504778s","remote":"127.0.0.1:56004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:07:56.698862Z","caller":"traceutil/trace.go:171","msg":"trace[2105119362] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:3691; }","duration":"946.311875ms","start":"2026-05-14T03:07:55.75254Z","end":"2026-05-14T03:07:56.698852Z","steps":["trace[2105119362] 'agreement among raft nodes before linearized reading' (duration: 946.231674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.698978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:55.752518Z","time spent":"946.445909ms","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:56.699014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331403269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:56.699058Z","caller":"traceutil/trace.go:171","msg":"trace[173760218] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:3691; }","duration":"1.331488821s","start":"2026-05-14T03:07:55.367558Z","end":"2026-05-14T03:07:56.699047Z","steps":["trace[173760218] 'agreement among raft nodes before linearized reading' (duration: 1.331418109s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.699064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.069842499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T03:07:56.699105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:55.367534Z","time spent":"1.331555751s","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:07:56.699114Z","caller":"traceutil/trace.go:171","msg":"trace[1303137128] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:3691; }","duration":"3.06995141s","start":"2026-05-14T03:07:53.629149Z","end":"2026-05-14T03:07:56.6991Z","steps":["trace[1303137128] 'agreement among raft nodes before linearized reading' (duration: 3.069874009s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.699157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:53.629117Z","time spent":"3.070028112s","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":31,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:56.699282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097817149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:07:56.69933Z","caller":"traceutil/trace.go:171","msg":"trace[1938708144] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:3691; }","duration":"1.097906401s","start":"2026-05-14T03:07:55.601413Z","end":"2026-05-14T03:07:56.69932Z","steps":["trace[1938708144] 'agreement among raft nodes before linearized reading' (duration: 1.09783549s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.699359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:55.601386Z","time spent":"1.097963842s","remote":"127.0.0.1:55996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":5,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:56.699389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.363073985s","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-05-14T03:07:56.699442Z","caller":"traceutil/trace.go:171","msg":"trace[14405459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3691; }","duration":"4.363174646s","start":"2026-05-14T03:07:52.336254Z","end":"2026-05-14T03:07:56.699429Z","steps":["trace[14405459] 'agreement among raft nodes before linearized reading' (duration: 4.363010352s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.699475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.336235Z","time spent":"4.363232498s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:56.699448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752054895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T03:07:56.699489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.362784118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-14T03:07:56.699553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183696146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:07:56.699615Z","caller":"traceutil/trace.go:171","msg":"trace[1761854108] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3691; }","duration":"1.183794788s","start":"2026-05-14T03:07:55.515803Z","end":"2026-05-14T03:07:56.699597Z","steps":["trace[1761854108] 'agreement among raft nodes before linearized reading' (duration: 1.183712646s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.699443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285944861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-14T03:07:56.699733Z","caller":"traceutil/trace.go:171","msg":"trace[781429056] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3691; }","duration":"1.286315519s","start":"2026-05-14T03:07:55.413406Z","end":"2026-05-14T03:07:56.699722Z","steps":["trace[781429056] 'agreement among raft nodes before linearized reading' (duration: 1.28589097s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.699694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344209291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-14T03:07:56.699752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445297441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:07:56.699766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:55.41337Z","time spent":"1.286387841s","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-14T03:07:56.699753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.36293498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:07:56.699792Z","caller":"traceutil/trace.go:171","msg":"trace[1880811630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3691; }","duration":"1.445369222s","start":"2026-05-14T03:07:55.254411Z","end":"2026-05-14T03:07:56.69978Z","steps":["trace[1880811630] 'agreement among raft nodes before linearized reading' (duration: 1.445311181s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:56.699784Z","caller":"traceutil/trace.go:171","msg":"trace[1694187795] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:3691; }","duration":"1.344341784s","start":"2026-05-14T03:07:55.355431Z","end":"2026-05-14T03:07:56.699773Z","steps":["trace[1694187795] 'agreement among raft nodes before linearized reading' (duration: 1.34415107s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:56.699833Z","caller":"traceutil/trace.go:171","msg":"trace[789355249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3691; }","duration":"4.363014463s","start":"2026-05-14T03:07:52.336797Z","end":"2026-05-14T03:07:56.699811Z","steps":["trace[789355249] 'agreement among raft nodes before linearized reading' (duration: 4.36286592s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.699832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:55.254388Z","time spent":"1.445434873s","remote":"127.0.0.1:55668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:07:56.699858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:55.355412Z","time spent":"1.344434386s","remote":"127.0.0.1:55714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.47\" "} {"level":"warn","ts":"2026-05-14T03:07:56.699615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.129852659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:07:56.69989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.336789Z","time spent":"4.363089075s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:56.699935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.731215629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:07:56.699996Z","caller":"traceutil/trace.go:171","msg":"trace[724195499] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3691; }","duration":"5.731357341s","start":"2026-05-14T03:07:50.968623Z","end":"2026-05-14T03:07:56.69998Z","steps":["trace[724195499] 'agreement among raft nodes before linearized reading' (duration: 5.731184808s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.700047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:50.968604Z","time spent":"5.731429813s","remote":"127.0.0.1:41238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:07:56.699533Z","caller":"traceutil/trace.go:171","msg":"trace[1897005229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3691; }","duration":"4.36292137s","start":"2026-05-14T03:07:52.3366Z","end":"2026-05-14T03:07:56.699521Z","steps":["trace[1897005229] 'agreement among raft nodes before linearized reading' (duration: 4.362782668s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.700219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.336563Z","time spent":"4.363540264s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:56.69811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928555806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:56.700295Z","caller":"traceutil/trace.go:171","msg":"trace[630914300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3691; }","duration":"1.930787533s","start":"2026-05-14T03:07:54.769496Z","end":"2026-05-14T03:07:56.700284Z","steps":["trace[630914300] 'agreement among raft nodes before linearized reading' (duration: 1.928486774s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.700337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:54.769468Z","time spent":"1.930859165s","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:07:56.700374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.363956153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-14T03:07:56.700435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.364074774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-14T03:07:56.700452Z","caller":"traceutil/trace.go:171","msg":"trace[947017243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3691; }","duration":"4.364049554s","start":"2026-05-14T03:07:52.336388Z","end":"2026-05-14T03:07:56.700438Z","steps":["trace[947017243] 'agreement among raft nodes before linearized reading' (duration: 4.363886331s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:56.700475Z","caller":"traceutil/trace.go:171","msg":"trace[2098953663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3691; }","duration":"4.364127085s","start":"2026-05-14T03:07:52.336339Z","end":"2026-05-14T03:07:56.700466Z","steps":["trace[2098953663] 'agreement among raft nodes before linearized reading' (duration: 4.364033744s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.698649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.373513Z","time spent":"325.122762ms","remote":"127.0.0.1:41244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:56.700507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.336331Z","time spent":"4.364168437s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T03:07:56.69991Z","caller":"traceutil/trace.go:171","msg":"trace[2017929010] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3691; }","duration":"4.130191467s","start":"2026-05-14T03:07:52.569709Z","end":"2026-05-14T03:07:56.6999Z","steps":["trace[2017929010] 'agreement among raft nodes before linearized reading' (duration: 4.12987554s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.700617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.569681Z","time spent":"4.130913661s","remote":"127.0.0.1:55924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:56.699687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:55.515781Z","time spent":"1.18388914s","remote":"127.0.0.1:41202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:56.700495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:52.336376Z","time spent":"4.364108246s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T03:07:56.69953Z","caller":"traceutil/trace.go:171","msg":"trace[2028936937] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:3691; }","duration":"1.752184767s","start":"2026-05-14T03:07:54.947331Z","end":"2026-05-14T03:07:56.699515Z","steps":["trace[2028936937] 'agreement among raft nodes before linearized reading' (duration: 1.752040175s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:56.700858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:54.947306Z","time spent":"1.753530537s","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:57.245638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767344395989165,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T03:07:57.715063Z","caller":"traceutil/trace.go:171","msg":"trace[1096672397] transaction","detail":"{read_only:false; response_revision:3693; number_of_response:1; }","duration":"1.008586884s","start":"2026-05-14T03:07:56.706452Z","end":"2026-05-14T03:07:57.715038Z","steps":["trace[1096672397] 'process raft request' (duration: 998.955718ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:57.715195Z","caller":"traceutil/trace.go:171","msg":"trace[1840689824] transaction","detail":"{read_only:false; response_revision:3694; number_of_response:1; }","duration":"1.008660766s","start":"2026-05-14T03:07:56.706494Z","end":"2026-05-14T03:07:57.715155Z","steps":["trace[1840689824] 'process raft request' (duration: 1.007920079s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:57.715314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.706444Z","time spent":"1.008682487s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:57.715338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.706491Z","time spent":"1.008770608s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:57.715646Z","caller":"traceutil/trace.go:171","msg":"trace[1710262773] transaction","detail":"{read_only:false; response_revision:3697; number_of_response:1; }","duration":"1.008525434s","start":"2026-05-14T03:07:56.707109Z","end":"2026-05-14T03:07:57.715634Z","steps":["trace[1710262773] 'process raft request' (duration: 1.008012563s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:57.715651Z","caller":"traceutil/trace.go:171","msg":"trace[1721783087] transaction","detail":"{read_only:false; response_revision:3698; number_of_response:1; }","duration":"1.008276958s","start":"2026-05-14T03:07:56.707358Z","end":"2026-05-14T03:07:57.715635Z","steps":["trace[1721783087] 'process raft request' (duration: 1.007792688s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:57.715694Z","caller":"traceutil/trace.go:171","msg":"trace[1807225716] transaction","detail":"{read_only:false; response_revision:3699; number_of_response:1; }","duration":"969.816039ms","start":"2026-05-14T03:07:56.745855Z","end":"2026-05-14T03:07:57.715671Z","steps":["trace[1807225716] 'process raft request' (duration: 969.507603ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:57.7157Z","caller":"traceutil/trace.go:171","msg":"trace[780772022] transaction","detail":"{read_only:false; response_revision:3695; number_of_response:1; }","duration":"1.009148696s","start":"2026-05-14T03:07:56.706534Z","end":"2026-05-14T03:07:57.715683Z","steps":["trace[780772022] 'process raft request' (duration: 1.008512763s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:57.715735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.707103Z","time spent":"1.008585774s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:57.715762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.707353Z","time spent":"1.008359619s","remote":"127.0.0.1:56138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2370,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T03:07:57.715794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.706523Z","time spent":"1.009233188s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:57.715905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.74584Z","time spent":"969.909251ms","remote":"127.0.0.1:55714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:57.716042Z","caller":"traceutil/trace.go:171","msg":"trace[1566997553] transaction","detail":"{read_only:false; response_revision:3696; number_of_response:1; }","duration":"1.00928896s","start":"2026-05-14T03:07:56.706691Z","end":"2026-05-14T03:07:57.71598Z","steps":["trace[1566997553] 'process raft request' (duration: 1.008399651s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:57.716119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.706683Z","time spent":"1.009409803s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:57.716369Z","caller":"traceutil/trace.go:171","msg":"trace[1467032462] transaction","detail":"{read_only:false; response_revision:3700; number_of_response:1; }","duration":"968.371038ms","start":"2026-05-14T03:07:56.747986Z","end":"2026-05-14T03:07:57.716357Z","steps":["trace[1467032462] 'process raft request' (duration: 967.553201ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:57.716787Z","caller":"traceutil/trace.go:171","msg":"trace[1535562046] linearizableReadLoop","detail":"{readStateIndex:3837; appliedIndex:3829; }","duration":"971.797941ms","start":"2026-05-14T03:07:56.74498Z","end":"2026-05-14T03:07:57.716778Z","steps":["trace[1535562046] 'read index received' (duration: 960.51427ms)","trace[1535562046] 'applied index is now lower than readState.Index' (duration: 11.283061ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:57.716922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009877093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:57.716966Z","caller":"traceutil/trace.go:171","msg":"trace[319058171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3701; }","duration":"1.009930854s","start":"2026-05-14T03:07:56.707025Z","end":"2026-05-14T03:07:57.716955Z","steps":["trace[319058171] 'agreement among raft nodes before linearized reading' (duration: 1.009861443s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:57.716999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.707018Z","time spent":"1.009973464s","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:07:57.717106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.612616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9426"} {"level":"info","ts":"2026-05-14T03:07:57.717153Z","caller":"traceutil/trace.go:171","msg":"trace[335181990] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:3701; }","duration":"970.672947ms","start":"2026-05-14T03:07:56.746473Z","end":"2026-05-14T03:07:57.717146Z","steps":["trace[335181990] 'agreement among raft nodes before linearized reading' (duration: 970.470003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:57.717219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.746467Z","time spent":"970.743258ms","remote":"127.0.0.1:55888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9450,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-14T03:07:57.717321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:56.747978Z","time spent":"968.42376ms","remote":"127.0.0.1:55782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T03:07:57.717222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.943446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-14T03:07:57.717504Z","caller":"traceutil/trace.go:171","msg":"trace[1820296122] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3701; }","duration":"286.261522ms","start":"2026-05-14T03:07:57.431236Z","end":"2026-05-14T03:07:57.717498Z","steps":["trace[1820296122] 'agreement among raft nodes before linearized reading' (duration: 285.922964ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:26.01718Z","caller":"traceutil/trace.go:171","msg":"trace[191458267] transaction","detail":"{read_only:false; response_revision:3920; number_of_response:1; }","duration":"126.632622ms","start":"2026-05-14T03:08:25.89053Z","end":"2026-05-14T03:08:26.017162Z","steps":["trace[191458267] 'process raft request' (duration: 126.588651ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:26.017342Z","caller":"traceutil/trace.go:171","msg":"trace[527231303] transaction","detail":"{read_only:false; response_revision:3918; number_of_response:1; }","duration":"129.065675ms","start":"2026-05-14T03:08:25.888264Z","end":"2026-05-14T03:08:26.017329Z","steps":["trace[527231303] 'process raft request' (duration: 103.746511ms)","trace[527231303] 'compare' (duration: 25.002557ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:08:26.017418Z","caller":"traceutil/trace.go:171","msg":"trace[984170382] transaction","detail":"{read_only:false; response_revision:3919; number_of_response:1; }","duration":"127.0423ms","start":"2026-05-14T03:08:25.890366Z","end":"2026-05-14T03:08:26.017409Z","steps":["trace[984170382] 'process raft request' (duration: 126.716353ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:01.404814Z","caller":"traceutil/trace.go:171","msg":"trace[366987254] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4581; }","duration":"103.147606ms","start":"2026-05-14T03:09:01.301643Z","end":"2026-05-14T03:09:01.40479Z","steps":["trace[366987254] 'process raft request' (duration: 102.980542ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:04.577936Z","caller":"traceutil/trace.go:171","msg":"trace[2112968214] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"100.171823ms","start":"2026-05-14T03:09:04.477752Z","end":"2026-05-14T03:09:04.577923Z","steps":["trace[2112968214] 'process raft request' (duration: 100.059541ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:11.735402Z","caller":"traceutil/trace.go:171","msg":"trace[1713573042] linearizableReadLoop","detail":"{readStateIndex:4959; appliedIndex:4958; }","duration":"485.227016ms","start":"2026-05-14T03:09:11.250137Z","end":"2026-05-14T03:09:11.735364Z","steps":["trace[1713573042] 'read index received' (duration: 484.785416ms)","trace[1713573042] 'applied index is now lower than readState.Index' (duration: 439.99µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:11.735476Z","caller":"traceutil/trace.go:171","msg":"trace[2122854977] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"485.443739ms","start":"2026-05-14T03:09:11.249987Z","end":"2026-05-14T03:09:11.73543Z","steps":["trace[2122854977] 'process raft request' (duration: 485.094261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:11.735701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.539033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61055"} {"level":"info","ts":"2026-05-14T03:09:11.73578Z","caller":"traceutil/trace.go:171","msg":"trace[721816408] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4780; }","duration":"485.703915ms","start":"2026-05-14T03:09:11.250059Z","end":"2026-05-14T03:09:11.735763Z","steps":["trace[721816408] 'agreement among raft nodes before linearized reading' (duration: 485.394928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:11.735828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:11.250009Z","time spent":"485.810588ms","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":61079,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-05-14T03:09:11.735706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:11.249969Z","time spent":"485.618174ms","remote":"127.0.0.1:55782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:12.236233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767344395991527,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T03:09:12.509501Z","caller":"traceutil/trace.go:171","msg":"trace[2025868085] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"1.25275507s","start":"2026-05-14T03:09:11.256724Z","end":"2026-05-14T03:09:12.509479Z","steps":["trace[2025868085] 'process raft request' (duration: 1.252622926s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.509796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:11.256714Z","time spent":"1.252854913s","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:09:12.562996Z","caller":"traceutil/trace.go:171","msg":"trace[13815522] linearizableReadLoop","detail":"{readStateIndex:4961; appliedIndex:4959; }","duration":"827.455879ms","start":"2026-05-14T03:09:11.735513Z","end":"2026-05-14T03:09:12.562968Z","steps":["trace[13815522] 'read index received' (duration: 773.812337ms)","trace[13815522] 'applied index is now lower than readState.Index' (duration: 53.642182ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:12.563054Z","caller":"traceutil/trace.go:171","msg":"trace[65381086] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"814.502936ms","start":"2026-05-14T03:09:11.748508Z","end":"2026-05-14T03:09:12.563011Z","steps":["trace[65381086] 'process raft request' (duration: 814.298422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.563244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01710702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e52c4fc9-997e-4c86-862c-9aa28f818faf\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-05-14T03:09:12.563271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:11.74848Z","time spent":"814.660719ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7566,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:09:12.56334Z","caller":"traceutil/trace.go:171","msg":"trace[792543364] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e52c4fc9-997e-4c86-862c-9aa28f818faf; range_end:; response_count:1; response_revision:4782; }","duration":"1.017214243s","start":"2026-05-14T03:09:11.546106Z","end":"2026-05-14T03:09:12.56332Z","steps":["trace[792543364] 'agreement among raft nodes before linearized reading' (duration: 1.016958467s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.563384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:11.546078Z","time spent":"1.017295485s","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e52c4fc9-997e-4c86-862c-9aa28f818faf\" "} {"level":"warn","ts":"2026-05-14T03:09:12.563437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259946724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-14T03:09:12.563608Z","caller":"traceutil/trace.go:171","msg":"trace[180802884] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4782; }","duration":"1.260131818s","start":"2026-05-14T03:09:11.303401Z","end":"2026-05-14T03:09:12.563533Z","steps":["trace[180802884] 'agreement among raft nodes before linearized reading' (duration: 1.25979927s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.563712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:11.303388Z","time spent":"1.260304441s","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-14T03:09:12.673687Z","caller":"traceutil/trace.go:171","msg":"trace[1466990567] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"156.825363ms","start":"2026-05-14T03:09:12.516839Z","end":"2026-05-14T03:09:12.673665Z","steps":["trace[1466990567] 'process raft request' (duration: 156.72195ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:12.673971Z","caller":"traceutil/trace.go:171","msg":"trace[404436831] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"157.483878ms","start":"2026-05-14T03:09:12.516436Z","end":"2026-05-14T03:09:12.67392Z","steps":["trace[404436831] 'process raft request' (duration: 118.753423ms)","trace[404436831] 'compare' (duration: 38.226643ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:12.674106Z","caller":"traceutil/trace.go:171","msg":"trace[1362626399] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4961; }","duration":"110.994469ms","start":"2026-05-14T03:09:12.563098Z","end":"2026-05-14T03:09:12.674093Z","steps":["trace[1362626399] 'read index received' (duration: 72.023828ms)","trace[1362626399] 'applied index is now lower than readState.Index' (duration: 38.969251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:09:12.67438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.271385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T03:09:12.674454Z","caller":"traceutil/trace.go:171","msg":"trace[1757416471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4786; }","duration":"436.391318ms","start":"2026-05-14T03:09:12.238043Z","end":"2026-05-14T03:09:12.674435Z","steps":["trace[1757416471] 'agreement among raft nodes before linearized reading' (duration: 436.186704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.674658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.856404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-756bac21-c05b-4fa8-a299-8e0f26a165ca\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-14T03:09:12.67473Z","caller":"traceutil/trace.go:171","msg":"trace[601796674] range","detail":"{range_begin:/registry/persistentvolumes/pvc-756bac21-c05b-4fa8-a299-8e0f26a165ca; range_end:; response_count:1; response_revision:4786; }","duration":"106.955256ms","start":"2026-05-14T03:09:12.567753Z","end":"2026-05-14T03:09:12.674708Z","steps":["trace[601796674] 'agreement among raft nodes before linearized reading' (duration: 106.792442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.674781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.213882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:09:12.674837Z","caller":"traceutil/trace.go:171","msg":"trace[1348344879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4786; }","duration":"431.290713ms","start":"2026-05-14T03:09:12.243531Z","end":"2026-05-14T03:09:12.674822Z","steps":["trace[1348344879] 'agreement among raft nodes before linearized reading' (duration: 431.18583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.674881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.243517Z","time spent":"431.356045ms","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:09:12.675033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.302019ms","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-05-14T03:09:12.675111Z","caller":"traceutil/trace.go:171","msg":"trace[1499026879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4786; }","duration":"584.424762ms","start":"2026-05-14T03:09:12.090668Z","end":"2026-05-14T03:09:12.675093Z","steps":["trace[1499026879] 'agreement among raft nodes before linearized reading' (duration: 584.246748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.675161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.090647Z","time spent":"584.498684ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T03:09:12.675165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.760855ms","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-05-14T03:09:12.675218Z","caller":"traceutil/trace.go:171","msg":"trace[252965875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4786; }","duration":"435.834356ms","start":"2026-05-14T03:09:12.23937Z","end":"2026-05-14T03:09:12.675204Z","steps":["trace[252965875] 'agreement among raft nodes before linearized reading' (duration: 435.702263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.675269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.239355Z","time spent":"435.902877ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T03:09:12.674557Z","caller":"traceutil/trace.go:171","msg":"trace[565629547] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"157.486677ms","start":"2026-05-14T03:09:12.517057Z","end":"2026-05-14T03:09:12.674543Z","steps":["trace[565629547] 'process raft request' (duration: 156.800062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.674394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.84632ms","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-05-14T03:09:12.675752Z","caller":"traceutil/trace.go:171","msg":"trace[931902391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4786; }","duration":"439.260853ms","start":"2026-05-14T03:09:12.236471Z","end":"2026-05-14T03:09:12.675732Z","steps":["trace[931902391] 'agreement among raft nodes before linearized reading' (duration: 437.77655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.675817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.236452Z","time spent":"439.349605ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T03:09:12.675845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.238026Z","time spent":"437.8005ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:09:12.674447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.974404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:09:12.675998Z","caller":"traceutil/trace.go:171","msg":"trace[1941627668] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:4786; }","duration":"142.56562ms","start":"2026-05-14T03:09:12.533415Z","end":"2026-05-14T03:09:12.67598Z","steps":["trace[1941627668] 'agreement among raft nodes before linearized reading' (duration: 140.913802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.676491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.461482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T03:09:12.676721Z","caller":"traceutil/trace.go:171","msg":"trace[2078429186] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4786; }","duration":"545.717308ms","start":"2026-05-14T03:09:12.130983Z","end":"2026-05-14T03:09:12.676701Z","steps":["trace[2078429186] 'agreement among raft nodes before linearized reading' (duration: 543.449087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:12.676877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.130966Z","time spent":"545.892911ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T03:09:12.674Z","caller":"traceutil/trace.go:171","msg":"trace[714995726] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"157.004627ms","start":"2026-05-14T03:09:12.516971Z","end":"2026-05-14T03:09:12.673975Z","steps":["trace[714995726] 'process raft request' (duration: 156.658129ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:13.30485Z","caller":"traceutil/trace.go:171","msg":"trace[1539564943] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"402.947956ms","start":"2026-05-14T03:09:12.901872Z","end":"2026-05-14T03:09:13.30482Z","steps":["trace[1539564943] 'process raft request' (duration: 402.705171ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:13.304836Z","caller":"traceutil/trace.go:171","msg":"trace[1013336553] linearizableReadLoop","detail":"{readStateIndex:4973; appliedIndex:4972; }","duration":"365.927119ms","start":"2026-05-14T03:09:12.938877Z","end":"2026-05-14T03:09:13.304804Z","steps":["trace[1013336553] 'read index received' (duration: 365.759616ms)","trace[1013336553] 'applied index is now lower than readState.Index' (duration: 165.973µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:09:13.305001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.100114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:09:13.306631Z","caller":"traceutil/trace.go:171","msg":"trace[1440783497] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4794; }","duration":"367.746222ms","start":"2026-05-14T03:09:12.938855Z","end":"2026-05-14T03:09:13.306602Z","steps":["trace[1440783497] 'agreement among raft nodes before linearized reading' (duration: 366.038962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:13.306749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.938788Z","time spent":"367.910805ms","remote":"127.0.0.1:41188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:09:13.305363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.901843Z","time spent":"403.375186ms","remote":"127.0.0.1:55782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T03:09:13.323232Z","caller":"traceutil/trace.go:171","msg":"trace[2119880907] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"367.353762ms","start":"2026-05-14T03:09:12.955855Z","end":"2026-05-14T03:09:13.323209Z","steps":["trace[2119880907] 'process raft request' (duration: 367.101827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:13.323347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.955828Z","time spent":"367.456024ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:09:13.323352Z","caller":"traceutil/trace.go:171","msg":"trace[235475922] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"341.777674ms","start":"2026-05-14T03:09:12.981552Z","end":"2026-05-14T03:09:13.32333Z","steps":["trace[235475922] 'process raft request' (duration: 341.569799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:13.323457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.981526Z","time spent":"341.881857ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:13.323484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.556818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-14T03:09:13.323539Z","caller":"traceutil/trace.go:171","msg":"trace[1475978919] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4797; }","duration":"325.657421ms","start":"2026-05-14T03:09:12.997869Z","end":"2026-05-14T03:09:13.323527Z","steps":["trace[1475978919] 'agreement among raft nodes before linearized reading' (duration: 325.476526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:13.323581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.997855Z","time spent":"325.718942ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-14T03:09:13.323243Z","caller":"traceutil/trace.go:171","msg":"trace[1279937019] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"345.407255ms","start":"2026-05-14T03:09:12.977812Z","end":"2026-05-14T03:09:13.32322Z","steps":["trace[1279937019] 'process raft request' (duration: 345.268753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:13.323906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:12.977788Z","time spent":"346.03048ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:09:13.671431Z","caller":"traceutil/trace.go:171","msg":"trace[107452994] linearizableReadLoop","detail":"{readStateIndex:4977; appliedIndex:4976; }","duration":"348.066628ms","start":"2026-05-14T03:09:13.323306Z","end":"2026-05-14T03:09:13.671372Z","steps":["trace[107452994] 'read index received' (duration: 304.480032ms)","trace[107452994] 'applied index is now lower than readState.Index' (duration: 43.584106ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:13.6715Z","caller":"traceutil/trace.go:171","msg":"trace[759201337] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"359.832323ms","start":"2026-05-14T03:09:13.311627Z","end":"2026-05-14T03:09:13.67146Z","steps":["trace[759201337] 'process raft request' (duration: 316.145505ms)","trace[759201337] 'compare' (duration: 43.170087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:09:13.671691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:13.311609Z","time spent":"359.955495ms","remote":"127.0.0.1:55782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T03:09:13.671904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.761419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-05-14T03:09:13.672024Z","caller":"traceutil/trace.go:171","msg":"trace[1579654920] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4798; }","duration":"358.937683ms","start":"2026-05-14T03:09:13.31306Z","end":"2026-05-14T03:09:13.671998Z","steps":["trace[1579654920] 'agreement among raft nodes before linearized reading' (duration: 358.476783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:13.672081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:13.313046Z","time spent":"359.021434ms","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16255,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-05-14T03:09:13.708461Z","caller":"traceutil/trace.go:171","msg":"trace[1425608980] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"381.081873ms","start":"2026-05-14T03:09:13.327355Z","end":"2026-05-14T03:09:13.708437Z","steps":["trace[1425608980] 'process raft request' (duration: 380.94968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:13.708609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:13.327329Z","time spent":"381.206927ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:13.708864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.99545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-14T03:09:13.708923Z","caller":"traceutil/trace.go:171","msg":"trace[1591357503] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4799; }","duration":"323.100023ms","start":"2026-05-14T03:09:13.385811Z","end":"2026-05-14T03:09:13.708911Z","steps":["trace[1591357503] 'agreement among raft nodes before linearized reading' (duration: 322.864157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:13.708949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:13.385695Z","time spent":"323.246736ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-14T03:09:13.709071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.22423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e52c4fc9-997e-4c86-862c-9aa28f818faf\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-05-14T03:09:13.709207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.626992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-14T03:09:13.710042Z","caller":"traceutil/trace.go:171","msg":"trace[781416630] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4799; }","duration":"269.02796ms","start":"2026-05-14T03:09:13.440503Z","end":"2026-05-14T03:09:13.709531Z","steps":["trace[781416630] 'agreement among raft nodes before linearized reading' (duration: 268.262903ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:13.710311Z","caller":"traceutil/trace.go:171","msg":"trace[841371103] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e52c4fc9-997e-4c86-862c-9aa28f818faf; range_end:; response_count:1; response_revision:4799; }","duration":"140.431005ms","start":"2026-05-14T03:09:13.56872Z","end":"2026-05-14T03:09:13.709151Z","steps":["trace[841371103] 'agreement among raft nodes before linearized reading' (duration: 140.020576ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:14.056414Z","caller":"traceutil/trace.go:171","msg":"trace[1089499939] linearizableReadLoop","detail":"{readStateIndex:4980; appliedIndex:4978; }","duration":"343.855483ms","start":"2026-05-14T03:09:13.712533Z","end":"2026-05-14T03:09:14.056389Z","steps":["trace[1089499939] 'read index received' (duration: 343.560656ms)","trace[1089499939] 'applied index is now lower than readState.Index' (duration: 293.787µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:14.056724Z","caller":"traceutil/trace.go:171","msg":"trace[124709544] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"380.642594ms","start":"2026-05-14T03:09:13.67588Z","end":"2026-05-14T03:09:14.056522Z","steps":["trace[124709544] 'process raft request' (duration: 380.279186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:14.056841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:13.675852Z","time spent":"380.91703ms","remote":"127.0.0.1:55782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T03:09:14.057119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.560999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3a344f8e-d0ba-4102-aebf-c007b016c6e9\" ","response":"range_response_count:1 size:1435"} {"level":"warn","ts":"2026-05-14T03:09:14.057218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.769121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:09:14.057244Z","caller":"traceutil/trace.go:171","msg":"trace[480469079] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3a344f8e-d0ba-4102-aebf-c007b016c6e9; range_end:; response_count:1; response_revision:4801; }","duration":"344.703532ms","start":"2026-05-14T03:09:13.71251Z","end":"2026-05-14T03:09:14.057213Z","steps":["trace[480469079] 'agreement among raft nodes before linearized reading' (duration: 344.398365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:14.057329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:13.712497Z","time spent":"344.815705ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1459,"request content":"key:\"/registry/persistentvolumes/pvc-3a344f8e-d0ba-4102-aebf-c007b016c6e9\" "} {"level":"info","ts":"2026-05-14T03:09:14.057266Z","caller":"traceutil/trace.go:171","msg":"trace[343675759] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4801; }","duration":"301.873723ms","start":"2026-05-14T03:09:13.755374Z","end":"2026-05-14T03:09:14.057248Z","steps":["trace[343675759] 'agreement among raft nodes before linearized reading' (duration: 301.796991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:14.057947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:13.755354Z","time spent":"302.516098ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:09:14.058256Z","caller":"traceutil/trace.go:171","msg":"trace[1916912981] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"370.575936ms","start":"2026-05-14T03:09:13.687666Z","end":"2026-05-14T03:09:14.058242Z","steps":["trace[1916912981] 'process raft request' (duration: 368.652552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:14.058583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:13.687641Z","time spent":"370.771011ms","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:14.05955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.173223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-14T03:09:14.059896Z","caller":"traceutil/trace.go:171","msg":"trace[624594476] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4801; }","duration":"185.526314ms","start":"2026-05-14T03:09:13.874069Z","end":"2026-05-14T03:09:14.059595Z","steps":["trace[624594476] 'agreement among raft nodes before linearized reading' (duration: 183.657612ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:17.485239Z","caller":"traceutil/trace.go:171","msg":"trace[819300254] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"149.130447ms","start":"2026-05-14T03:09:17.336089Z","end":"2026-05-14T03:09:17.48522Z","steps":["trace[819300254] 'process raft request' (duration: 148.994434ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:17.485671Z","caller":"traceutil/trace.go:171","msg":"trace[268781966] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"146.184472ms","start":"2026-05-14T03:09:17.339474Z","end":"2026-05-14T03:09:17.485659Z","steps":["trace[268781966] 'process raft request' (duration: 146.08731ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:17.485825Z","caller":"traceutil/trace.go:171","msg":"trace[1717096909] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"144.984515ms","start":"2026-05-14T03:09:17.34075Z","end":"2026-05-14T03:09:17.485734Z","steps":["trace[1717096909] 'process raft request' (duration: 144.865232ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:22.746385Z","caller":"traceutil/trace.go:171","msg":"trace[2027324977] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5112; }","duration":"297.894766ms","start":"2026-05-14T03:09:22.448471Z","end":"2026-05-14T03:09:22.746366Z","steps":["trace[2027324977] 'read index received' (duration: 297.672791ms)","trace[2027324977] 'applied index is now lower than readState.Index' (duration: 221.245µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:22.746533Z","caller":"traceutil/trace.go:171","msg":"trace[509087761] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"323.892296ms","start":"2026-05-14T03:09:22.422629Z","end":"2026-05-14T03:09:22.746522Z","steps":["trace[509087761] 'process raft request' (duration: 323.553598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:22.746689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:22.422609Z","time spent":"323.997338ms","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13835,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:22.74686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.374277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:09:22.746964Z","caller":"traceutil/trace.go:171","msg":"trace[1577859936] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4928; }","duration":"298.54184ms","start":"2026-05-14T03:09:22.448402Z","end":"2026-05-14T03:09:22.746944Z","steps":["trace[1577859936] 'agreement among raft nodes before linearized reading' (duration: 298.374626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:22.746874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.902934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-14T03:09:22.74723Z","caller":"traceutil/trace.go:171","msg":"trace[133702152] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4928; }","duration":"253.297181ms","start":"2026-05-14T03:09:22.493916Z","end":"2026-05-14T03:09:22.747213Z","steps":["trace[133702152] 'agreement among raft nodes before linearized reading' (duration: 252.830181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:28.011342Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767344395991976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:09:28.512363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767344395991976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:09:29.013503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767344395991976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:09:29.088008Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.623862562s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T03:09:29.088666Z","caller":"traceutil/trace.go:171","msg":"trace[632973850] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"1.624657131s","start":"2026-05-14T03:09:27.463972Z","end":"2026-05-14T03:09:29.088629Z","steps":["trace[632973850] 'process raft request' (duration: 1.624277712s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.088875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:27.463943Z","time spent":"1.624823604s","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17215,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:09:29.381094Z","caller":"traceutil/trace.go:171","msg":"trace[832237827] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"1.854279335s","start":"2026-05-14T03:09:27.526784Z","end":"2026-05-14T03:09:29.381063Z","steps":["trace[832237827] 'process raft request' (duration: 1.853860035s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:29.38118Z","caller":"traceutil/trace.go:171","msg":"trace[288875894] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5165; }","duration":"1.870664348s","start":"2026-05-14T03:09:27.510477Z","end":"2026-05-14T03:09:29.381141Z","steps":["trace[288875894] 'read index received' (duration: 1.577813004s)","trace[288875894] 'applied index is now lower than readState.Index' (duration: 292.849994ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:29.381297Z","caller":"traceutil/trace.go:171","msg":"trace[1733671246] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"463.777878ms","start":"2026-05-14T03:09:28.917512Z","end":"2026-05-14T03:09:29.38129Z","steps":["trace[1733671246] 'process raft request' (duration: 463.559553ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:29.381332Z","caller":"traceutil/trace.go:171","msg":"trace[919879260] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"1.855635767s","start":"2026-05-14T03:09:27.525683Z","end":"2026-05-14T03:09:29.381319Z","steps":["trace[919879260] 'process raft request' (duration: 1.83339063s)","trace[919879260] 'compare' (duration: 21.216543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:09:29.381411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.917499Z","time spent":"463.833979ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:29.381413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:27.52567Z","time spent":"1.855693488s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:29.38146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.870952633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:09:29.381212Z","caller":"traceutil/trace.go:171","msg":"trace[1491488952] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"1.140770239s","start":"2026-05-14T03:09:28.240414Z","end":"2026-05-14T03:09:29.381184Z","steps":["trace[1491488952] 'process raft request' (duration: 1.140529223s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:29.381521Z","caller":"traceutil/trace.go:171","msg":"trace[1854017197] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:4987; }","duration":"1.871083746s","start":"2026-05-14T03:09:27.51042Z","end":"2026-05-14T03:09:29.381504Z","steps":["trace[1854017197] 'agreement among raft nodes before linearized reading' (duration: 1.870954244s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.381542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.240388Z","time spent":"1.141133936s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:29.381556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:27.510399Z","time spent":"1.871148028s","remote":"127.0.0.1:41152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:09:29.381233Z","caller":"traceutil/trace.go:171","msg":"trace[1615526992] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"1.531981207s","start":"2026-05-14T03:09:27.849231Z","end":"2026-05-14T03:09:29.381212Z","steps":["trace[1615526992] 'process raft request' (duration: 1.53166155s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.38162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:27.849211Z","time spent":"1.532388837s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:29.381632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.048353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-14T03:09:29.381641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.870527484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-05-14T03:09:29.381256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:27.52676Z","time spent":"1.854410117s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:09:29.381671Z","caller":"traceutil/trace.go:171","msg":"trace[489142586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4987; }","duration":"525.120715ms","start":"2026-05-14T03:09:28.856539Z","end":"2026-05-14T03:09:29.381659Z","steps":["trace[489142586] 'agreement among raft nodes before linearized reading' (duration: 524.998072ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:29.38128Z","caller":"traceutil/trace.go:171","msg":"trace[102016922] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"511.811253ms","start":"2026-05-14T03:09:28.869457Z","end":"2026-05-14T03:09:29.381268Z","steps":["trace[102016922] 'process raft request' (duration: 511.555317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.381732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.869436Z","time spent":"512.277723ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:09:29.381733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.85649Z","time spent":"525.225927ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:09:29.38182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.879386ms","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-05-14T03:09:29.381685Z","caller":"traceutil/trace.go:171","msg":"trace[670769615] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4987; }","duration":"1.870620917s","start":"2026-05-14T03:09:27.511049Z","end":"2026-05-14T03:09:29.38167Z","steps":["trace[670769615] 'agreement among raft nodes before linearized reading' (duration: 1.870454823s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:29.381857Z","caller":"traceutil/trace.go:171","msg":"trace[391140318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4987; }","duration":"573.948747ms","start":"2026-05-14T03:09:28.807899Z","end":"2026-05-14T03:09:29.381848Z","steps":["trace[391140318] 'agreement among raft nodes before linearized reading' (duration: 573.817864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.381889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.807883Z","time spent":"573.996759ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T03:09:29.381895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:27.511029Z","time spent":"1.870848612s","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-14T03:09:29.381966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.356709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-14T03:09:29.382027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.023752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-14T03:09:29.382039Z","caller":"traceutil/trace.go:171","msg":"trace[737237897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4987; }","duration":"528.474911ms","start":"2026-05-14T03:09:28.853546Z","end":"2026-05-14T03:09:29.382021Z","steps":["trace[737237897] 'agreement among raft nodes before linearized reading' (duration: 528.160364ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:29.382058Z","caller":"traceutil/trace.go:171","msg":"trace[726387311] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4987; }","duration":"669.086994ms","start":"2026-05-14T03:09:28.712961Z","end":"2026-05-14T03:09:29.382048Z","steps":["trace[726387311] 'agreement among raft nodes before linearized reading' (duration: 669.005742ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:29.381245Z","caller":"traceutil/trace.go:171","msg":"trace[1190754381] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"1.853855035s","start":"2026-05-14T03:09:27.527351Z","end":"2026-05-14T03:09:29.381206Z","steps":["trace[1190754381] 'process raft request' (duration: 1.853466166s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.382338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.898064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-14T03:09:29.382373Z","caller":"traceutil/trace.go:171","msg":"trace[1993541835] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4987; }","duration":"295.928665ms","start":"2026-05-14T03:09:29.086433Z","end":"2026-05-14T03:09:29.382362Z","steps":["trace[1993541835] 'agreement among raft nodes before linearized reading' (duration: 295.800351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.382092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.712929Z","time spent":"669.155666ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-14T03:09:29.382084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.853531Z","time spent":"528.542314ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T03:09:29.382148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08027868s","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-05-14T03:09:29.382809Z","caller":"traceutil/trace.go:171","msg":"trace[606968048] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:4987; }","duration":"1.080929765s","start":"2026-05-14T03:09:28.301858Z","end":"2026-05-14T03:09:29.382788Z","steps":["trace[606968048] 'agreement among raft nodes before linearized reading' (duration: 1.080254s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.382894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.301808Z","time spent":"1.081063678s","remote":"127.0.0.1:56064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:09:29.3822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138520538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:09:29.383115Z","caller":"traceutil/trace.go:171","msg":"trace[1669193798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4987; }","duration":"1.139468629s","start":"2026-05-14T03:09:28.243628Z","end":"2026-05-14T03:09:29.383097Z","steps":["trace[1669193798] 'agreement among raft nodes before linearized reading' (duration: 1.138537638s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.383165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.243582Z","time spent":"1.139567751s","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:09:29.382212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.219227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:09:29.383233Z","caller":"traceutil/trace.go:171","msg":"trace[1141237634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4987; }","duration":"515.23689ms","start":"2026-05-14T03:09:28.867983Z","end":"2026-05-14T03:09:29.38322Z","steps":["trace[1141237634] 'agreement among raft nodes before linearized reading' (duration: 514.143325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.383279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.867944Z","time spent":"515.322412ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:09:29.382255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.395461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-14T03:09:29.383481Z","caller":"traceutil/trace.go:171","msg":"trace[1505950192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4987; }","duration":"515.65289ms","start":"2026-05-14T03:09:28.867819Z","end":"2026-05-14T03:09:29.383472Z","steps":["trace[1505950192] 'agreement among raft nodes before linearized reading' (duration: 514.325689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.383517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:28.867803Z","time spent":"515.703451ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T03:09:29.382258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.489268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17230"} {"level":"info","ts":"2026-05-14T03:09:29.383615Z","caller":"traceutil/trace.go:171","msg":"trace[2098506332] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4987; }","duration":"283.88426ms","start":"2026-05-14T03:09:29.099711Z","end":"2026-05-14T03:09:29.383595Z","steps":["trace[2098506332] 'agreement among raft nodes before linearized reading' (duration: 282.473967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:29.38542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:09:27.527335Z","time spent":"1.854998952s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:10:42.452659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2932} {"level":"info","ts":"2026-05-14T03:10:42.537748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2932,"took":"83.670097ms","hash":3546946193,"current-db-size-bytes":53616640,"current-db-size":"54 MB","current-db-size-in-use-bytes":29093888,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-14T03:10:42.537814Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3546946193,"revision":2932,"compact-revision":-1} {"level":"info","ts":"2026-05-14T03:13:32.440735Z","caller":"traceutil/trace.go:171","msg":"trace[2579405] transaction","detail":"{read_only:false; response_revision:6749; number_of_response:1; }","duration":"115.607261ms","start":"2026-05-14T03:13:32.325112Z","end":"2026-05-14T03:13:32.440719Z","steps":["trace[2579405] 'process raft request' (duration: 115.380476ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:13:32.440933Z","caller":"traceutil/trace.go:171","msg":"trace[1606320372] linearizableReadLoop","detail":"{readStateIndex:6998; appliedIndex:6996; }","duration":"109.789422ms","start":"2026-05-14T03:13:32.330856Z","end":"2026-05-14T03:13:32.440645Z","steps":["trace[1606320372] 'read index received' (duration: 80.932051ms)","trace[1606320372] 'applied index is now lower than readState.Index' (duration: 28.856231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:13:32.440965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.076609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/shovels.rabbitmq.com\" ","response":"range_response_count:1 size:8339"} {"level":"info","ts":"2026-05-14T03:13:32.442306Z","caller":"traceutil/trace.go:171","msg":"trace[1275790836] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/shovels.rabbitmq.com; range_end:; response_count:1; response_revision:6750; }","duration":"111.436072ms","start":"2026-05-14T03:13:32.330839Z","end":"2026-05-14T03:13:32.442275Z","steps":["trace[1275790836] 'agreement among raft nodes before linearized reading' (duration: 110.013567ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:15:42.460895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5582} {"level":"info","ts":"2026-05-14T03:15:42.531491Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5582,"took":"69.397573ms","hash":2390887589,"current-db-size-bytes":53616640,"current-db-size":"54 MB","current-db-size-in-use-bytes":18399232,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-14T03:15:42.531544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2390887589,"revision":5582,"compact-revision":2932}