{"level":"warn","ts":"2026-04-22T16:28:16.647956Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:28:16.648052Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.147: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.147:2380","--initial-cluster=instance=https://199.19.213.147:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.147:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.147:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T16:28:16.648125Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:28:16.648138Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.147:2380"]} {"level":"info","ts":"2026-04-22T16:28:16.648154Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:28:16.648765Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.147:2379"]} {"level":"info","ts":"2026-04-22T16:28:16.648836Z","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":16,"max-cpu-available":16,"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.147:2380"],"listen-peer-urls":["https://199.19.213.147:2380"],"advertise-client-urls":["https://199.19.213.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.147:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T16:28:16.665861Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.818873ms"} {"level":"info","ts":"2026-04-22T16:28:16.720055Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6244e483e416226f","cluster-id":"68a374eab3e2de92"} {"level":"info","ts":"2026-04-22T16:28:16.720268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T16:28:16.720467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became follower at term 0"} {"level":"info","ts":"2026-04-22T16:28:16.720608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6244e483e416226f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T16:28:16.72107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became follower at term 1"} {"level":"info","ts":"2026-04-22T16:28:16.721457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f switched to configuration voters=(7081035769251766895)"} {"level":"warn","ts":"2026-04-22T16:28:16.754462Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T16:28:16.758273Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T16:28:16.760928Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T16:28:16.763515Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6244e483e416226f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T16:28:16.763672Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:28:16.763805Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:28:16.763816Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:28:16.764008Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6244e483e416226f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T16:28:16.766404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f switched to configuration voters=(7081035769251766895)"} {"level":"info","ts":"2026-04-22T16:28:16.766619Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"68a374eab3e2de92","local-member-id":"6244e483e416226f","added-peer-id":"6244e483e416226f","added-peer-peer-urls":["https://199.19.213.147:2380"]} {"level":"info","ts":"2026-04-22T16:28:16.768762Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:28:16.768942Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.147:2380"} {"level":"info","ts":"2026-04-22T16:28:16.768999Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.147:2380"} {"level":"info","ts":"2026-04-22T16:28:16.769078Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6244e483e416226f","initial-advertise-peer-urls":["https://199.19.213.147:2380"],"listen-peer-urls":["https://199.19.213.147:2380"],"advertise-client-urls":["https://199.19.213.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T16:28:16.769114Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T16:28:17.023098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T16:28:17.02317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T16:28:17.023205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f received MsgPreVoteResp from 6244e483e416226f at term 1"} {"level":"info","ts":"2026-04-22T16:28:17.023225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became candidate at term 2"} {"level":"info","ts":"2026-04-22T16:28:17.023236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f received MsgVoteResp from 6244e483e416226f at term 2"} {"level":"info","ts":"2026-04-22T16:28:17.023257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became leader at term 2"} {"level":"info","ts":"2026-04-22T16:28:17.023275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6244e483e416226f elected leader 6244e483e416226f at term 2"} {"level":"info","ts":"2026-04-22T16:28:17.023989Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:28:17.025874Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:28:17.025871Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6244e483e416226f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.147:2379]}","request-path":"/0/members/6244e483e416226f/attributes","cluster-id":"68a374eab3e2de92","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T16:28:17.025984Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:28:17.026284Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T16:28:17.026401Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T16:28:17.029415Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T16:28:17.029895Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.147:2379"} {"level":"info","ts":"2026-04-22T16:28:17.03155Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"68a374eab3e2de92","local-member-id":"6244e483e416226f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:28:17.031726Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:28:17.031772Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:28:36.983335Z","caller":"traceutil/trace.go:171","msg":"trace[533773306] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"188.291565ms","start":"2026-04-22T16:28:36.795017Z","end":"2026-04-22T16:28:36.983308Z","steps":["trace[533773306] 'process raft request' (duration: 188.161242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:48.805016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.172179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:28:48.805455Z","caller":"traceutil/trace.go:171","msg":"trace[1450899239] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"295.804419ms","start":"2026-04-22T16:28:48.509606Z","end":"2026-04-22T16:28:48.80541Z","steps":["trace[1450899239] 'process raft request' (duration: 134.319369ms)","trace[1450899239] 'compare' (duration: 159.952454ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:48.805452Z","caller":"traceutil/trace.go:171","msg":"trace[1034989070] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"284.838607ms","start":"2026-04-22T16:28:48.520592Z","end":"2026-04-22T16:28:48.805431Z","steps":["trace[1034989070] 'process raft request' (duration: 284.675973ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:48.805813Z","caller":"traceutil/trace.go:171","msg":"trace[455475749] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"284.906459ms","start":"2026-04-22T16:28:48.520894Z","end":"2026-04-22T16:28:48.8058Z","steps":["trace[455475749] 'process raft request' (duration: 284.469028ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:48.805927Z","caller":"traceutil/trace.go:171","msg":"trace[522537453] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"197.020773ms","start":"2026-04-22T16:28:48.60885Z","end":"2026-04-22T16:28:48.805871Z","steps":["trace[522537453] 'process raft request' (duration: 196.846639ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:55.414252Z","caller":"traceutil/trace.go:171","msg":"trace[1910361500] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"165.18761ms","start":"2026-04-22T16:28:55.24904Z","end":"2026-04-22T16:28:55.414228Z","steps":["trace[1910361500] 'read index received' (duration: 164.337129ms)","trace[1910361500] 'applied index is now lower than readState.Index' (duration: 849.481µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:55.414533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.468666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-22T16:28:55.414619Z","caller":"traceutil/trace.go:171","msg":"trace[1211841920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"165.61465ms","start":"2026-04-22T16:28:55.248986Z","end":"2026-04-22T16:28:55.4146Z","steps":["trace[1211841920] 'agreement among raft nodes before linearized reading' (duration: 165.373064ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:55.414914Z","caller":"traceutil/trace.go:171","msg":"trace[1412633997] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"263.166288ms","start":"2026-04-22T16:28:55.151154Z","end":"2026-04-22T16:28:55.414321Z","steps":["trace[1412633997] 'process raft request' (duration: 262.448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:55.97405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2481375324803684116,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:28:56.022335Z","caller":"traceutil/trace.go:171","msg":"trace[705432469] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"560.173637ms","start":"2026-04-22T16:28:55.462134Z","end":"2026-04-22T16:28:56.022308Z","steps":["trace[705432469] 'process raft request' (duration: 530.868263ms)","trace[705432469] 'compare' (duration: 28.945295ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:56.022424Z","caller":"traceutil/trace.go:171","msg":"trace[1783640368] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"548.922567ms","start":"2026-04-22T16:28:55.473483Z","end":"2026-04-22T16:28:56.022406Z","steps":["trace[1783640368] 'read index received' (duration: 519.532262ms)","trace[1783640368] 'applied index is now lower than readState.Index' (duration: 29.389405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:56.022471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:55.462104Z","time spent":"560.29359ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:56.022652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.363012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:28:56.022672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.193814ms","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-04-22T16:28:56.023406Z","caller":"traceutil/trace.go:171","msg":"trace[871323399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"549.961431ms","start":"2026-04-22T16:28:55.473433Z","end":"2026-04-22T16:28:56.023395Z","steps":["trace[871323399] 'agreement among raft nodes before linearized reading' (duration: 549.101051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:56.023453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:55.473415Z","time spent":"550.026523ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:28:56.022699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.334031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:56.023513Z","caller":"traceutil/trace.go:171","msg":"trace[2046675976] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:625; }","duration":"259.167342ms","start":"2026-04-22T16:28:55.76433Z","end":"2026-04-22T16:28:56.023497Z","steps":["trace[2046675976] 'agreement among raft nodes before linearized reading' (duration: 258.346292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:56.022709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.809427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:56.023693Z","caller":"traceutil/trace.go:171","msg":"trace[69489336] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:625; }","duration":"106.78417ms","start":"2026-04-22T16:28:55.916844Z","end":"2026-04-22T16:28:56.023628Z","steps":["trace[69489336] 'agreement among raft nodes before linearized reading' (duration: 105.808687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:56.022732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.410963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:56.023789Z","caller":"traceutil/trace.go:171","msg":"trace[318530668] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:625; }","duration":"259.484299ms","start":"2026-04-22T16:28:55.764294Z","end":"2026-04-22T16:28:56.023778Z","steps":["trace[318530668] 'agreement among raft nodes before linearized reading' (duration: 258.421294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:56.022736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.774485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:56.023484Z","caller":"traceutil/trace.go:171","msg":"trace[554382401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"293.956946ms","start":"2026-04-22T16:28:55.729265Z","end":"2026-04-22T16:28:56.023222Z","steps":["trace[554382401] 'agreement among raft nodes before linearized reading' (duration: 293.336181ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:56.023885Z","caller":"traceutil/trace.go:171","msg":"trace[2028912564] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:625; }","duration":"106.944333ms","start":"2026-04-22T16:28:55.916918Z","end":"2026-04-22T16:28:56.023862Z","steps":["trace[2028912564] 'agreement among raft nodes before linearized reading' (duration: 105.787266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:56.022762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.081609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:56.023972Z","caller":"traceutil/trace.go:171","msg":"trace[493032678] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:625; }","duration":"106.328629ms","start":"2026-04-22T16:28:55.917635Z","end":"2026-04-22T16:28:56.023963Z","steps":["trace[493032678] 'agreement among raft nodes before linearized reading' (duration: 105.017458ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:56.023019Z","caller":"traceutil/trace.go:171","msg":"trace[1812101700] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"541.12592ms","start":"2026-04-22T16:28:55.481338Z","end":"2026-04-22T16:28:56.022464Z","steps":["trace[1812101700] 'process raft request' (duration: 540.901915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:56.024083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:55.481312Z","time spent":"542.712167ms","remote":"127.0.0.1:46796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:29:11.772984Z","caller":"traceutil/trace.go:171","msg":"trace[38045833] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"243.418154ms","start":"2026-04-22T16:29:11.529536Z","end":"2026-04-22T16:29:11.772954Z","steps":["trace[38045833] 'read index received' (duration: 243.084596ms)","trace[38045833] 'applied index is now lower than readState.Index' (duration: 332.388µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:11.773035Z","caller":"traceutil/trace.go:171","msg":"trace[418718001] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"289.030077ms","start":"2026-04-22T16:29:11.483969Z","end":"2026-04-22T16:29:11.772999Z","steps":["trace[418718001] 'process raft request' (duration: 288.7216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:11.773243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.69297ms","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-04-22T16:29:11.773356Z","caller":"traceutil/trace.go:171","msg":"trace[1713710204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"243.867335ms","start":"2026-04-22T16:29:11.529464Z","end":"2026-04-22T16:29:11.773331Z","steps":["trace[1713710204] 'agreement among raft nodes before linearized reading' (duration: 243.588179ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:23.760256Z","caller":"traceutil/trace.go:171","msg":"trace[1682950971] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"132.186443ms","start":"2026-04-22T16:30:23.628035Z","end":"2026-04-22T16:30:23.760221Z","steps":["trace[1682950971] 'process raft request' (duration: 66.058196ms)","trace[1682950971] 'compare' (duration: 65.926392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:30:24.273994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.865923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:30:24.274111Z","caller":"traceutil/trace.go:171","msg":"trace[1697360368] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1907; }","duration":"360.532186ms","start":"2026-04-22T16:30:23.913563Z","end":"2026-04-22T16:30:24.274095Z","steps":["trace[1697360368] 'read index received' (duration: 134.427957ms)","trace[1697360368] 'applied index is now lower than readState.Index' (duration: 226.102899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:30:24.274231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.670009ms","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":"info","ts":"2026-04-22T16:30:24.27418Z","caller":"traceutil/trace.go:171","msg":"trace[984287725] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"426.948981ms","start":"2026-04-22T16:30:23.847192Z","end":"2026-04-22T16:30:24.274141Z","steps":["trace[984287725] 'process raft request' (duration: 200.859313ms)","trace[984287725] 'compare' (duration: 225.550124ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:30:24.27427Z","caller":"traceutil/trace.go:171","msg":"trace[510458007] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1838; }","duration":"360.744031ms","start":"2026-04-22T16:30:23.913515Z","end":"2026-04-22T16:30:24.274259Z","steps":["trace[510458007] 'agreement among raft nodes before linearized reading' (duration: 360.677499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:24.274344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:23.913496Z","time spent":"360.839873ms","remote":"127.0.0.1:46778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:30:24.274496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.261949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-22T16:30:24.274617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:23.847172Z","time spent":"427.110734ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:30:24.274663Z","caller":"traceutil/trace.go:171","msg":"trace[1073358724] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1838; }","duration":"300.466593ms","start":"2026-04-22T16:30:23.97417Z","end":"2026-04-22T16:30:24.274636Z","steps":["trace[1073358724] 'agreement among raft nodes before linearized reading' (duration: 300.170596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:24.274551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.110325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T16:30:24.274722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:23.97415Z","time spent":"300.553985ms","remote":"127.0.0.1:55696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-22T16:30:24.274877Z","caller":"traceutil/trace.go:171","msg":"trace[1604170902] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1838; }","duration":"210.427342ms","start":"2026-04-22T16:30:24.064383Z","end":"2026-04-22T16:30:24.274811Z","steps":["trace[1604170902] 'agreement among raft nodes before linearized reading' (duration: 210.135615ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:24.853495Z","caller":"traceutil/trace.go:171","msg":"trace[85944346] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"147.697795ms","start":"2026-04-22T16:30:24.705737Z","end":"2026-04-22T16:30:24.853435Z","steps":["trace[85944346] 'process raft request' (duration: 89.439678ms)","trace[85944346] 'compare' (duration: 57.909359ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:30:24.853776Z","caller":"traceutil/trace.go:171","msg":"trace[1684150619] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"127.307557ms","start":"2026-04-22T16:30:24.726441Z","end":"2026-04-22T16:30:24.853748Z","steps":["trace[1684150619] 'read index received' (duration: 68.642889ms)","trace[1684150619] 'applied index is now lower than readState.Index' (duration: 58.662698ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:30:24.854058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.562463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:30:24.854622Z","caller":"traceutil/trace.go:171","msg":"trace[2015003631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1843; }","duration":"128.209719ms","start":"2026-04-22T16:30:24.72639Z","end":"2026-04-22T16:30:24.8546Z","steps":["trace[2015003631] 'agreement among raft nodes before linearized reading' (duration: 127.575624ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:37.721203Z","caller":"traceutil/trace.go:171","msg":"trace[1423450844] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"160.474022ms","start":"2026-04-22T16:30:37.560705Z","end":"2026-04-22T16:30:37.721179Z","steps":["trace[1423450844] 'process raft request' (duration: 160.340359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:10.186751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.04937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-22T16:31:10.186848Z","caller":"traceutil/trace.go:171","msg":"trace[1754371356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2143; }","duration":"137.191052ms","start":"2026-04-22T16:31:10.049627Z","end":"2026-04-22T16:31:10.186818Z","steps":["trace[1754371356] 'range keys from in-memory index tree' (duration: 136.841374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:10.186995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.59729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T16:31:10.187088Z","caller":"traceutil/trace.go:171","msg":"trace[888762661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2143; }","duration":"129.750114ms","start":"2026-04-22T16:31:10.0573Z","end":"2026-04-22T16:31:10.187051Z","steps":["trace[888762661] 'range keys from in-memory index tree' (duration: 129.299783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.569719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2481375324803694066,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:36:40.663184Z","caller":"traceutil/trace.go:171","msg":"trace[149077917] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"618.710021ms","start":"2026-04-22T16:36:40.044442Z","end":"2026-04-22T16:36:40.663153Z","steps":["trace[149077917] 'process raft request' (duration: 618.490175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.66341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:40.044421Z","time spent":"618.874035ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:36:40.666254Z","caller":"traceutil/trace.go:171","msg":"trace[1136770168] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"393.253447ms","start":"2026-04-22T16:36:40.272988Z","end":"2026-04-22T16:36:40.666241Z","steps":["trace[1136770168] 'process raft request' (duration: 393.123234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.666332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:40.272946Z","time spent":"393.34193ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:36:40.666401Z","caller":"traceutil/trace.go:171","msg":"trace[1151475019] linearizableReadLoop","detail":"{readStateIndex:5090; appliedIndex:5087; }","duration":"597.189086ms","start":"2026-04-22T16:36:40.069193Z","end":"2026-04-22T16:36:40.666382Z","steps":["trace[1151475019] 'read index received' (duration: 593.811675ms)","trace[1151475019] 'applied index is now lower than readState.Index' (duration: 3.375451ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:36:40.666488Z","caller":"traceutil/trace.go:171","msg":"trace[1329355380] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"234.863758ms","start":"2026-04-22T16:36:40.431614Z","end":"2026-04-22T16:36:40.666477Z","steps":["trace[1329355380] 'process raft request' (duration: 234.576091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.666639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.12041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T16:36:40.666646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.07848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-22T16:36:40.666662Z","caller":"traceutil/trace.go:171","msg":"trace[1703978386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4903; }","duration":"254.200251ms","start":"2026-04-22T16:36:40.412455Z","end":"2026-04-22T16:36:40.666655Z","steps":["trace[1703978386] 'agreement among raft nodes before linearized reading' (duration: 254.112268ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:36:40.666674Z","caller":"traceutil/trace.go:171","msg":"trace[326046885] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4903; }","duration":"596.152971ms","start":"2026-04-22T16:36:40.070512Z","end":"2026-04-22T16:36:40.666665Z","steps":["trace[326046885] 'agreement among raft nodes before linearized reading' (duration: 596.001957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.666677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.508051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.147\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-22T16:36:40.666694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:40.07049Z","time spent":"596.198753ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1839,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-04-22T16:36:40.666707Z","caller":"traceutil/trace.go:171","msg":"trace[1091379915] range","detail":"{range_begin:/registry/masterleases/199.19.213.147; range_end:; response_count:1; response_revision:4903; }","duration":"420.575742ms","start":"2026-04-22T16:36:40.246121Z","end":"2026-04-22T16:36:40.666697Z","steps":["trace[1091379915] 'agreement among raft nodes before linearized reading' (duration: 420.471559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.666734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:40.246104Z","time spent":"420.624453ms","remote":"127.0.0.1:46574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.147\" "} {"level":"warn","ts":"2026-04-22T16:36:40.666792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.601611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-22T16:36:40.666873Z","caller":"traceutil/trace.go:171","msg":"trace[935044784] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4903; }","duration":"282.730724ms","start":"2026-04-22T16:36:40.384127Z","end":"2026-04-22T16:36:40.666858Z","steps":["trace[935044784] 'agreement among raft nodes before linearized reading' (duration: 282.54704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.666919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.696749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T16:36:40.666988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.369774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T16:36:40.667014Z","caller":"traceutil/trace.go:171","msg":"trace[673684595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4903; }","duration":"597.853202ms","start":"2026-04-22T16:36:40.069141Z","end":"2026-04-22T16:36:40.666994Z","steps":["trace[673684595] 'agreement among raft nodes before linearized reading' (duration: 597.531265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.667046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.385728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:36:40.667071Z","caller":"traceutil/trace.go:171","msg":"trace[1264681773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4903; }","duration":"172.514887ms","start":"2026-04-22T16:36:40.494533Z","end":"2026-04-22T16:36:40.667048Z","steps":["trace[1264681773] 'agreement among raft nodes before linearized reading' (duration: 172.19038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.667071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:40.06912Z","time spent":"597.941725ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:36:40.667094Z","caller":"traceutil/trace.go:171","msg":"trace[2034660934] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4903; }","duration":"443.858999ms","start":"2026-04-22T16:36:40.223212Z","end":"2026-04-22T16:36:40.667071Z","steps":["trace[2034660934] 'agreement among raft nodes before linearized reading' (duration: 443.385887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:40.667118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:40.22319Z","time spent":"443.92091ms","remote":"127.0.0.1:46908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:36:55.072699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.280183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:36:55.07287Z","caller":"traceutil/trace.go:171","msg":"trace[1331385939] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"274.810504ms","start":"2026-04-22T16:36:54.798049Z","end":"2026-04-22T16:36:55.072859Z","steps":["trace[1331385939] 'process raft request' (duration: 274.784874ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:36:55.072871Z","caller":"traceutil/trace.go:171","msg":"trace[956053956] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"355.198668ms","start":"2026-04-22T16:36:54.717647Z","end":"2026-04-22T16:36:55.072846Z","steps":["trace[956053956] 'process raft request' (duration: 39.71034ms)","trace[956053956] 'compare' (duration: 315.189041ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:36:55.072889Z","caller":"traceutil/trace.go:171","msg":"trace[1250965516] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"354.470399ms","start":"2026-04-22T16:36:54.7184Z","end":"2026-04-22T16:36:55.07287Z","steps":["trace[1250965516] 'process raft request' (duration: 354.384427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:55.072932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:54.717623Z","time spent":"355.278679ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:36:55.072973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:54.718374Z","time spent":"354.557121ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:36:55.073052Z","caller":"traceutil/trace.go:171","msg":"trace[375543625] linearizableReadLoop","detail":"{readStateIndex:5443; appliedIndex:5441; }","duration":"346.079578ms","start":"2026-04-22T16:36:54.726949Z","end":"2026-04-22T16:36:55.073028Z","steps":["trace[375543625] 'read index received' (duration: 30.434637ms)","trace[375543625] 'applied index is now lower than readState.Index' (duration: 315.643951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:36:55.073125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.182301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:36:55.073155Z","caller":"traceutil/trace.go:171","msg":"trace[505092804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5247; }","duration":"346.250583ms","start":"2026-04-22T16:36:54.726898Z","end":"2026-04-22T16:36:55.073149Z","steps":["trace[505092804] 'agreement among raft nodes before linearized reading' (duration: 346.203262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:55.073175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:54.726879Z","time spent":"346.291653ms","remote":"127.0.0.1:46540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:36:55.073213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.283719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T16:36:55.073242Z","caller":"traceutil/trace.go:171","msg":"trace[1375935144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5247; }","duration":"345.334391ms","start":"2026-04-22T16:36:54.727902Z","end":"2026-04-22T16:36:55.073236Z","steps":["trace[1375935144] 'agreement among raft nodes before linearized reading' (duration: 345.245899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:55.073262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:54.727878Z","time spent":"345.378203ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:36:55.073395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.256319ms","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-04-22T16:36:55.073412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.762422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T16:36:55.073424Z","caller":"traceutil/trace.go:171","msg":"trace[248720447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5247; }","duration":"327.31763ms","start":"2026-04-22T16:36:54.7461Z","end":"2026-04-22T16:36:55.073418Z","steps":["trace[248720447] 'agreement among raft nodes before linearized reading' (duration: 327.251749ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:36:55.073431Z","caller":"traceutil/trace.go:171","msg":"trace[805266214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5247; }","duration":"330.806624ms","start":"2026-04-22T16:36:54.742619Z","end":"2026-04-22T16:36:55.073426Z","steps":["trace[805266214] 'agreement among raft nodes before linearized reading' (duration: 330.755642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:55.073446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:54.746083Z","time spent":"327.357751ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T16:36:55.073493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.33581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T16:36:55.073449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:54.742607Z","time spent":"330.836924ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T16:36:55.07352Z","caller":"traceutil/trace.go:171","msg":"trace[1839580522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5247; }","duration":"332.393021ms","start":"2026-04-22T16:36:54.741122Z","end":"2026-04-22T16:36:55.073515Z","steps":["trace[1839580522] 'agreement among raft nodes before linearized reading' (duration: 332.337639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:36:55.07354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:36:54.741102Z","time spent":"332.433142ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:36:55.073571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.121119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-bnnmq\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-22T16:36:55.073626Z","caller":"traceutil/trace.go:171","msg":"trace[761857145] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-bnnmq; range_end:; response_count:1; response_revision:5247; }","duration":"157.207021ms","start":"2026-04-22T16:36:54.916403Z","end":"2026-04-22T16:36:55.07361Z","steps":["trace[761857145] 'agreement among raft nodes before linearized reading' (duration: 157.085978ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:36:55.359175Z","caller":"traceutil/trace.go:171","msg":"trace[1150860089] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"230.848452ms","start":"2026-04-22T16:36:55.128276Z","end":"2026-04-22T16:36:55.359124Z","steps":["trace[1150860089] 'process raft request' (duration: 201.48798ms)","trace[1150860089] 'compare' (duration: 28.87181ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:36:55.359525Z","caller":"traceutil/trace.go:171","msg":"trace[730575497] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"230.542195ms","start":"2026-04-22T16:36:55.12895Z","end":"2026-04-22T16:36:55.359492Z","steps":["trace[730575497] 'process raft request' (duration: 230.448552ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:36:55.359666Z","caller":"traceutil/trace.go:171","msg":"trace[939517025] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"230.816001ms","start":"2026-04-22T16:36:55.128824Z","end":"2026-04-22T16:36:55.35964Z","steps":["trace[939517025] 'process raft request' (duration: 230.161296ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.315577Z","caller":"traceutil/trace.go:171","msg":"trace[1254788969] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"142.035647ms","start":"2026-04-22T16:37:03.173504Z","end":"2026-04-22T16:37:03.31554Z","steps":["trace[1254788969] 'process raft request' (duration: 74.361578ms)","trace[1254788969] 'compare' (duration: 67.400662ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:37:03.362451Z","caller":"traceutil/trace.go:171","msg":"trace[2146831589] linearizableReadLoop","detail":"{readStateIndex:5622; appliedIndex:5620; }","duration":"186.81756ms","start":"2026-04-22T16:37:03.175611Z","end":"2026-04-22T16:37:03.362429Z","steps":["trace[2146831589] 'read index received' (duration: 72.240279ms)","trace[2146831589] 'applied index is now lower than readState.Index' (duration: 114.576311ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:37:03.362584Z","caller":"traceutil/trace.go:171","msg":"trace[25349161] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"180.374714ms","start":"2026-04-22T16:37:03.182172Z","end":"2026-04-22T16:37:03.362547Z","steps":["trace[25349161] 'process raft request' (duration: 180.20135ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.362614Z","caller":"traceutil/trace.go:171","msg":"trace[1546990535] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"186.969242ms","start":"2026-04-22T16:37:03.175533Z","end":"2026-04-22T16:37:03.362502Z","steps":["trace[1546990535] 'process raft request' (duration: 186.718447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:03.362788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.124367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-22T16:37:03.362875Z","caller":"traceutil/trace.go:171","msg":"trace[1107142686] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5423; }","duration":"187.288861ms","start":"2026-04-22T16:37:03.175568Z","end":"2026-04-22T16:37:03.362857Z","steps":["trace[1107142686] 'agreement among raft nodes before linearized reading' (duration: 187.042255ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.475983Z","caller":"traceutil/trace.go:171","msg":"trace[582655639] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"105.167046ms","start":"2026-04-22T16:37:03.370763Z","end":"2026-04-22T16:37:03.47593Z","steps":["trace[582655639] 'process raft request' (duration: 58.397567ms)","trace[582655639] 'compare' (duration: 46.412311ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:37:03.476152Z","caller":"traceutil/trace.go:171","msg":"trace[2015568015] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"105.218077ms","start":"2026-04-22T16:37:03.370862Z","end":"2026-04-22T16:37:03.47608Z","steps":["trace[2015568015] 'process raft request' (duration: 104.961441ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.476217Z","caller":"traceutil/trace.go:171","msg":"trace[158069613] linearizableReadLoop","detail":"{readStateIndex:5629; appliedIndex:5623; }","duration":"100.413882ms","start":"2026-04-22T16:37:03.375776Z","end":"2026-04-22T16:37:03.47619Z","steps":["trace[158069613] 'read index received' (duration: 14.082747ms)","trace[158069613] 'applied index is now lower than readState.Index' (duration: 86.330065ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:37:03.476363Z","caller":"traceutil/trace.go:171","msg":"trace[117838262] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"105.433062ms","start":"2026-04-22T16:37:03.370915Z","end":"2026-04-22T16:37:03.476348Z","steps":["trace[117838262] 'process raft request' (duration: 105.001862ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.476537Z","caller":"traceutil/trace.go:171","msg":"trace[320033960] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"105.441332ms","start":"2026-04-22T16:37:03.371078Z","end":"2026-04-22T16:37:03.476519Z","steps":["trace[320033960] 'process raft request' (duration: 104.984861ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.476564Z","caller":"traceutil/trace.go:171","msg":"trace[1166695059] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"105.609916ms","start":"2026-04-22T16:37:03.370916Z","end":"2026-04-22T16:37:03.476526Z","steps":["trace[1166695059] 'process raft request' (duration: 105.100974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:03.47673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.928564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-22T16:37:03.476779Z","caller":"traceutil/trace.go:171","msg":"trace[191597905] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5430; }","duration":"101.017546ms","start":"2026-04-22T16:37:03.375747Z","end":"2026-04-22T16:37:03.476764Z","steps":["trace[191597905] 'agreement among raft nodes before linearized reading' (duration: 100.794011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:03.986534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.565246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:37:03.98665Z","caller":"traceutil/trace.go:171","msg":"trace[1976711631] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:0; response_revision:5434; }","duration":"406.75733ms","start":"2026-04-22T16:37:03.579867Z","end":"2026-04-22T16:37:03.986624Z","steps":["trace[1976711631] 'range keys from in-memory index tree' (duration: 406.459983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:03.98665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.686438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"warn","ts":"2026-04-22T16:37:03.986637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.162045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-04-22T16:37:03.986702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:37:03.579846Z","time spent":"406.846103ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"info","ts":"2026-04-22T16:37:03.986713Z","caller":"traceutil/trace.go:171","msg":"trace[1344239055] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5434; }","duration":"388.797922ms","start":"2026-04-22T16:37:03.597896Z","end":"2026-04-22T16:37:03.986694Z","steps":["trace[1344239055] 'range keys from in-memory index tree' (duration: 388.516215ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:03.986717Z","caller":"traceutil/trace.go:171","msg":"trace[1146343817] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5434; }","duration":"408.295808ms","start":"2026-04-22T16:37:03.578405Z","end":"2026-04-22T16:37:03.986701Z","steps":["trace[1146343817] 'range keys from in-memory index tree' (duration: 407.97509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:03.986748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:37:03.597868Z","time spent":"388.870653ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3587,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-04-22T16:37:03.98675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:37:03.578385Z","time spent":"408.357649ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":730,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-04-22T16:37:03.986814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.600947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:37:03.986952Z","caller":"traceutil/trace.go:171","msg":"trace[215185680] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5434; }","duration":"388.75734ms","start":"2026-04-22T16:37:03.598174Z","end":"2026-04-22T16:37:03.986931Z","steps":["trace[215185680] 'count revisions from in-memory index tree' (duration: 388.463243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:03.986992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:37:03.598096Z","time spent":"388.883423ms","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-22T16:37:55.569456Z","caller":"traceutil/trace.go:171","msg":"trace[1053289880] transaction","detail":"{read_only:false; response_revision:5939; number_of_response:1; }","duration":"121.424515ms","start":"2026-04-22T16:37:55.448009Z","end":"2026-04-22T16:37:55.569433Z","steps":["trace[1053289880] 'process raft request' (duration: 121.026445ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:38:17.085245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3028} {"level":"info","ts":"2026-04-22T16:38:17.180831Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3028,"took":"93.938288ms","hash":2301293739,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":31592448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-22T16:38:17.180981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2301293739,"revision":3028,"compact-revision":-1} {"level":"warn","ts":"2026-04-22T16:43:11.396029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.968567ms","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-04-22T16:43:11.396152Z","caller":"traceutil/trace.go:171","msg":"trace[1983847641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8275; }","duration":"162.171741ms","start":"2026-04-22T16:43:11.233956Z","end":"2026-04-22T16:43:11.396127Z","steps":["trace[1983847641] 'range keys from in-memory index tree' (duration: 161.71821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:43:11.848207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.895932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-785b99b7fd-t2b6x.18a8bb76bec71cf6\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-22T16:43:11.848479Z","caller":"traceutil/trace.go:171","msg":"trace[29259975] range","detail":"{range_begin:/registry/events/openstack/barbican-api-785b99b7fd-t2b6x.18a8bb76bec71cf6; range_end:; response_count:1; response_revision:8279; }","duration":"169.222449ms","start":"2026-04-22T16:43:11.679224Z","end":"2026-04-22T16:43:11.848447Z","steps":["trace[29259975] 'range keys from in-memory index tree' (duration: 168.702357ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:12.880282Z","caller":"traceutil/trace.go:171","msg":"trace[379544299] transaction","detail":"{read_only:false; response_revision:8282; number_of_response:1; }","duration":"247.920628ms","start":"2026-04-22T16:43:12.632338Z","end":"2026-04-22T16:43:12.880259Z","steps":["trace[379544299] 'process raft request' (duration: 247.761344ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:12.909767Z","caller":"traceutil/trace.go:171","msg":"trace[393590717] linearizableReadLoop","detail":"{readStateIndex:8573; appliedIndex:8570; }","duration":"181.912252ms","start":"2026-04-22T16:43:12.727821Z","end":"2026-04-22T16:43:12.909733Z","steps":["trace[393590717] 'read index received' (duration: 152.281234ms)","trace[393590717] 'applied index is now lower than readState.Index' (duration: 29.630068ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:43:12.909812Z","caller":"traceutil/trace.go:171","msg":"trace[1855662377] transaction","detail":"{read_only:false; response_revision:8283; number_of_response:1; }","duration":"261.42872ms","start":"2026-04-22T16:43:12.648344Z","end":"2026-04-22T16:43:12.909772Z","steps":["trace[1855662377] 'process raft request' (duration: 261.170003ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:12.909896Z","caller":"traceutil/trace.go:171","msg":"trace[1685190546] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"258.551051ms","start":"2026-04-22T16:43:12.651318Z","end":"2026-04-22T16:43:12.909869Z","steps":["trace[1685190546] 'process raft request' (duration: 258.367226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:43:12.909919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.080646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:43:12.910182Z","caller":"traceutil/trace.go:171","msg":"trace[1673842410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8284; }","duration":"182.348232ms","start":"2026-04-22T16:43:12.727756Z","end":"2026-04-22T16:43:12.910104Z","steps":["trace[1673842410] 'agreement among raft nodes before linearized reading' (duration: 182.093986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:43:13.127728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.925249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T16:43:13.127831Z","caller":"traceutil/trace.go:171","msg":"trace[730357831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8284; }","duration":"122.088373ms","start":"2026-04-22T16:43:13.005724Z","end":"2026-04-22T16:43:13.127812Z","steps":["trace[730357831] 'range keys from in-memory index tree' (duration: 121.645943ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:13.558632Z","caller":"traceutil/trace.go:171","msg":"trace[297131840] linearizableReadLoop","detail":"{readStateIndex:8577; appliedIndex:8575; }","duration":"116.706876ms","start":"2026-04-22T16:43:13.44189Z","end":"2026-04-22T16:43:13.558597Z","steps":["trace[297131840] 'read index received' (duration: 40.740753ms)","trace[297131840] 'applied index is now lower than readState.Index' (duration: 75.965103ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:43:13.558803Z","caller":"traceutil/trace.go:171","msg":"trace[223136567] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"134.640834ms","start":"2026-04-22T16:43:13.424121Z","end":"2026-04-22T16:43:13.558762Z","steps":["trace[223136567] 'process raft request' (duration: 127.937914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:43:13.559097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.176557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-22T16:43:13.559358Z","caller":"traceutil/trace.go:171","msg":"trace[1560862325] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8288; }","duration":"117.497245ms","start":"2026-04-22T16:43:13.441833Z","end":"2026-04-22T16:43:13.55933Z","steps":["trace[1560862325] 'agreement among raft nodes before linearized reading' (duration: 116.942782ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:17.092472Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6112} {"level":"info","ts":"2026-04-22T16:43:17.226307Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6112,"took":"131.53127ms","hash":1818009997,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":23175168,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-22T16:43:17.226383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1818009997,"revision":6112,"compact-revision":3028} {"level":"info","ts":"2026-04-22T16:43:41.179866Z","caller":"traceutil/trace.go:171","msg":"trace[749450454] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"182.029294ms","start":"2026-04-22T16:43:40.997734Z","end":"2026-04-22T16:43:41.179763Z","steps":["trace[749450454] 'process raft request' (duration: 181.637935ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:41.180875Z","caller":"traceutil/trace.go:171","msg":"trace[1656792385] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"158.774819ms","start":"2026-04-22T16:43:41.022076Z","end":"2026-04-22T16:43:41.180851Z","steps":["trace[1656792385] 'process raft request' (duration: 158.734528ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:41.181183Z","caller":"traceutil/trace.go:171","msg":"trace[1078565738] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"159.751633ms","start":"2026-04-22T16:43:41.021387Z","end":"2026-04-22T16:43:41.181139Z","steps":["trace[1078565738] 'process raft request' (duration: 159.266591ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:54.409406Z","caller":"traceutil/trace.go:171","msg":"trace[1717040847] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8597; }","duration":"193.258083ms","start":"2026-04-22T16:43:54.216119Z","end":"2026-04-22T16:43:54.409377Z","steps":["trace[1717040847] 'process raft request' (duration: 117.422682ms)","trace[1717040847] 'compare' (duration: 75.477901ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:43:54.417044Z","caller":"traceutil/trace.go:171","msg":"trace[598610156] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"198.683923ms","start":"2026-04-22T16:43:54.21834Z","end":"2026-04-22T16:43:54.417024Z","steps":["trace[598610156] 'process raft request' (duration: 198.480348ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:54.41703Z","caller":"traceutil/trace.go:171","msg":"trace[1368279439] linearizableReadLoop","detail":"{readStateIndex:8901; appliedIndex:8899; }","duration":"136.666872ms","start":"2026-04-22T16:43:54.28033Z","end":"2026-04-22T16:43:54.416997Z","steps":["trace[1368279439] 'read index received' (duration: 53.295522ms)","trace[1368279439] 'applied index is now lower than readState.Index' (duration: 83.3706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:43:54.417235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.880107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-22T16:43:54.417223Z","caller":"traceutil/trace.go:171","msg":"trace[328502820] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"103.036759ms","start":"2026-04-22T16:43:54.314159Z","end":"2026-04-22T16:43:54.417196Z","steps":["trace[328502820] 'process raft request' (duration: 102.783163ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:54.417288Z","caller":"traceutil/trace.go:171","msg":"trace[1617948575] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8599; }","duration":"136.993739ms","start":"2026-04-22T16:43:54.280279Z","end":"2026-04-22T16:43:54.417272Z","steps":["trace[1617948575] 'agreement among raft nodes before linearized reading' (duration: 136.811015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:43:54.417359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.738426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-dlt5r\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-04-22T16:43:54.417421Z","caller":"traceutil/trace.go:171","msg":"trace[1373083992] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-dlt5r; range_end:; response_count:1; response_revision:8599; }","duration":"103.850449ms","start":"2026-04-22T16:43:54.313557Z","end":"2026-04-22T16:43:54.417407Z","steps":["trace[1373083992] 'agreement among raft nodes before linearized reading' (duration: 103.745396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:43:55.802009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.123176ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-22T16:43:55.802169Z","caller":"traceutil/trace.go:171","msg":"trace[1733125379] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8908; }","duration":"403.689205ms","start":"2026-04-22T16:43:55.398451Z","end":"2026-04-22T16:43:55.802141Z","steps":["trace[1733125379] 'read index received' (duration: 242.547808ms)","trace[1733125379] 'applied index is now lower than readState.Index' (duration: 161.138707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:43:55.802458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.975632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T16:43:55.802651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.256393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-22T16:43:55.802663Z","caller":"traceutil/trace.go:171","msg":"trace[2079929040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8605; }","duration":"404.228148ms","start":"2026-04-22T16:43:55.398404Z","end":"2026-04-22T16:43:55.802632Z","steps":["trace[2079929040] 'agreement among raft nodes before linearized reading' (duration: 403.809918ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:55.802711Z","caller":"traceutil/trace.go:171","msg":"trace[614991386] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8605; }","duration":"356.423427ms","start":"2026-04-22T16:43:55.44627Z","end":"2026-04-22T16:43:55.802693Z","steps":["trace[614991386] 'agreement among raft nodes before linearized reading' (duration: 356.097859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:43:55.802727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:43:55.398381Z","time spent":"404.32921ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:43:55.802764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:43:55.446236Z","time spent":"356.514339ms","remote":"127.0.0.1:41712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5754,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-04-22T16:43:55.802793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.973833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T16:43:55.802919Z","caller":"traceutil/trace.go:171","msg":"trace[261667073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8605; }","duration":"355.179048ms","start":"2026-04-22T16:43:55.447716Z","end":"2026-04-22T16:43:55.802895Z","steps":["trace[261667073] 'agreement among raft nodes before linearized reading' (duration: 354.810339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:43:55.803017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:43:55.447691Z","time spent":"355.307801ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T16:43:55.936718Z","caller":"traceutil/trace.go:171","msg":"trace[511647484] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"128.314663ms","start":"2026-04-22T16:43:55.808366Z","end":"2026-04-22T16:43:55.93668Z","steps":["trace[511647484] 'process raft request' (duration: 127.832242ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:55.936851Z","caller":"traceutil/trace.go:171","msg":"trace[1500145938] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"128.426335ms","start":"2026-04-22T16:43:55.808383Z","end":"2026-04-22T16:43:55.93681Z","steps":["trace[1500145938] 'process raft request' (duration: 128.20935ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:55.937255Z","caller":"traceutil/trace.go:171","msg":"trace[1723285451] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"128.789134ms","start":"2026-04-22T16:43:55.80843Z","end":"2026-04-22T16:43:55.937219Z","steps":["trace[1723285451] 'process raft request' (duration: 128.559278ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:55.937408Z","caller":"traceutil/trace.go:171","msg":"trace[1916650486] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"128.276902ms","start":"2026-04-22T16:43:55.809117Z","end":"2026-04-22T16:43:55.937394Z","steps":["trace[1916650486] 'process raft request' (duration: 128.016916ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:55.937747Z","caller":"traceutil/trace.go:171","msg":"trace[168080629] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"121.741605ms","start":"2026-04-22T16:43:55.815992Z","end":"2026-04-22T16:43:55.937733Z","steps":["trace[168080629] 'process raft request' (duration: 121.249423ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:43:56.752893Z","caller":"traceutil/trace.go:171","msg":"trace[44030337] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"199.915441ms","start":"2026-04-22T16:43:56.552923Z","end":"2026-04-22T16:43:56.752839Z","steps":["trace[44030337] 'process raft request' (duration: 184.039511ms)","trace[44030337] 'compare' (duration: 15.47167ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:44:22.481368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2481375324803704718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:44:22.981705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2481375324803704718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:44:23.275514Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.328833247s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:44:23.276164Z","caller":"traceutil/trace.go:171","msg":"trace[813811427] linearizableReadLoop","detail":"{readStateIndex:9211; appliedIndex:9210; }","duration":"1.294816985s","start":"2026-04-22T16:44:21.981302Z","end":"2026-04-22T16:44:23.276119Z","steps":["trace[813811427] 'read index received' (duration: 1.294496937s)","trace[813811427] 'applied index is now lower than readState.Index' (duration: 319.018µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:44:23.276316Z","caller":"traceutil/trace.go:171","msg":"trace[1111172452] transaction","detail":"{read_only:false; response_revision:8898; number_of_response:1; }","duration":"1.329668018s","start":"2026-04-22T16:44:21.946575Z","end":"2026-04-22T16:44:23.276243Z","steps":["trace[1111172452] 'process raft request' (duration: 1.32933356s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.276503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:21.94655Z","time spent":"1.329862312s","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:44:23.276636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295256576s","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-04-22T16:44:23.276751Z","caller":"traceutil/trace.go:171","msg":"trace[261166993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8898; }","duration":"1.295487161s","start":"2026-04-22T16:44:21.981243Z","end":"2026-04-22T16:44:23.276731Z","steps":["trace[261166993] 'agreement among raft nodes before linearized reading' (duration: 1.295091482s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.27698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:21.981223Z","time spent":"1.295622094s","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:44:23.423241Z","caller":"traceutil/trace.go:171","msg":"trace[758951161] linearizableReadLoop","detail":"{readStateIndex:9212; appliedIndex:9211; }","duration":"146.957769ms","start":"2026-04-22T16:44:23.276257Z","end":"2026-04-22T16:44:23.423215Z","steps":["trace[758951161] 'read index received' (duration: 146.851805ms)","trace[758951161] 'applied index is now lower than readState.Index' (duration: 105.064µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:44:23.423451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.227571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6f9xx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-22T16:44:23.423556Z","caller":"traceutil/trace.go:171","msg":"trace[1666999807] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6f9xx; range_end:; response_count:1; response_revision:8899; }","duration":"795.393065ms","start":"2026-04-22T16:44:22.628143Z","end":"2026-04-22T16:44:23.423536Z","steps":["trace[1666999807] 'agreement among raft nodes before linearized reading' (duration: 795.114419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.423608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:22.628124Z","time spent":"795.475077ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-6f9xx\" "} {"level":"warn","ts":"2026-04-22T16:44:23.423607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320712414s","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":"warn","ts":"2026-04-22T16:44:23.423631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341753716s","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-04-22T16:44:23.423665Z","caller":"traceutil/trace.go:171","msg":"trace[412804827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8899; }","duration":"1.320837927s","start":"2026-04-22T16:44:22.102811Z","end":"2026-04-22T16:44:23.423649Z","steps":["trace[412804827] 'agreement among raft nodes before linearized reading' (duration: 1.320687403s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.423721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:22.102795Z","time spent":"1.320914499s","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:44:23.423762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.925428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T16:44:23.423784Z","caller":"traceutil/trace.go:171","msg":"trace[1713009850] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8899; }","duration":"803.98764ms","start":"2026-04-22T16:44:22.61979Z","end":"2026-04-22T16:44:23.423778Z","steps":["trace[1713009850] 'agreement among raft nodes before linearized reading' (duration: 803.913798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.42347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342537975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-22T16:44:23.423552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333974401s","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-04-22T16:44:23.423836Z","caller":"traceutil/trace.go:171","msg":"trace[403273636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8899; }","duration":"1.342974186s","start":"2026-04-22T16:44:22.080851Z","end":"2026-04-22T16:44:23.423825Z","steps":["trace[403273636] 'agreement among raft nodes before linearized reading' (duration: 1.342457614s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.423845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:22.619768Z","time spent":"804.069502ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T16:44:23.423847Z","caller":"traceutil/trace.go:171","msg":"trace[1498029853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8899; }","duration":"1.334314929s","start":"2026-04-22T16:44:22.089518Z","end":"2026-04-22T16:44:23.423833Z","steps":["trace[1498029853] 'agreement among raft nodes before linearized reading' (duration: 1.333993041s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.423875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:22.08083Z","time spent":"1.343035967s","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T16:44:23.423885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:22.089492Z","time spent":"1.334383861s","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T16:44:23.423681Z","caller":"traceutil/trace.go:171","msg":"trace[552833049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8899; }","duration":"1.341816327s","start":"2026-04-22T16:44:22.081853Z","end":"2026-04-22T16:44:23.423669Z","steps":["trace[552833049] 'agreement among raft nodes before linearized reading' (duration: 1.341734395s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:23.423999Z","caller":"traceutil/trace.go:171","msg":"trace[1945695211] transaction","detail":"{read_only:false; response_revision:8899; number_of_response:1; }","duration":"341.623714ms","start":"2026-04-22T16:44:23.082365Z","end":"2026-04-22T16:44:23.423989Z","steps":["trace[1945695211] 'process raft request' (duration: 340.67266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.424009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.417443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:44:23.424057Z","caller":"traceutil/trace.go:171","msg":"trace[775576640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8899; }","duration":"696.508445ms","start":"2026-04-22T16:44:22.727538Z","end":"2026-04-22T16:44:23.424047Z","steps":["trace[775576640] 'agreement among raft nodes before linearized reading' (duration: 696.436993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.424091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:23.08234Z","time spent":"341.681335ms","remote":"127.0.0.1:46732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19323,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:44:23.424092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:22.727521Z","time spent":"696.562796ms","remote":"127.0.0.1:46540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:44:23.424133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:22.081844Z","time spent":"1.342147865s","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:44:23.424338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.46937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:44:23.424435Z","caller":"traceutil/trace.go:171","msg":"trace[1026956725] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8899; }","duration":"789.043234ms","start":"2026-04-22T16:44:22.635371Z","end":"2026-04-22T16:44:23.424414Z","steps":["trace[1026956725] 'agreement among raft nodes before linearized reading' (duration: 788.428209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.424491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:22.635351Z","time spent":"789.128835ms","remote":"127.0.0.1:41662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:44:23.424633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286947469s","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-04-22T16:44:23.4247Z","caller":"traceutil/trace.go:171","msg":"trace[1864096661] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8899; }","duration":"1.287736057s","start":"2026-04-22T16:44:22.136947Z","end":"2026-04-22T16:44:23.424683Z","steps":["trace[1864096661] 'agreement among raft nodes before linearized reading' (duration: 1.286941768s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:23.424743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:44:22.136922Z","time spent":"1.287809328s","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-22T16:44:23.434812Z","caller":"traceutil/trace.go:171","msg":"trace[1755862739] transaction","detail":"{read_only:false; response_revision:8900; number_of_response:1; }","duration":"153.931974ms","start":"2026-04-22T16:44:23.280816Z","end":"2026-04-22T16:44:23.434748Z","steps":["trace[1755862739] 'process raft request' (duration: 153.772051ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:25.599782Z","caller":"traceutil/trace.go:171","msg":"trace[349531909] transaction","detail":"{read_only:false; response_revision:8911; number_of_response:1; }","duration":"118.188771ms","start":"2026-04-22T16:44:25.48154Z","end":"2026-04-22T16:44:25.599728Z","steps":["trace[349531909] 'process raft request' (duration: 51.89981ms)","trace[349531909] 'compare' (duration: 66.004424ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:44:25.599876Z","caller":"traceutil/trace.go:171","msg":"trace[176321601] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"112.21539ms","start":"2026-04-22T16:44:25.487627Z","end":"2026-04-22T16:44:25.599842Z","steps":["trace[176321601] 'process raft request' (duration: 111.991024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:25.600071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.051609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:234680"} {"level":"info","ts":"2026-04-22T16:44:25.600167Z","caller":"traceutil/trace.go:171","msg":"trace[301239884] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8912; }","duration":"100.214442ms","start":"2026-04-22T16:44:25.499933Z","end":"2026-04-22T16:44:25.600147Z","steps":["trace[301239884] 'agreement among raft nodes before linearized reading' (duration: 99.73975ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:25.630535Z","caller":"traceutil/trace.go:171","msg":"trace[1078320459] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"130.383692ms","start":"2026-04-22T16:44:25.500119Z","end":"2026-04-22T16:44:25.630503Z","steps":["trace[1078320459] 'process raft request' (duration: 130.133816ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:25.630726Z","caller":"traceutil/trace.go:171","msg":"trace[1888048312] transaction","detail":"{read_only:false; response_revision:8914; number_of_response:1; }","duration":"130.436254ms","start":"2026-04-22T16:44:25.500276Z","end":"2026-04-22T16:44:25.630712Z","steps":["trace[1888048312] 'process raft request' (duration: 130.173837ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:25.631197Z","caller":"traceutil/trace.go:171","msg":"trace[2057260354] transaction","detail":"{read_only:false; response_revision:8918; number_of_response:1; }","duration":"129.569154ms","start":"2026-04-22T16:44:25.501581Z","end":"2026-04-22T16:44:25.63115Z","steps":["trace[2057260354] 'process raft request' (duration: 129.519003ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:25.631544Z","caller":"traceutil/trace.go:171","msg":"trace[1108687799] transaction","detail":"{read_only:false; response_revision:8915; number_of_response:1; }","duration":"131.178861ms","start":"2026-04-22T16:44:25.500353Z","end":"2026-04-22T16:44:25.631532Z","steps":["trace[1108687799] 'process raft request' (duration: 130.29223ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:25.631764Z","caller":"traceutil/trace.go:171","msg":"trace[505160624] transaction","detail":"{read_only:false; response_revision:8917; number_of_response:1; }","duration":"131.299814ms","start":"2026-04-22T16:44:25.500446Z","end":"2026-04-22T16:44:25.631746Z","steps":["trace[505160624] 'process raft request' (duration: 130.610898ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:25.631872Z","caller":"traceutil/trace.go:171","msg":"trace[2044225216] transaction","detail":"{read_only:false; response_revision:8916; number_of_response:1; }","duration":"131.500449ms","start":"2026-04-22T16:44:25.500365Z","end":"2026-04-22T16:44:25.631865Z","steps":["trace[2044225216] 'process raft request' (duration: 130.552137ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:45.661872Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d5.wal"} {"level":"info","ts":"2026-04-22T16:45:09.956788Z","caller":"traceutil/trace.go:171","msg":"trace[607958728] transaction","detail":"{read_only:false; response_revision:9269; number_of_response:1; }","duration":"256.095302ms","start":"2026-04-22T16:45:09.700661Z","end":"2026-04-22T16:45:09.956756Z","steps":["trace[607958728] 'process raft request' (duration: 255.894967ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:09.998179Z","caller":"traceutil/trace.go:171","msg":"trace[1121426896] linearizableReadLoop","detail":"{readStateIndex:9594; appliedIndex:9591; }","duration":"159.050956ms","start":"2026-04-22T16:45:09.839094Z","end":"2026-04-22T16:45:09.998145Z","steps":["trace[1121426896] 'read index received' (duration: 117.434072ms)","trace[1121426896] 'applied index is now lower than readState.Index' (duration: 41.615714ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:45:09.998277Z","caller":"traceutil/trace.go:171","msg":"trace[1496161633] transaction","detail":"{read_only:false; response_revision:9270; number_of_response:1; }","duration":"203.23421ms","start":"2026-04-22T16:45:09.794978Z","end":"2026-04-22T16:45:09.998213Z","steps":["trace[1496161633] 'process raft request' (duration: 202.961324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:09.998587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.6454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6f9xx\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-22T16:45:09.998572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.450386ms","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-04-22T16:45:09.998649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.703937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-22T16:45:09.998692Z","caller":"traceutil/trace.go:171","msg":"trace[906110312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9271; }","duration":"159.62858ms","start":"2026-04-22T16:45:09.83904Z","end":"2026-04-22T16:45:09.998668Z","steps":["trace[906110312] 'agreement among raft nodes before linearized reading' (duration: 159.282932ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:09.998721Z","caller":"traceutil/trace.go:171","msg":"trace[1579145703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9271; }","duration":"153.821781ms","start":"2026-04-22T16:45:09.84488Z","end":"2026-04-22T16:45:09.998702Z","steps":["trace[1579145703] 'agreement among raft nodes before linearized reading' (duration: 153.647987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:09.998689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.835625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T16:45:09.998629Z","caller":"traceutil/trace.go:171","msg":"trace[1181766933] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6f9xx; range_end:; response_count:1; response_revision:9271; }","duration":"110.753133ms","start":"2026-04-22T16:45:09.887865Z","end":"2026-04-22T16:45:09.998618Z","steps":["trace[1181766933] 'agreement among raft nodes before linearized reading' (duration: 110.577129ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:09.998874Z","caller":"traceutil/trace.go:171","msg":"trace[2094034445] transaction","detail":"{read_only:false; response_revision:9271; number_of_response:1; }","duration":"203.301952ms","start":"2026-04-22T16:45:09.795004Z","end":"2026-04-22T16:45:09.998306Z","steps":["trace[2094034445] 'process raft request' (duration: 203.102107ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:09.998799Z","caller":"traceutil/trace.go:171","msg":"trace[2107486218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9271; }","duration":"155.050811ms","start":"2026-04-22T16:45:09.843725Z","end":"2026-04-22T16:45:09.998776Z","steps":["trace[2107486218] 'agreement among raft nodes before linearized reading' (duration: 154.669152ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:10.406554Z","caller":"traceutil/trace.go:171","msg":"trace[1073564857] transaction","detail":"{read_only:false; response_revision:9278; number_of_response:1; }","duration":"120.933096ms","start":"2026-04-22T16:45:10.285585Z","end":"2026-04-22T16:45:10.406518Z","steps":["trace[1073564857] 'process raft request' (duration: 24.679789ms)","trace[1073564857] 'compare' (duration: 95.990301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:45:16.44046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.139888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-22T16:45:16.440696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.903368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T16:45:16.440779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.526708ms","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-04-22T16:45:16.440819Z","caller":"traceutil/trace.go:171","msg":"trace[92707521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9326; }","duration":"322.57922ms","start":"2026-04-22T16:45:16.118222Z","end":"2026-04-22T16:45:16.440802Z","steps":["trace[92707521] 'range keys from in-memory index tree' (duration: 322.363745ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:16.440819Z","caller":"traceutil/trace.go:171","msg":"trace[459009353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9326; }","duration":"336.03663ms","start":"2026-04-22T16:45:16.104758Z","end":"2026-04-22T16:45:16.440795Z","steps":["trace[459009353] 'range keys from in-memory index tree' (duration: 335.784375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:16.44087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:16.104742Z","time spent":"336.114203ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T16:45:16.440877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.59698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-22T16:45:16.440898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.774405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-22T16:45:16.440876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.351766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-22T16:45:16.440966Z","caller":"traceutil/trace.go:171","msg":"trace[907322136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9326; }","duration":"325.861647ms","start":"2026-04-22T16:45:16.115086Z","end":"2026-04-22T16:45:16.440948Z","steps":["trace[907322136] 'range keys from in-memory index tree' (duration: 325.650792ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:16.440974Z","caller":"traceutil/trace.go:171","msg":"trace[1605406643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9326; }","duration":"322.684112ms","start":"2026-04-22T16:45:16.118229Z","end":"2026-04-22T16:45:16.440913Z","steps":["trace[1605406643] 'range keys from in-memory index tree' (duration: 322.460937ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:16.440996Z","caller":"traceutil/trace.go:171","msg":"trace[185968578] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9326; }","duration":"369.5217ms","start":"2026-04-22T16:45:16.071449Z","end":"2026-04-22T16:45:16.44097Z","steps":["trace[185968578] 'range keys from in-memory index tree' (duration: 369.053539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:16.441005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:16.115077Z","time spent":"325.916719ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T16:45:16.441043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:16.118206Z","time spent":"322.826985ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:45:16.441053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:16.071438Z","time spent":"369.603431ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-22T16:45:16.441128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:16.118212Z","time spent":"322.633961ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:45:16.440651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.554575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-22T16:45:16.441212Z","caller":"traceutil/trace.go:171","msg":"trace[1342579328] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9326; }","duration":"327.126448ms","start":"2026-04-22T16:45:16.114027Z","end":"2026-04-22T16:45:16.441153Z","steps":["trace[1342579328] 'range keys from in-memory index tree' (duration: 326.238547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:16.441293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:16.114017Z","time spent":"327.261022ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T16:45:16.44272Z","caller":"traceutil/trace.go:171","msg":"trace[98846297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9326; }","duration":"316.440453ms","start":"2026-04-22T16:45:16.126258Z","end":"2026-04-22T16:45:16.442698Z","steps":["trace[98846297] 'range keys from in-memory index tree' (duration: 313.871811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:16.44309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:16.12625Z","time spent":"316.822892ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T16:45:56.201798Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6244e483e416226f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T16:45:56.208736Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T16:45:56.208857Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-22T16:46:39.490013Z","caller":"traceutil/trace.go:171","msg":"trace[24558450] transaction","detail":"{read_only:false; response_revision:9995; number_of_response:1; }","duration":"115.186069ms","start":"2026-04-22T16:46:39.374802Z","end":"2026-04-22T16:46:39.489988Z","steps":["trace[24558450] 'process raft request' (duration: 115.003615ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:15.461681Z","caller":"traceutil/trace.go:171","msg":"trace[1314853881] transaction","detail":"{read_only:false; response_revision:10266; number_of_response:1; }","duration":"195.991053ms","start":"2026-04-22T16:47:15.265672Z","end":"2026-04-22T16:47:15.461663Z","steps":["trace[1314853881] 'process raft request' (duration: 195.85144ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:15.532937Z","caller":"traceutil/trace.go:171","msg":"trace[169650300] linearizableReadLoop","detail":"{readStateIndex:10621; appliedIndex:10620; }","duration":"229.768719ms","start":"2026-04-22T16:47:15.30314Z","end":"2026-04-22T16:47:15.532909Z","steps":["trace[169650300] 'read index received' (duration: 158.52118ms)","trace[169650300] 'applied index is now lower than readState.Index' (duration: 71.245849ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:47:15.533052Z","caller":"traceutil/trace.go:171","msg":"trace[390265617] transaction","detail":"{read_only:false; response_revision:10267; number_of_response:1; }","duration":"260.497902ms","start":"2026-04-22T16:47:15.272515Z","end":"2026-04-22T16:47:15.533013Z","steps":["trace[390265617] 'process raft request' (duration: 245.124856ms)","trace[390265617] 'compare' (duration: 15.128011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:47:15.533253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.61143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T16:47:15.533253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.107757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T16:47:15.533333Z","caller":"traceutil/trace.go:171","msg":"trace[1401246673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10267; }","duration":"173.744854ms","start":"2026-04-22T16:47:15.359579Z","end":"2026-04-22T16:47:15.533324Z","steps":["trace[1401246673] 'agreement among raft nodes before linearized reading' (duration: 173.59214ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:15.533368Z","caller":"traceutil/trace.go:171","msg":"trace[459895025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10267; }","duration":"230.262851ms","start":"2026-04-22T16:47:15.303087Z","end":"2026-04-22T16:47:15.53335Z","steps":["trace[459895025] 'agreement among raft nodes before linearized reading' (duration: 230.023676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:47:15.533433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.513816ms","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-04-22T16:47:15.533586Z","caller":"traceutil/trace.go:171","msg":"trace[1873092267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10267; }","duration":"123.611298ms","start":"2026-04-22T16:47:15.409863Z","end":"2026-04-22T16:47:15.533474Z","steps":["trace[1873092267] 'agreement among raft nodes before linearized reading' (duration: 123.411113ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:25.732023Z","caller":"traceutil/trace.go:171","msg":"trace[620984546] transaction","detail":"{read_only:false; response_revision:10400; number_of_response:1; }","duration":"122.162654ms","start":"2026-04-22T16:47:25.609826Z","end":"2026-04-22T16:47:25.731988Z","steps":["trace[620984546] 'process raft request' (duration: 121.371725ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:25.732382Z","caller":"traceutil/trace.go:171","msg":"trace[1162076073] transaction","detail":"{read_only:false; response_revision:10401; number_of_response:1; }","duration":"121.994509ms","start":"2026-04-22T16:47:25.610356Z","end":"2026-04-22T16:47:25.73235Z","steps":["trace[1162076073] 'process raft request' (duration: 121.566859ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:25.733568Z","caller":"traceutil/trace.go:171","msg":"trace[1106207273] transaction","detail":"{read_only:false; response_revision:10402; number_of_response:1; }","duration":"122.961172ms","start":"2026-04-22T16:47:25.610582Z","end":"2026-04-22T16:47:25.733543Z","steps":["trace[1106207273] 'process raft request' (duration: 121.58882ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:25.73473Z","caller":"traceutil/trace.go:171","msg":"trace[672799865] transaction","detail":"{read_only:false; response_revision:10404; number_of_response:1; }","duration":"122.921401ms","start":"2026-04-22T16:47:25.611785Z","end":"2026-04-22T16:47:25.734707Z","steps":["trace[672799865] 'process raft request' (duration: 122.706916ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:25.735331Z","caller":"traceutil/trace.go:171","msg":"trace[2068917271] transaction","detail":"{read_only:false; response_revision:10403; number_of_response:1; }","duration":"123.617117ms","start":"2026-04-22T16:47:25.611685Z","end":"2026-04-22T16:47:25.735302Z","steps":["trace[2068917271] 'process raft request' (duration: 121.683941ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:48:17.100248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8311} {"level":"info","ts":"2026-04-22T16:48:17.180101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8311,"took":"76.065354ms","hash":1037556679,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":25190400,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-22T16:48:17.180161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1037556679,"revision":8311,"compact-revision":6112} {"level":"info","ts":"2026-04-22T16:53:17.105725Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10730} {"level":"info","ts":"2026-04-22T16:53:17.181234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10730,"took":"73.626257ms","hash":1811065570,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":20557824,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-22T16:53:17.181296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1811065570,"revision":10730,"compact-revision":8311}