{"level":"warn","ts":"2026-03-11T19:29:31.638189Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T19:29:31.638319Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-03-11T19:29:31.638417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T19:29:31.63844Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-03-11T19:29:31.638468Z","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-03-11T19:29:31.63931Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-03-11T19:29:31.639428Z","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.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-03-11T19:29:31.644162Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.481408ms"} {"level":"info","ts":"2026-03-11T19:29:31.650306Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-03-11T19:29:31.650428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-11T19:29:31.650461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-03-11T19:29:31.650474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-11T19:29:31.650492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-03-11T19:29:31.650543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-03-11T19:29:31.655496Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-11T19:29:31.656664Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-11T19:29:31.657815Z","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-03-11T19:29:31.659585Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-11T19:29:31.659742Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-11T19:29:31.659881Z","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-03-11T19:29:31.659979Z","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-03-11T19:29:31.659997Z","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-03-11T19:29:31.661097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-03-11T19:29:31.661581Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-03-11T19:29:31.663899Z","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-03-11T19:29:31.664056Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-03-11T19:29:31.664146Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-03-11T19:29:31.664266Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-11T19:29:31.664347Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-11T19:29:32.451362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-11T19:29:32.451441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-11T19:29:32.451474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-03-11T19:29:32.451498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-03-11T19:29:32.451513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-03-11T19:29:32.451529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-03-11T19:29:32.451542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-03-11T19:29:32.452698Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T19:29:32.453703Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T19:29:32.453703Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-03-11T19:29:32.45379Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T19:29:32.454157Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-11T19:29:32.454375Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-11T19:29:32.457634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-11T19:29:32.45798Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-03-11T19:29:32.463121Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T19:29:32.463215Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T19:29:32.463249Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T19:30:13.587649Z","caller":"traceutil/trace.go:171","msg":"trace[730564448] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"282.566948ms","start":"2026-03-11T19:30:13.305056Z","end":"2026-03-11T19:30:13.587623Z","steps":["trace[730564448] 'process raft request' (duration: 282.406634ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:30:14.52815Z","caller":"traceutil/trace.go:171","msg":"trace[1262568474] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"104.392738ms","start":"2026-03-11T19:30:14.423725Z","end":"2026-03-11T19:30:14.528118Z","steps":["trace[1262568474] 'process raft request' (duration: 101.638206ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:30:14.529037Z","caller":"traceutil/trace.go:171","msg":"trace[1220324947] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"104.041129ms","start":"2026-03-11T19:30:14.424966Z","end":"2026-03-11T19:30:14.529007Z","steps":["trace[1220324947] 'process raft request' (duration: 103.7054ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:31:27.829745Z","caller":"traceutil/trace.go:171","msg":"trace[2144205595] linearizableReadLoop","detail":"{readStateIndex:1643; appliedIndex:1642; }","duration":"329.040576ms","start":"2026-03-11T19:31:27.500679Z","end":"2026-03-11T19:31:27.82972Z","steps":["trace[2144205595] 'read index received' (duration: 328.814459ms)","trace[2144205595] 'applied index is now lower than readState.Index' (duration: 225.097µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:31:27.829874Z","caller":"traceutil/trace.go:171","msg":"trace[155467305] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"356.433536ms","start":"2026-03-11T19:31:27.473407Z","end":"2026-03-11T19:31:27.82984Z","steps":["trace[155467305] 'process raft request' (duration: 356.157468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:31:27.829965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.264721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T19:31:27.830087Z","caller":"traceutil/trace.go:171","msg":"trace[2113212085] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1577; }","duration":"329.453426ms","start":"2026-03-11T19:31:27.500622Z","end":"2026-03-11T19:31:27.830076Z","steps":["trace[2113212085] 'agreement among raft nodes before linearized reading' (duration: 329.264551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:31:27.830144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:31:27.500593Z","time spent":"329.539368ms","remote":"127.0.0.1:47540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":22,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-11T19:31:27.830245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.520811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e10b270-8402-4b88-83b7-fd2d718c9bff\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-03-11T19:31:27.83032Z","caller":"traceutil/trace.go:171","msg":"trace[1076188658] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e10b270-8402-4b88-83b7-fd2d718c9bff; range_end:; response_count:1; response_revision:1577; }","duration":"146.679766ms","start":"2026-03-11T19:31:27.683622Z","end":"2026-03-11T19:31:27.830302Z","steps":["trace[1076188658] 'agreement among raft nodes before linearized reading' (duration: 146.417609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:31:27.830642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:31:27.473377Z","time spent":"356.58412ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T19:31:28.274921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.762208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-11T19:31:28.275182Z","caller":"traceutil/trace.go:171","msg":"trace[184140618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1578; }","duration":"169.069816ms","start":"2026-03-11T19:31:28.106092Z","end":"2026-03-11T19:31:28.275162Z","steps":["trace[184140618] 'range keys from in-memory index tree' (duration: 168.546261ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:31:39.359891Z","caller":"traceutil/trace.go:171","msg":"trace[1653634539] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"154.58809ms","start":"2026-03-11T19:31:39.205277Z","end":"2026-03-11T19:31:39.359865Z","steps":["trace[1653634539] 'process raft request' (duration: 154.358815ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:32:06.618312Z","caller":"traceutil/trace.go:171","msg":"trace[280859100] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"102.410086ms","start":"2026-03-11T19:32:06.51587Z","end":"2026-03-11T19:32:06.61828Z","steps":["trace[280859100] 'read index received' (duration: 102.14624ms)","trace[280859100] 'applied index is now lower than readState.Index' (duration: 262.426µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:32:06.618409Z","caller":"traceutil/trace.go:171","msg":"trace[1645696630] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"113.014722ms","start":"2026-03-11T19:32:06.505376Z","end":"2026-03-11T19:32:06.618391Z","steps":["trace[1645696630] 'process raft request' (duration: 112.731034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:32:06.618641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.749345ms","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-03-11T19:32:06.618694Z","caller":"traceutil/trace.go:171","msg":"trace[598721087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1821; }","duration":"102.839848ms","start":"2026-03-11T19:32:06.51584Z","end":"2026-03-11T19:32:06.61868Z","steps":["trace[598721087] 'agreement among raft nodes before linearized reading' (duration: 102.588522ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:32:12.631909Z","caller":"traceutil/trace.go:171","msg":"trace[849436280] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"127.19739ms","start":"2026-03-11T19:32:12.504679Z","end":"2026-03-11T19:32:12.631876Z","steps":["trace[849436280] 'process raft request' (duration: 90.448357ms)","trace[849436280] 'compare' (duration: 36.61508ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:32:18.515271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.226997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-11T19:32:18.515369Z","caller":"traceutil/trace.go:171","msg":"trace[32096187] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1876; }","duration":"172.405931ms","start":"2026-03-11T19:32:18.342938Z","end":"2026-03-11T19:32:18.515344Z","steps":["trace[32096187] 'range keys from in-memory index tree' (duration: 171.950171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:32:18.908289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.95983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T19:32:18.908365Z","caller":"traceutil/trace.go:171","msg":"trace[2077154046] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1878; }","duration":"270.050292ms","start":"2026-03-11T19:32:18.638295Z","end":"2026-03-11T19:32:18.908346Z","steps":["trace[2077154046] 'count revisions from in-memory index tree' (duration: 269.827797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:32:18.908398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.152455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-11T19:32:18.908476Z","caller":"traceutil/trace.go:171","msg":"trace[1117449373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1878; }","duration":"256.288497ms","start":"2026-03-11T19:32:18.652168Z","end":"2026-03-11T19:32:18.908457Z","steps":["trace[1117449373] 'range keys from in-memory index tree' (duration: 255.863806ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:32:18.908526Z","caller":"traceutil/trace.go:171","msg":"trace[750847638] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"216.009078ms","start":"2026-03-11T19:32:18.692493Z","end":"2026-03-11T19:32:18.908502Z","steps":["trace[750847638] 'read index received' (duration: 121.187319ms)","trace[750847638] 'applied index is now lower than readState.Index' (duration: 94.820689ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:32:18.908763Z","caller":"traceutil/trace.go:171","msg":"trace[1239274321] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"242.527983ms","start":"2026-03-11T19:32:18.666182Z","end":"2026-03-11T19:32:18.90871Z","steps":["trace[1239274321] 'process raft request' (duration: 147.593981ms)","trace[1239274321] 'compare' (duration: 94.614223ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:32:18.908842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.343426ms","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-03-11T19:32:18.908891Z","caller":"traceutil/trace.go:171","msg":"trace[1494527576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1879; }","duration":"216.448508ms","start":"2026-03-11T19:32:18.692429Z","end":"2026-03-11T19:32:18.908878Z","steps":["trace[1494527576] 'agreement among raft nodes before linearized reading' (duration: 216.168631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:33:06.279112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.037344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T19:33:06.279377Z","caller":"traceutil/trace.go:171","msg":"trace[8276113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2283; }","duration":"155.335201ms","start":"2026-03-11T19:33:06.124016Z","end":"2026-03-11T19:33:06.279351Z","steps":["trace[8276113] 'range keys from in-memory index tree' (duration: 154.948052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:33:40.311311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.828401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T19:33:40.311416Z","caller":"traceutil/trace.go:171","msg":"trace[796574030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2472; }","duration":"184.987095ms","start":"2026-03-11T19:33:40.126408Z","end":"2026-03-11T19:33:40.311395Z","steps":["trace[796574030] 'range keys from in-memory index tree' (duration: 184.712368ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:33:45.664025Z","caller":"traceutil/trace.go:171","msg":"trace[1845464808] linearizableReadLoop","detail":"{readStateIndex:2601; appliedIndex:2600; }","duration":"369.334495ms","start":"2026-03-11T19:33:45.294662Z","end":"2026-03-11T19:33:45.663996Z","steps":["trace[1845464808] 'read index received' (duration: 369.085219ms)","trace[1845464808] 'applied index is now lower than readState.Index' (duration: 247.726µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:33:45.664345Z","caller":"traceutil/trace.go:171","msg":"trace[2111997956] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"448.98501ms","start":"2026-03-11T19:33:45.215343Z","end":"2026-03-11T19:33:45.664328Z","steps":["trace[2111997956] 'process raft request' (duration: 448.487077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:33:45.664508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:33:45.215292Z","time spent":"449.123863ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T19:33:45.664893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.227868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-11T19:33:45.664953Z","caller":"traceutil/trace.go:171","msg":"trace[177756152] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2499; }","duration":"370.322241ms","start":"2026-03-11T19:33:45.294615Z","end":"2026-03-11T19:33:45.664938Z","steps":["trace[177756152] 'agreement among raft nodes before linearized reading' (duration: 370.152635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:33:45.664999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:33:45.294596Z","time spent":"370.393572ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-03-11T19:33:45.665282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.115503ms","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-03-11T19:33:45.665343Z","caller":"traceutil/trace.go:171","msg":"trace[1741484182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2499; }","duration":"359.208735ms","start":"2026-03-11T19:33:45.306122Z","end":"2026-03-11T19:33:45.66533Z","steps":["trace[1741484182] 'agreement among raft nodes before linearized reading' (duration: 359.067502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:33:45.66539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:33:45.306104Z","time spent":"359.274997ms","remote":"127.0.0.1:47636","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-03-11T19:33:45.665725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.219287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T19:33:45.665784Z","caller":"traceutil/trace.go:171","msg":"trace[521610854] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2499; }","duration":"154.31422ms","start":"2026-03-11T19:33:45.511455Z","end":"2026-03-11T19:33:45.665769Z","steps":["trace[521610854] 'agreement among raft nodes before linearized reading' (duration: 154.218057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:33:45.666053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.086889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-11T19:33:45.666105Z","caller":"traceutil/trace.go:171","msg":"trace[36716415] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:2499; }","duration":"339.163791ms","start":"2026-03-11T19:33:45.326926Z","end":"2026-03-11T19:33:45.66609Z","steps":["trace[36716415] 'agreement among raft nodes before linearized reading' (duration: 339.041298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:33:45.666148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:33:45.326911Z","time spent":"339.227743ms","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-03-11T19:33:45.667418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.840377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-11T19:33:45.667499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.315893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-11T19:33:45.667527Z","caller":"traceutil/trace.go:171","msg":"trace[90386983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2499; }","duration":"361.016841ms","start":"2026-03-11T19:33:45.306492Z","end":"2026-03-11T19:33:45.667509Z","steps":["trace[90386983] 'agreement among raft nodes before linearized reading' (duration: 360.707934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:33:45.667572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:33:45.306486Z","time spent":"361.076752ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-11T19:33:45.667572Z","caller":"traceutil/trace.go:171","msg":"trace[1409398112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2499; }","duration":"360.413035ms","start":"2026-03-11T19:33:45.30714Z","end":"2026-03-11T19:33:45.667553Z","steps":["trace[1409398112] 'agreement among raft nodes before linearized reading' (duration: 360.2067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:33:45.667612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:33:45.307133Z","time spent":"360.470917ms","remote":"127.0.0.1:47636","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-03-11T19:33:45.807518Z","caller":"traceutil/trace.go:171","msg":"trace[1841364969] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"134.079529ms","start":"2026-03-11T19:33:45.673411Z","end":"2026-03-11T19:33:45.80749Z","steps":["trace[1841364969] 'process raft request' (duration: 133.986097ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:33:45.80758Z","caller":"traceutil/trace.go:171","msg":"trace[1620080270] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"134.235863ms","start":"2026-03-11T19:33:45.673318Z","end":"2026-03-11T19:33:45.807554Z","steps":["trace[1620080270] 'process raft request' (duration: 133.890894ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:33:45.807827Z","caller":"traceutil/trace.go:171","msg":"trace[171825606] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"134.366067ms","start":"2026-03-11T19:33:45.673449Z","end":"2026-03-11T19:33:45.807815Z","steps":["trace[171825606] 'process raft request' (duration: 133.987367ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:33:45.808057Z","caller":"traceutil/trace.go:171","msg":"trace[2071257848] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"134.593573ms","start":"2026-03-11T19:33:45.673433Z","end":"2026-03-11T19:33:45.808026Z","steps":["trace[2071257848] 'process raft request' (duration: 134.295166ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:36:00.910896Z","caller":"traceutil/trace.go:171","msg":"trace[1535436427] transaction","detail":"{read_only:false; response_revision:3346; number_of_response:1; }","duration":"112.288517ms","start":"2026-03-11T19:36:00.798575Z","end":"2026-03-11T19:36:00.910864Z","steps":["trace[1535436427] 'process raft request' (duration: 112.081542ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:36:01.07494Z","caller":"traceutil/trace.go:171","msg":"trace[1401172829] linearizableReadLoop","detail":"{readStateIndex:3485; appliedIndex:3484; }","duration":"144.481585ms","start":"2026-03-11T19:36:00.930427Z","end":"2026-03-11T19:36:01.074909Z","steps":["trace[1401172829] 'read index received' (duration: 141.392485ms)","trace[1401172829] 'applied index is now lower than readState.Index' (duration: 3.08777ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:36:01.075069Z","caller":"traceutil/trace.go:171","msg":"trace[606031341] transaction","detail":"{read_only:false; response_revision:3348; number_of_response:1; }","duration":"144.956747ms","start":"2026-03-11T19:36:00.930087Z","end":"2026-03-11T19:36:01.075044Z","steps":["trace[606031341] 'process raft request' (duration: 141.721803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:36:01.075286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.848765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-03-11T19:36:01.075355Z","caller":"traceutil/trace.go:171","msg":"trace[717301483] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3348; }","duration":"144.942147ms","start":"2026-03-11T19:36:00.93039Z","end":"2026-03-11T19:36:01.075332Z","steps":["trace[717301483] 'agreement among raft nodes before linearized reading' (duration: 144.675721ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:11.480559Z","caller":"traceutil/trace.go:171","msg":"trace[1628626478] linearizableReadLoop","detail":"{readStateIndex:4333; appliedIndex:4331; }","duration":"160.991303ms","start":"2026-03-11T19:37:11.319543Z","end":"2026-03-11T19:37:11.480535Z","steps":["trace[1628626478] 'read index received' (duration: 130.945003ms)","trace[1628626478] 'applied index is now lower than readState.Index' (duration: 30.04521ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:37:11.480639Z","caller":"traceutil/trace.go:171","msg":"trace[1807999641] transaction","detail":"{read_only:false; response_revision:4161; number_of_response:1; }","duration":"173.735214ms","start":"2026-03-11T19:37:11.306874Z","end":"2026-03-11T19:37:11.480609Z","steps":["trace[1807999641] 'process raft request' (duration: 173.60082ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:11.480688Z","caller":"traceutil/trace.go:171","msg":"trace[511374005] transaction","detail":"{read_only:false; response_revision:4160; number_of_response:1; }","duration":"175.323274ms","start":"2026-03-11T19:37:11.305332Z","end":"2026-03-11T19:37:11.480656Z","steps":["trace[511374005] 'process raft request' (duration: 144.960316ms)","trace[511374005] 'compare' (duration: 29.979888ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:37:11.480847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.276761ms","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-03-11T19:37:11.480893Z","caller":"traceutil/trace.go:171","msg":"trace[1051577572] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4161; }","duration":"161.378352ms","start":"2026-03-11T19:37:11.319504Z","end":"2026-03-11T19:37:11.480882Z","steps":["trace[1051577572] 'agreement among raft nodes before linearized reading' (duration: 161.210008ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:16.73862Z","caller":"traceutil/trace.go:171","msg":"trace[1285066363] linearizableReadLoop","detail":"{readStateIndex:4489; appliedIndex:4487; }","duration":"212.206763ms","start":"2026-03-11T19:37:16.526356Z","end":"2026-03-11T19:37:16.738562Z","steps":["trace[1285066363] 'read index received' (duration: 47.519934ms)","trace[1285066363] 'applied index is now lower than readState.Index' (duration: 164.685769ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:37:16.738682Z","caller":"traceutil/trace.go:171","msg":"trace[472204379] transaction","detail":"{read_only:false; response_revision:4310; number_of_response:1; }","duration":"217.802639ms","start":"2026-03-11T19:37:16.520849Z","end":"2026-03-11T19:37:16.738652Z","steps":["trace[472204379] 'process raft request' (duration: 127.222146ms)","trace[472204379] 'compare' (duration: 90.365288ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:37:16.738894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.510361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8wmws\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-11T19:37:16.738934Z","caller":"traceutil/trace.go:171","msg":"trace[1704751737] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8wmws; range_end:; response_count:1; response_revision:4310; }","duration":"212.648284ms","start":"2026-03-11T19:37:16.526275Z","end":"2026-03-11T19:37:16.738924Z","steps":["trace[1704751737] 'agreement among raft nodes before linearized reading' (duration: 212.474209ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:16.811607Z","caller":"traceutil/trace.go:171","msg":"trace[1920144433] transaction","detail":"{read_only:false; response_revision:4311; number_of_response:1; }","duration":"232.893831ms","start":"2026-03-11T19:37:16.578688Z","end":"2026-03-11T19:37:16.811582Z","steps":["trace[1920144433] 'process raft request' (duration: 232.737477ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:16.899117Z","caller":"traceutil/trace.go:171","msg":"trace[1471446324] linearizableReadLoop","detail":"{readStateIndex:4491; appliedIndex:4489; }","duration":"160.386007ms","start":"2026-03-11T19:37:16.73871Z","end":"2026-03-11T19:37:16.899096Z","steps":["trace[1471446324] 'read index received' (duration: 72.72991ms)","trace[1471446324] 'applied index is now lower than readState.Index' (duration: 87.655207ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:37:16.899183Z","caller":"traceutil/trace.go:171","msg":"trace[1884575232] transaction","detail":"{read_only:false; response_revision:4312; number_of_response:1; }","duration":"247.982382ms","start":"2026-03-11T19:37:16.65116Z","end":"2026-03-11T19:37:16.899142Z","steps":["trace[1884575232] 'process raft request' (duration: 247.769817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:16.899318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.276898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T19:37:16.899354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.881535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11453"} {"level":"info","ts":"2026-03-11T19:37:16.899382Z","caller":"traceutil/trace.go:171","msg":"trace[886974447] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:4312; }","duration":"296.398761ms","start":"2026-03-11T19:37:16.602966Z","end":"2026-03-11T19:37:16.899364Z","steps":["trace[886974447] 'agreement among raft nodes before linearized reading' (duration: 296.220977ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:16.899412Z","caller":"traceutil/trace.go:171","msg":"trace[1475699109] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4312; }","duration":"318.980517ms","start":"2026-03-11T19:37:16.580412Z","end":"2026-03-11T19:37:16.899393Z","steps":["trace[1475699109] 'agreement among raft nodes before linearized reading' (duration: 318.795972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:16.899461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:37:16.580397Z","time spent":"319.05005ms","remote":"127.0.0.1:47540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":11476,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-03-11T19:37:16.899487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.671748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62967"} {"level":"warn","ts":"2026-03-11T19:37:16.899508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.610301ms","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-03-11T19:37:16.89952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.173514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-11T19:37:16.899567Z","caller":"traceutil/trace.go:171","msg":"trace[1334094811] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4312; }","duration":"154.782111ms","start":"2026-03-11T19:37:16.744763Z","end":"2026-03-11T19:37:16.899546Z","steps":["trace[1334094811] 'agreement among raft nodes before linearized reading' (duration: 154.515014ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:16.899584Z","caller":"traceutil/trace.go:171","msg":"trace[239803222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4312; }","duration":"106.708103ms","start":"2026-03-11T19:37:16.792852Z","end":"2026-03-11T19:37:16.89956Z","steps":["trace[239803222] 'agreement among raft nodes before linearized reading' (duration: 106.478536ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:16.899601Z","caller":"traceutil/trace.go:171","msg":"trace[1272143965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4312; }","duration":"107.267417ms","start":"2026-03-11T19:37:16.792305Z","end":"2026-03-11T19:37:16.899572Z","steps":["trace[1272143965] 'agreement among raft nodes before linearized reading' (duration: 107.072411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:16.899911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.951798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-03-11T19:37:16.899984Z","caller":"traceutil/trace.go:171","msg":"trace[927203006] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4312; }","duration":"294.05182ms","start":"2026-03-11T19:37:16.605913Z","end":"2026-03-11T19:37:16.899964Z","steps":["trace[927203006] 'agreement among raft nodes before linearized reading' (duration: 293.851845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:20.611392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.518824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39ad1831-b030-4db2-b57c-60f1424c9f91\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-03-11T19:37:20.611481Z","caller":"traceutil/trace.go:171","msg":"trace[1850536317] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39ad1831-b030-4db2-b57c-60f1424c9f91; range_end:; response_count:1; response_revision:4464; }","duration":"244.657147ms","start":"2026-03-11T19:37:20.366802Z","end":"2026-03-11T19:37:20.61146Z","steps":["trace[1850536317] 'range keys from in-memory index tree' (duration: 244.309608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:20.611942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.200543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-2tfrf-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-03-11T19:37:20.612026Z","caller":"traceutil/trace.go:171","msg":"trace[1397148704] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-2tfrf-tls; range_end:; response_count:1; response_revision:4464; }","duration":"274.347328ms","start":"2026-03-11T19:37:20.337657Z","end":"2026-03-11T19:37:20.612005Z","steps":["trace[1397148704] 'range keys from in-memory index tree' (duration: 273.947757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:20.689847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.961154ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-11T19:37:20.689949Z","caller":"traceutil/trace.go:171","msg":"trace[726737921] linearizableReadLoop","detail":"{readStateIndex:4647; appliedIndex:4646; }","duration":"211.158767ms","start":"2026-03-11T19:37:20.478776Z","end":"2026-03-11T19:37:20.689935Z","steps":["trace[726737921] 'read index received' (duration: 51.342µs)","trace[726737921] 'applied index is now lower than readState.Index' (duration: 211.106245ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:37:20.690114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.336661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39ad1831-b030-4db2-b57c-60f1424c9f91\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-03-11T19:37:20.690141Z","caller":"traceutil/trace.go:171","msg":"trace[2011718355] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39ad1831-b030-4db2-b57c-60f1424c9f91; range_end:; response_count:1; response_revision:4464; }","duration":"211.417033ms","start":"2026-03-11T19:37:20.478716Z","end":"2026-03-11T19:37:20.690133Z","steps":["trace[2011718355] 'agreement among raft nodes before linearized reading' (duration: 211.28869ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:21.20859Z","caller":"traceutil/trace.go:171","msg":"trace[479192950] linearizableReadLoop","detail":"{readStateIndex:4651; appliedIndex:4650; }","duration":"313.27295ms","start":"2026-03-11T19:37:20.895294Z","end":"2026-03-11T19:37:21.208567Z","steps":["trace[479192950] 'read index received' (duration: 313.161036ms)","trace[479192950] 'applied index is now lower than readState.Index' (duration: 110.804µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:37:21.208673Z","caller":"traceutil/trace.go:171","msg":"trace[1111623019] transaction","detail":"{read_only:false; response_revision:4468; number_of_response:1; }","duration":"317.197951ms","start":"2026-03-11T19:37:20.891455Z","end":"2026-03-11T19:37:21.208653Z","steps":["trace[1111623019] 'process raft request' (duration: 316.966115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:21.208777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:37:20.891435Z","time spent":"317.279013ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2500,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T19:37:21.209121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.813523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777462"} {"level":"info","ts":"2026-03-11T19:37:21.209165Z","caller":"traceutil/trace.go:171","msg":"trace[759222348] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4468; }","duration":"313.928066ms","start":"2026-03-11T19:37:20.895226Z","end":"2026-03-11T19:37:21.209154Z","steps":["trace[759222348] 'agreement among raft nodes before linearized reading' (duration: 313.457784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:21.209205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:37:20.895209Z","time spent":"313.986899ms","remote":"127.0.0.1:43506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":777485,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-03-11T19:37:21.215165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.989948ms","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-03-11T19:37:21.215199Z","caller":"traceutil/trace.go:171","msg":"trace[29506239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4469; }","duration":"187.06491ms","start":"2026-03-11T19:37:21.028127Z","end":"2026-03-11T19:37:21.215192Z","steps":["trace[29506239] 'agreement among raft nodes before linearized reading' (duration: 186.972218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:21.215272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.528074ms","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-03-11T19:37:21.215308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.597718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-11T19:37:21.215348Z","caller":"traceutil/trace.go:171","msg":"trace[106528309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4469; }","duration":"296.652447ms","start":"2026-03-11T19:37:20.918678Z","end":"2026-03-11T19:37:21.21533Z","steps":["trace[106528309] 'agreement among raft nodes before linearized reading' (duration: 296.459123ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:21.21539Z","caller":"traceutil/trace.go:171","msg":"trace[358861060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4469; }","duration":"293.70778ms","start":"2026-03-11T19:37:20.921663Z","end":"2026-03-11T19:37:21.215371Z","steps":["trace[358861060] 'agreement among raft nodes before linearized reading' (duration: 293.452414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:21.2156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.629148ms","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-03-11T19:37:21.21565Z","caller":"traceutil/trace.go:171","msg":"trace[156100745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4469; }","duration":"291.704719ms","start":"2026-03-11T19:37:20.923933Z","end":"2026-03-11T19:37:21.215638Z","steps":["trace[156100745] 'agreement among raft nodes before linearized reading' (duration: 291.535844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:21.215774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.039682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8wmws\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-11T19:37:21.21581Z","caller":"traceutil/trace.go:171","msg":"trace[38784271] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8wmws; range_end:; response_count:1; response_revision:4469; }","duration":"301.077753ms","start":"2026-03-11T19:37:20.914722Z","end":"2026-03-11T19:37:21.2158Z","steps":["trace[38784271] 'agreement among raft nodes before linearized reading' (duration: 300.333354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:21.215838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:37:20.91468Z","time spent":"301.150185ms","remote":"127.0.0.1:47540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-8wmws\" "} {"level":"info","ts":"2026-03-11T19:37:38.695986Z","caller":"traceutil/trace.go:171","msg":"trace[1790838625] linearizableReadLoop","detail":"{readStateIndex:4988; appliedIndex:4987; }","duration":"115.582083ms","start":"2026-03-11T19:37:38.580369Z","end":"2026-03-11T19:37:38.695951Z","steps":["trace[1790838625] 'read index received' (duration: 115.311466ms)","trace[1790838625] 'applied index is now lower than readState.Index' (duration: 269.357µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:37:38.69654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.910542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-caf85da1-ccc5-4d12-909b-1fb5a3689353\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-03-11T19:37:38.69608Z","caller":"traceutil/trace.go:171","msg":"trace[64806091] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"121.943198ms","start":"2026-03-11T19:37:38.574104Z","end":"2026-03-11T19:37:38.696047Z","steps":["trace[64806091] 'process raft request' (duration: 121.625581ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:38.69666Z","caller":"traceutil/trace.go:171","msg":"trace[1444292874] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-caf85da1-ccc5-4d12-909b-1fb5a3689353; range_end:; response_count:1; response_revision:4798; }","duration":"116.312092ms","start":"2026-03-11T19:37:38.580325Z","end":"2026-03-11T19:37:38.696638Z","steps":["trace[1444292874] 'agreement among raft nodes before linearized reading' (duration: 115.82665ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:37:38.971792Z","caller":"traceutil/trace.go:171","msg":"trace[1649737973] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"144.096044ms","start":"2026-03-11T19:37:38.827667Z","end":"2026-03-11T19:37:38.971763Z","steps":["trace[1649737973] 'process raft request' (duration: 143.91949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:37:39.316984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.068025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T19:37:39.31708Z","caller":"traceutil/trace.go:171","msg":"trace[2143772204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4799; }","duration":"191.232328ms","start":"2026-03-11T19:37:39.125828Z","end":"2026-03-11T19:37:39.31706Z","steps":["trace[2143772204] 'range keys from in-memory index tree' (duration: 190.947641ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:39:32.481822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2733} {"level":"info","ts":"2026-03-11T19:39:32.540566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2733,"took":"57.025446ms","hash":673254373,"current-db-size-bytes":42192896,"current-db-size":"42 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-11T19:39:32.540715Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":673254373,"revision":2733,"compact-revision":-1} {"level":"info","ts":"2026-03-11T19:43:39.071104Z","caller":"traceutil/trace.go:171","msg":"trace[1566517083] transaction","detail":"{read_only:false; response_revision:7455; number_of_response:1; }","duration":"393.591059ms","start":"2026-03-11T19:43:38.677493Z","end":"2026-03-11T19:43:39.071084Z","steps":["trace[1566517083] 'process raft request' (duration: 393.466075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:43:39.071254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:43:38.677473Z","time spent":"393.694971ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:43:39.16272Z","caller":"traceutil/trace.go:171","msg":"trace[1919539976] transaction","detail":"{read_only:false; response_revision:7456; number_of_response:1; }","duration":"141.987316ms","start":"2026-03-11T19:43:39.020695Z","end":"2026-03-11T19:43:39.162682Z","steps":["trace[1919539976] 'process raft request' (duration: 128.612712ms)","trace[1919539976] 'compare' (duration: 13.257741ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:43:50.741861Z","caller":"traceutil/trace.go:171","msg":"trace[584139517] transaction","detail":"{read_only:false; response_revision:7511; number_of_response:1; }","duration":"383.514668ms","start":"2026-03-11T19:43:50.358322Z","end":"2026-03-11T19:43:50.741837Z","steps":["trace[584139517] 'process raft request' (duration: 383.371075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:43:50.742012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:43:50.358298Z","time spent":"383.633921ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:43:50.873069Z","caller":"traceutil/trace.go:171","msg":"trace[926877744] linearizableReadLoop","detail":"{readStateIndex:7793; appliedIndex:7791; }","duration":"217.01868ms","start":"2026-03-11T19:43:50.656019Z","end":"2026-03-11T19:43:50.873038Z","steps":["trace[926877744] 'read index received' (duration: 85.670807ms)","trace[926877744] 'applied index is now lower than readState.Index' (duration: 131.346553ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:43:50.873308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.267637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7cfbf4747d-pgn8j.189be0f28d1acf32\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-03-11T19:43:50.873399Z","caller":"traceutil/trace.go:171","msg":"trace[1410464309] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7cfbf4747d-pgn8j.189be0f28d1acf32; range_end:; response_count:1; response_revision:7511; }","duration":"217.41459ms","start":"2026-03-11T19:43:50.655963Z","end":"2026-03-11T19:43:50.873378Z","steps":["trace[1410464309] 'agreement among raft nodes before linearized reading' (duration: 217.165973ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:43:51.473626Z","caller":"traceutil/trace.go:171","msg":"trace[1646945425] transaction","detail":"{read_only:false; response_revision:7515; number_of_response:1; }","duration":"111.088992ms","start":"2026-03-11T19:43:51.362517Z","end":"2026-03-11T19:43:51.473606Z","steps":["trace[1646945425] 'process raft request' (duration: 110.980019ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:43:51.824725Z","caller":"traceutil/trace.go:171","msg":"trace[1900994591] transaction","detail":"{read_only:false; response_revision:7516; number_of_response:1; }","duration":"461.98337ms","start":"2026-03-11T19:43:51.362713Z","end":"2026-03-11T19:43:51.824696Z","steps":["trace[1900994591] 'process raft request' (duration: 418.498468ms)","trace[1900994591] 'compare' (duration: 43.05753ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:43:51.825188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:43:51.362693Z","time spent":"462.121823ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:43:51.825251Z","caller":"traceutil/trace.go:171","msg":"trace[386534193] transaction","detail":"{read_only:false; response_revision:7518; number_of_response:1; }","duration":"461.694111ms","start":"2026-03-11T19:43:51.363533Z","end":"2026-03-11T19:43:51.825227Z","steps":["trace[386534193] 'process raft request' (duration: 461.379994ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:43:51.825231Z","caller":"traceutil/trace.go:171","msg":"trace[1768310856] linearizableReadLoop","detail":"{readStateIndex:7801; appliedIndex:7798; }","duration":"287.018793ms","start":"2026-03-11T19:43:51.538182Z","end":"2026-03-11T19:43:51.825201Z","steps":["trace[1768310856] 'read index received' (duration: 243.05374ms)","trace[1768310856] 'applied index is now lower than readState.Index' (duration: 43.946182ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:43:51.825341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:43:51.363518Z","time spent":"461.774204ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:43:51.825358Z","caller":"traceutil/trace.go:171","msg":"trace[1255699376] transaction","detail":"{read_only:false; response_revision:7517; number_of_response:1; }","duration":"462.175624ms","start":"2026-03-11T19:43:51.363091Z","end":"2026-03-11T19:43:51.825266Z","steps":["trace[1255699376] 'process raft request' (duration: 461.534038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:43:51.825443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.237889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8wmws\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-11T19:43:51.825535Z","caller":"traceutil/trace.go:171","msg":"trace[1677100779] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8wmws; range_end:; response_count:1; response_revision:7518; }","duration":"287.373302ms","start":"2026-03-11T19:43:51.538138Z","end":"2026-03-11T19:43:51.825511Z","steps":["trace[1677100779] 'agreement among raft nodes before linearized reading' (duration: 287.155356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:43:51.825661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:43:51.363077Z","time spent":"462.425381ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:43:52.869861Z","caller":"traceutil/trace.go:171","msg":"trace[1113867031] transaction","detail":"{read_only:false; response_revision:7521; number_of_response:1; }","duration":"122.68352ms","start":"2026-03-11T19:43:52.747153Z","end":"2026-03-11T19:43:52.869837Z","steps":["trace[1113867031] 'process raft request' (duration: 122.528106ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:44:22.334466Z","caller":"traceutil/trace.go:171","msg":"trace[219083296] transaction","detail":"{read_only:false; response_revision:7718; number_of_response:1; }","duration":"327.84341ms","start":"2026-03-11T19:44:22.006596Z","end":"2026-03-11T19:44:22.334439Z","steps":["trace[219083296] 'process raft request' (duration: 327.708687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:44:22.334625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:44:22.006569Z","time spent":"327.976453ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:44:22.349917Z","caller":"traceutil/trace.go:171","msg":"trace[1538573319] linearizableReadLoop","detail":"{readStateIndex:8010; appliedIndex:8008; }","duration":"223.931921ms","start":"2026-03-11T19:44:22.125959Z","end":"2026-03-11T19:44:22.34989Z","steps":["trace[1538573319] 'read index received' (duration: 208.429744ms)","trace[1538573319] 'applied index is now lower than readState.Index' (duration: 15.500837ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:44:22.350031Z","caller":"traceutil/trace.go:171","msg":"trace[2129640197] transaction","detail":"{read_only:false; response_revision:7719; number_of_response:1; }","duration":"343.305667ms","start":"2026-03-11T19:44:22.006696Z","end":"2026-03-11T19:44:22.350002Z","steps":["trace[2129640197] 'process raft request' (duration: 343.050241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:44:22.350077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.090995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T19:44:22.350118Z","caller":"traceutil/trace.go:171","msg":"trace[859309177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7719; }","duration":"224.180047ms","start":"2026-03-11T19:44:22.125928Z","end":"2026-03-11T19:44:22.350108Z","steps":["trace[859309177] 'agreement among raft nodes before linearized reading' (duration: 224.090425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:44:22.350133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.089102ms","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-03-11T19:44:22.350181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:44:22.00668Z","time spent":"343.426089ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:44:22.350192Z","caller":"traceutil/trace.go:171","msg":"trace[917579486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7719; }","duration":"102.201136ms","start":"2026-03-11T19:44:22.247975Z","end":"2026-03-11T19:44:22.350176Z","steps":["trace[917579486] 'agreement among raft nodes before linearized reading' (duration: 102.084212ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:44:32.489522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5747} {"level":"info","ts":"2026-03-11T19:44:32.578978Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5747,"took":"87.788035ms","hash":856040578,"current-db-size-bytes":43319296,"current-db-size":"43 MB","current-db-size-in-use-bytes":23379968,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-11T19:44:32.579052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":856040578,"revision":5747,"compact-revision":2733} {"level":"info","ts":"2026-03-11T19:44:37.005175Z","caller":"traceutil/trace.go:171","msg":"trace[1535929721] transaction","detail":"{read_only:false; response_revision:7830; number_of_response:1; }","duration":"186.594557ms","start":"2026-03-11T19:44:36.818553Z","end":"2026-03-11T19:44:37.005148Z","steps":["trace[1535929721] 'process raft request' (duration: 186.422693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:44:37.262595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.503834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-11T19:44:37.262776Z","caller":"traceutil/trace.go:171","msg":"trace[1696245405] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7831; }","duration":"443.542723ms","start":"2026-03-11T19:44:36.819192Z","end":"2026-03-11T19:44:37.262735Z","steps":["trace[1696245405] 'process raft request' (duration: 288.784863ms)","trace[1696245405] 'compare' (duration: 154.30993ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:44:37.262854Z","caller":"traceutil/trace.go:171","msg":"trace[2016145727] transaction","detail":"{read_only:false; response_revision:7832; number_of_response:1; }","duration":"306.65785ms","start":"2026-03-11T19:44:36.956182Z","end":"2026-03-11T19:44:37.262839Z","steps":["trace[2016145727] 'process raft request' (duration: 306.488156ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:44:37.262912Z","caller":"traceutil/trace.go:171","msg":"trace[300602917] transaction","detail":"{read_only:false; response_revision:7833; number_of_response:1; }","duration":"235.107351ms","start":"2026-03-11T19:44:37.027771Z","end":"2026-03-11T19:44:37.262878Z","steps":["trace[300602917] 'process raft request' (duration: 235.067749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:44:37.262915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:44:36.81917Z","time spent":"443.670256ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-03-11T19:44:37.262922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:44:36.956167Z","time spent":"306.722202ms","remote":"127.0.0.1:43488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T19:44:37.26294Z","caller":"traceutil/trace.go:171","msg":"trace[1683773830] linearizableReadLoop","detail":"{readStateIndex:8129; appliedIndex:8127; }","duration":"306.837545ms","start":"2026-03-11T19:44:36.956073Z","end":"2026-03-11T19:44:37.262911Z","steps":["trace[1683773830] 'read index received' (duration: 48.933174ms)","trace[1683773830] 'applied index is now lower than readState.Index' (duration: 257.901751ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:44:37.263146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.989333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-kqw9l\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-03-11T19:44:37.263173Z","caller":"traceutil/trace.go:171","msg":"trace[563952894] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-kqw9l; range_end:; response_count:1; response_revision:7833; }","duration":"254.055914ms","start":"2026-03-11T19:44:37.00911Z","end":"2026-03-11T19:44:37.263166Z","steps":["trace[563952894] 'agreement among raft nodes before linearized reading' (duration: 253.955262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:44:37.26321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.113392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-kqw9l\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-03-11T19:44:37.263273Z","caller":"traceutil/trace.go:171","msg":"trace[895620336] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-kqw9l; range_end:; response_count:1; response_revision:7833; }","duration":"307.202593ms","start":"2026-03-11T19:44:36.956038Z","end":"2026-03-11T19:44:37.26324Z","steps":["trace[895620336] 'agreement among raft nodes before linearized reading' (duration: 307.014609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:44:37.263319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:44:36.956017Z","time spent":"307.292936ms","remote":"127.0.0.1:47540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4670,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-kqw9l\" "} {"level":"info","ts":"2026-03-11T19:44:38.312993Z","caller":"traceutil/trace.go:171","msg":"trace[1625422251] linearizableReadLoop","detail":"{readStateIndex:8141; appliedIndex:8140; }","duration":"220.805942ms","start":"2026-03-11T19:44:38.092162Z","end":"2026-03-11T19:44:38.312968Z","steps":["trace[1625422251] 'read index received' (duration: 220.620838ms)","trace[1625422251] 'applied index is now lower than readState.Index' (duration: 183.914µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T19:44:38.313179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.422048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T19:44:38.313221Z","caller":"traceutil/trace.go:171","msg":"trace[608546105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7843; }","duration":"189.512011ms","start":"2026-03-11T19:44:38.123699Z","end":"2026-03-11T19:44:38.313211Z","steps":["trace[608546105] 'agreement among raft nodes before linearized reading' (duration: 189.423439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:44:38.31322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.021448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8wmws\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-11T19:44:38.313311Z","caller":"traceutil/trace.go:171","msg":"trace[909956615] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8wmws; range_end:; response_count:1; response_revision:7843; }","duration":"221.144732ms","start":"2026-03-11T19:44:38.092146Z","end":"2026-03-11T19:44:38.313291Z","steps":["trace[909956615] 'agreement among raft nodes before linearized reading' (duration: 220.955767ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:44:38.313124Z","caller":"traceutil/trace.go:171","msg":"trace[1125549285] transaction","detail":"{read_only:false; response_revision:7843; number_of_response:1; }","duration":"225.364537ms","start":"2026-03-11T19:44:38.087725Z","end":"2026-03-11T19:44:38.313089Z","steps":["trace[1125549285] 'process raft request' (duration: 225.050949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:44:38.314232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.742496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-03-11T19:44:38.314295Z","caller":"traceutil/trace.go:171","msg":"trace[1502884329] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:7843; }","duration":"189.837718ms","start":"2026-03-11T19:44:38.124441Z","end":"2026-03-11T19:44:38.314278Z","steps":["trace[1502884329] 'agreement among raft nodes before linearized reading' (duration: 188.895935ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:44:39.589758Z","caller":"traceutil/trace.go:171","msg":"trace[1724029989] transaction","detail":"{read_only:false; response_revision:7850; number_of_response:1; }","duration":"116.666778ms","start":"2026-03-11T19:44:39.473069Z","end":"2026-03-11T19:44:39.589736Z","steps":["trace[1724029989] 'process raft request' (duration: 116.581946ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:45:34.688456Z","caller":"traceutil/trace.go:171","msg":"trace[702589134] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"104.829152ms","start":"2026-03-11T19:45:34.583596Z","end":"2026-03-11T19:45:34.688426Z","steps":["trace[702589134] 'process raft request' (duration: 104.592157ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:47:15.339668Z","caller":"traceutil/trace.go:171","msg":"trace[1450338568] linearizableReadLoop","detail":"{readStateIndex:9428; appliedIndex:9427; }","duration":"159.029472ms","start":"2026-03-11T19:47:15.180605Z","end":"2026-03-11T19:47:15.339635Z","steps":["trace[1450338568] 'read index received' (duration: 153.666595ms)","trace[1450338568] 'applied index is now lower than readState.Index' (duration: 5.361437ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:47:15.339679Z","caller":"traceutil/trace.go:171","msg":"trace[405948519] transaction","detail":"{read_only:false; response_revision:9093; number_of_response:1; }","duration":"345.469374ms","start":"2026-03-11T19:47:14.994184Z","end":"2026-03-11T19:47:15.339653Z","steps":["trace[405948519] 'process raft request' (duration: 340.057316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:47:15.339885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.273538ms","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-03-11T19:47:15.339937Z","caller":"traceutil/trace.go:171","msg":"trace[1567153892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9093; }","duration":"159.367061ms","start":"2026-03-11T19:47:15.180555Z","end":"2026-03-11T19:47:15.339922Z","steps":["trace[1567153892] 'agreement among raft nodes before linearized reading' (duration: 159.195686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:47:15.339882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:47:14.994165Z","time spent":"345.627019ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:47:15.340509Z","caller":"traceutil/trace.go:171","msg":"trace[1309301490] transaction","detail":"{read_only:false; response_revision:9094; number_of_response:1; }","duration":"102.245577ms","start":"2026-03-11T19:47:15.238242Z","end":"2026-03-11T19:47:15.340488Z","steps":["trace[1309301490] 'process raft request' (duration: 101.663583ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:47:19.838569Z","caller":"traceutil/trace.go:171","msg":"trace[1250772440] linearizableReadLoop","detail":"{readStateIndex:9457; appliedIndex:9456; }","duration":"468.860153ms","start":"2026-03-11T19:47:19.369679Z","end":"2026-03-11T19:47:19.838539Z","steps":["trace[1250772440] 'read index received' (duration: 468.619437ms)","trace[1250772440] 'applied index is now lower than readState.Index' (duration: 239.836µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:47:19.838665Z","caller":"traceutil/trace.go:171","msg":"trace[692881174] transaction","detail":"{read_only:false; response_revision:9121; number_of_response:1; }","duration":"471.736857ms","start":"2026-03-11T19:47:19.366891Z","end":"2026-03-11T19:47:19.838628Z","steps":["trace[692881174] 'process raft request' (duration: 471.498462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:47:19.83884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:47:19.366871Z","time spent":"471.869521ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T19:47:19.838845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.497696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8wmws\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-11T19:47:19.838911Z","caller":"traceutil/trace.go:171","msg":"trace[904559341] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8wmws; range_end:; response_count:1; response_revision:9121; }","duration":"363.59309ms","start":"2026-03-11T19:47:19.475304Z","end":"2026-03-11T19:47:19.838897Z","steps":["trace[904559341] 'agreement among raft nodes before linearized reading' (duration: 363.475087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:47:19.838953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:47:19.47529Z","time spent":"363.654771ms","remote":"127.0.0.1:47540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-8wmws\" "} {"level":"warn","ts":"2026-03-11T19:47:19.838852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.140361ms","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-03-11T19:47:19.839125Z","caller":"traceutil/trace.go:171","msg":"trace[1112862533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9121; }","duration":"469.459839ms","start":"2026-03-11T19:47:19.369655Z","end":"2026-03-11T19:47:19.839115Z","steps":["trace[1112862533] 'agreement among raft nodes before linearized reading' (duration: 469.045889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:47:19.839159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:47:19.369647Z","time spent":"469.50197ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-11T19:47:20.969828Z","caller":"traceutil/trace.go:171","msg":"trace[2097492444] transaction","detail":"{read_only:false; response_revision:9126; number_of_response:1; }","duration":"421.129582ms","start":"2026-03-11T19:47:20.548669Z","end":"2026-03-11T19:47:20.969799Z","steps":["trace[2097492444] 'process raft request' (duration: 420.961488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:47:20.969994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:47:20.548651Z","time spent":"421.253495ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:47:20.972412Z","caller":"traceutil/trace.go:171","msg":"trace[1797233264] linearizableReadLoop","detail":"{readStateIndex:9463; appliedIndex:9461; }","duration":"286.738692ms","start":"2026-03-11T19:47:20.685648Z","end":"2026-03-11T19:47:20.972387Z","steps":["trace[1797233264] 'read index received' (duration: 283.95802ms)","trace[1797233264] 'applied index is now lower than readState.Index' (duration: 2.779442ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:47:20.972475Z","caller":"traceutil/trace.go:171","msg":"trace[158544062] transaction","detail":"{read_only:false; response_revision:9127; number_of_response:1; }","duration":"188.675521ms","start":"2026-03-11T19:47:20.783774Z","end":"2026-03-11T19:47:20.972449Z","steps":["trace[158544062] 'process raft request' (duration: 188.487566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:47:20.972669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.014778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-03-11T19:47:20.972754Z","caller":"traceutil/trace.go:171","msg":"trace[1806154772] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:9127; }","duration":"287.139781ms","start":"2026-03-11T19:47:20.685596Z","end":"2026-03-11T19:47:20.972736Z","steps":["trace[1806154772] 'agreement among raft nodes before linearized reading' (duration: 286.908475ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:48:06.813891Z","caller":"traceutil/trace.go:171","msg":"trace[1015832788] transaction","detail":"{read_only:false; response_revision:9493; number_of_response:1; }","duration":"159.246558ms","start":"2026-03-11T19:48:06.654617Z","end":"2026-03-11T19:48:06.813864Z","steps":["trace[1015832788] 'process raft request' (duration: 159.070674ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:48:28.217581Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-11T19:48:28.222251Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-11T19:48:28.222396Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-11T19:49:32.495588Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7802} {"level":"info","ts":"2026-03-11T19:49:32.557973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7802,"took":"60.189104ms","hash":1126554528,"current-db-size-bytes":43319296,"current-db-size":"43 MB","current-db-size-in-use-bytes":25698304,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-11T19:49:32.558034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1126554528,"revision":7802,"compact-revision":5747} {"level":"info","ts":"2026-03-11T19:54:01.581921Z","caller":"traceutil/trace.go:171","msg":"trace[1195770213] linearizableReadLoop","detail":"{readStateIndex:11634; appliedIndex:11633; }","duration":"166.020794ms","start":"2026-03-11T19:54:01.415872Z","end":"2026-03-11T19:54:01.581892Z","steps":["trace[1195770213] 'read index received' (duration: 165.948223ms)","trace[1195770213] 'applied index is now lower than readState.Index' (duration: 71.541µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:54:01.582028Z","caller":"traceutil/trace.go:171","msg":"trace[632821319] transaction","detail":"{read_only:false; response_revision:11207; number_of_response:1; }","duration":"173.170929ms","start":"2026-03-11T19:54:01.408822Z","end":"2026-03-11T19:54:01.581993Z","steps":["trace[632821319] 'process raft request' (duration: 172.901392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:01.582145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.253622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5127"} {"level":"info","ts":"2026-03-11T19:54:01.582211Z","caller":"traceutil/trace.go:171","msg":"trace[1736922832] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11207; }","duration":"166.362694ms","start":"2026-03-11T19:54:01.415832Z","end":"2026-03-11T19:54:01.582195Z","steps":["trace[1736922832] 'agreement among raft nodes before linearized reading' (duration: 166.200579ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:54:01.583316Z","caller":"traceutil/trace.go:171","msg":"trace[758496415] transaction","detail":"{read_only:false; response_revision:11209; number_of_response:1; }","duration":"121.863127ms","start":"2026-03-11T19:54:01.46143Z","end":"2026-03-11T19:54:01.583293Z","steps":["trace[758496415] 'process raft request' (duration: 121.629112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:01.583277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.954113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-11T19:54:01.583528Z","caller":"traceutil/trace.go:171","msg":"trace[1822272779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11209; }","duration":"166.281602ms","start":"2026-03-11T19:54:01.417228Z","end":"2026-03-11T19:54:01.58351Z","steps":["trace[1822272779] 'agreement among raft nodes before linearized reading' (duration: 165.876591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:01.583662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.799686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-etcd\" ","response":"range_response_count:1 size:9422"} {"level":"warn","ts":"2026-03-11T19:54:01.583689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.994679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-11T19:54:01.583722Z","caller":"traceutil/trace.go:171","msg":"trace[1574491670] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-etcd; range_end:; response_count:1; response_revision:11209; }","duration":"140.912309ms","start":"2026-03-11T19:54:01.442797Z","end":"2026-03-11T19:54:01.58371Z","steps":["trace[1574491670] 'agreement among raft nodes before linearized reading' (duration: 140.735034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:01.583722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.136368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-rbd-overview\" ","response":"range_response_count:1 size:26148"} {"level":"info","ts":"2026-03-11T19:54:01.583654Z","caller":"traceutil/trace.go:171","msg":"trace[1648580271] transaction","detail":"{read_only:false; response_revision:11208; number_of_response:1; }","duration":"166.705722ms","start":"2026-03-11T19:54:01.416917Z","end":"2026-03-11T19:54:01.583622Z","steps":["trace[1648580271] 'process raft request' (duration: 166.026515ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:54:01.583769Z","caller":"traceutil/trace.go:171","msg":"trace[381841153] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-rbd-overview; range_end:; response_count:1; response_revision:11209; }","duration":"166.19824ms","start":"2026-03-11T19:54:01.417559Z","end":"2026-03-11T19:54:01.583757Z","steps":["trace[381841153] 'agreement among raft nodes before linearized reading' (duration: 166.009105ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:54:01.583813Z","caller":"traceutil/trace.go:171","msg":"trace[2042643628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11209; }","duration":"165.079481ms","start":"2026-03-11T19:54:01.418654Z","end":"2026-03-11T19:54:01.583733Z","steps":["trace[2042643628] 'agreement among raft nodes before linearized reading' (duration: 164.804573ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:54:01.964868Z","caller":"traceutil/trace.go:171","msg":"trace[1853492710] transaction","detail":"{read_only:false; response_revision:11212; number_of_response:1; }","duration":"250.159542ms","start":"2026-03-11T19:54:01.714689Z","end":"2026-03-11T19:54:01.964849Z","steps":["trace[1853492710] 'process raft request' (duration: 250.017938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.126167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000388489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-11T19:54:04.12627Z","caller":"traceutil/trace.go:171","msg":"trace[1790065317] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000512253s","start":"2026-03-11T19:54:02.125736Z","end":"2026-03-11T19:54:04.126248Z","steps":["trace[1790065317] 'agreement among raft nodes before linearized reading' (duration: 2.000385349s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.12633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:02.125718Z","time spent":"2.000600116s","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T19:54:04.651918Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.687182324s","expected-duration":"1s"} {"level":"info","ts":"2026-03-11T19:54:04.655301Z","caller":"traceutil/trace.go:171","msg":"trace[154924249] transaction","detail":"{read_only:false; response_revision:11213; number_of_response:1; }","duration":"2.939211724s","start":"2026-03-11T19:54:01.716061Z","end":"2026-03-11T19:54:04.655273Z","steps":["trace[154924249] 'process raft request' (duration: 2.936029232s)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:54:04.655364Z","caller":"traceutil/trace.go:171","msg":"trace[1646229449] transaction","detail":"{read_only:false; response_revision:11215; number_of_response:1; }","duration":"1.068419662s","start":"2026-03-11T19:54:03.58693Z","end":"2026-03-11T19:54:04.655349Z","steps":["trace[1646229449] 'process raft request' (duration: 1.06836345s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.655425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:01.716044Z","time spent":"2.939316897s","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T19:54:04.65543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:03.586906Z","time spent":"1.068488244s","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:54:04.65552Z","caller":"traceutil/trace.go:171","msg":"trace[1055728365] linearizableReadLoop","detail":"{readStateIndex:11640; appliedIndex:11638; }","duration":"2.897230834s","start":"2026-03-11T19:54:01.758281Z","end":"2026-03-11T19:54:04.655512Z","steps":["trace[1055728365] 'read index received' (duration: 206.484137ms)","trace[1055728365] 'applied index is now lower than readState.Index' (duration: 2.690744706s)"],"step_count":2} {"level":"info","ts":"2026-03-11T19:54:04.65558Z","caller":"traceutil/trace.go:171","msg":"trace[1141686452] transaction","detail":"{read_only:false; response_revision:11214; number_of_response:1; }","duration":"2.091384964s","start":"2026-03-11T19:54:02.564151Z","end":"2026-03-11T19:54:04.655536Z","steps":["trace[1141686452] 'process raft request' (duration: 2.091042565s)"],"step_count":1} {"level":"info","ts":"2026-03-11T19:54:04.655597Z","caller":"traceutil/trace.go:171","msg":"trace[672659638] transaction","detail":"{read_only:false; response_revision:11217; number_of_response:1; }","duration":"1.067641222s","start":"2026-03-11T19:54:03.587922Z","end":"2026-03-11T19:54:04.655563Z","steps":["trace[672659638] 'process raft request' (duration: 1.067521068s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.655671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.897392527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-grafana-config-dashboards\" ","response":"range_response_count:1 size:1215"} {"level":"info","ts":"2026-03-11T19:54:04.655698Z","caller":"traceutil/trace.go:171","msg":"trace[541880610] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-grafana-config-dashboards; range_end:; response_count:1; response_revision:11217; }","duration":"2.897445929s","start":"2026-03-11T19:54:01.758244Z","end":"2026-03-11T19:54:04.65569Z","steps":["trace[541880610] 'agreement among raft nodes before linearized reading' (duration: 2.897325036s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.655722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:01.758227Z","time spent":"2.897491201s","remote":"127.0.0.1:43516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1238,"request content":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-grafana-config-dashboards\" "} {"level":"warn","ts":"2026-03-11T19:54:04.655751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:02.564129Z","time spent":"2.091533367s","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T19:54:04.655794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:03.587915Z","time spent":"1.067770715s","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T19:54:04.655863Z","caller":"traceutil/trace.go:171","msg":"trace[1322906048] transaction","detail":"{read_only:false; response_revision:11216; number_of_response:1; }","duration":"1.06796014s","start":"2026-03-11T19:54:03.587894Z","end":"2026-03-11T19:54:04.655854Z","steps":["trace[1322906048] 'process raft request' (duration: 1.067430156s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.655875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.894922903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:2424"} {"level":"info","ts":"2026-03-11T19:54:04.655895Z","caller":"traceutil/trace.go:171","msg":"trace[1320633936] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:11217; }","duration":"2.894953715s","start":"2026-03-11T19:54:01.760935Z","end":"2026-03-11T19:54:04.655889Z","steps":["trace[1320633936] 'agreement among raft nodes before linearized reading' (duration: 2.894894783s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.655907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:03.58787Z","time spent":"1.068014361s","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T19:54:04.655914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:01.760929Z","time spent":"2.894981185s","remote":"127.0.0.1:43516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":2447,"request content":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-grafana\" "} {"level":"warn","ts":"2026-03-11T19:54:04.65607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064373087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-11T19:54:04.656092Z","caller":"traceutil/trace.go:171","msg":"trace[218638747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11217; }","duration":"1.064466309s","start":"2026-03-11T19:54:03.59162Z","end":"2026-03-11T19:54:04.656086Z","steps":["trace[218638747] 'agreement among raft nodes before linearized reading' (duration: 1.064405028s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.656084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.436522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T19:54:04.656111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:03.591606Z","time spent":"1.064501301s","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-11T19:54:04.656149Z","caller":"traceutil/trace.go:171","msg":"trace[1238876635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11217; }","duration":"501.522774ms","start":"2026-03-11T19:54:04.154602Z","end":"2026-03-11T19:54:04.656125Z","steps":["trace[1238876635] 'agreement among raft nodes before linearized reading' (duration: 501.399921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.656237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064291505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-11T19:54:04.656183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:04.15459Z","time spent":"501.585436ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T19:54:04.656268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067529939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5127"} {"level":"info","ts":"2026-03-11T19:54:04.65629Z","caller":"traceutil/trace.go:171","msg":"trace[1593179952] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11217; }","duration":"1.06757011s","start":"2026-03-11T19:54:03.588714Z","end":"2026-03-11T19:54:04.656284Z","steps":["trace[1593179952] 'agreement among raft nodes before linearized reading' (duration: 1.067523889s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.656312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:03.588709Z","time spent":"1.067598821s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5150,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"warn","ts":"2026-03-11T19:54:04.65644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.730792127s","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-03-11T19:54:04.656463Z","caller":"traceutil/trace.go:171","msg":"trace[1438689635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11217; }","duration":"1.730848009s","start":"2026-03-11T19:54:02.92561Z","end":"2026-03-11T19:54:04.656458Z","steps":["trace[1438689635] 'agreement among raft nodes before linearized reading' (duration: 1.730786587s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.656485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:02.925586Z","time spent":"1.73089422s","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-11T19:54:04.656466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.576205ms","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-03-11T19:54:04.656538Z","caller":"traceutil/trace.go:171","msg":"trace[920125496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11217; }","duration":"687.689558ms","start":"2026-03-11T19:54:03.968832Z","end":"2026-03-11T19:54:04.656521Z","steps":["trace[920125496] 'agreement among raft nodes before linearized reading' (duration: 687.482463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.656568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:03.968812Z","time spent":"687.749499ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-11T19:54:04.65627Z","caller":"traceutil/trace.go:171","msg":"trace[1208084942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11217; }","duration":"1.064538303s","start":"2026-03-11T19:54:03.591716Z","end":"2026-03-11T19:54:04.656255Z","steps":["trace[1208084942] 'agreement among raft nodes before linearized reading' (duration: 1.064275385s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.656624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.587886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T19:54:04.65665Z","caller":"traceutil/trace.go:171","msg":"trace[1856563401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11217; }","duration":"529.615277ms","start":"2026-03-11T19:54:04.127027Z","end":"2026-03-11T19:54:04.656643Z","steps":["trace[1856563401] 'agreement among raft nodes before linearized reading' (duration: 529.256198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.656655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:03.591697Z","time spent":"1.064945642s","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-11T19:54:04.65667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:04.126961Z","time spent":"529.70465ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T19:54:04.656727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.728326824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-11T19:54:04.656792Z","caller":"traceutil/trace.go:171","msg":"trace[606107575] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:11217; }","duration":"2.728447156s","start":"2026-03-11T19:54:01.928329Z","end":"2026-03-11T19:54:04.656777Z","steps":["trace[606107575] 'agreement among raft nodes before linearized reading' (duration: 2.728223811s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.656826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:01.928307Z","time spent":"2.728512498s","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-03-11T19:54:04.657847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.068154375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8wmws\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-11T19:54:04.657921Z","caller":"traceutil/trace.go:171","msg":"trace[1385622274] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8wmws; range_end:; response_count:1; response_revision:11217; }","duration":"2.068291898s","start":"2026-03-11T19:54:02.589611Z","end":"2026-03-11T19:54:04.657903Z","steps":["trace[1385622274] 'agreement among raft nodes before linearized reading' (duration: 2.066871831s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T19:54:04.657971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:54:02.589592Z","time spent":"2.06836639s","remote":"127.0.0.1:47540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-8wmws\" "} {"level":"info","ts":"2026-03-11T19:54:32.501986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9961} {"level":"info","ts":"2026-03-11T19:54:32.579909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9961,"took":"75.498545ms","hash":3213382413,"current-db-size-bytes":43319296,"current-db-size":"43 MB","current-db-size-in-use-bytes":16637952,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-11T19:54:32.579965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3213382413,"revision":9961,"compact-revision":7802}