{"level":"warn","ts":"2026-05-27T09:46:24.32748Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T09:46:24.327589Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-27T09:46:24.32766Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T09:46:24.327673Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-05-27T09:46:24.327691Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T09:46:24.328464Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-05-27T09:46:24.328535Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-27T09:46:24.332613Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.871845ms"} {"level":"info","ts":"2026-05-27T09:46:24.342455Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-05-27T09:46:24.342565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T09:46:24.342593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-05-27T09:46:24.342609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T09:46:24.342621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-05-27T09:46:24.342665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-05-27T09:46:24.348756Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T09:46:24.351877Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T09:46:24.352897Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T09:46:24.355627Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T09:46:24.356404Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T09:46:24.35643Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T09:46:24.356522Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T09:46:24.356554Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T09:46:24.359694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-05-27T09:46:24.366941Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T09:46:24.367087Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-05-27T09:46:24.367122Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-05-27T09:46:24.367222Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T09:46:24.367259Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T09:46:24.371336Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-05-27T09:46:25.043305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T09:46:25.043484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T09:46:25.043554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-05-27T09:46:25.043602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-05-27T09:46:25.043628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-05-27T09:46:25.043674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-05-27T09:46:25.043712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-05-27T09:46:25.044576Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T09:46:25.044811Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:46:25.044935Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:46:25.044936Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:46:25.045229Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T09:46:25.045292Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T09:46:25.04634Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:46:25.046569Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:46:25.046637Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:46:25.047751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-05-27T09:46:25.048277Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T09:47:01.402875Z","caller":"traceutil/trace.go:171","msg":"trace[1474027347] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"118.073025ms","start":"2026-05-27T09:47:01.284785Z","end":"2026-05-27T09:47:01.402858Z","steps":["trace[1474027347] 'process raft request' (duration: 117.964584ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:01.409866Z","caller":"traceutil/trace.go:171","msg":"trace[735202032] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"118.747591ms","start":"2026-05-27T09:47:01.291101Z","end":"2026-05-27T09:47:01.409848Z","steps":["trace[735202032] 'process raft request' (duration: 118.077716ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:01.410142Z","caller":"traceutil/trace.go:171","msg":"trace[2071124199] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"106.733077ms","start":"2026-05-27T09:47:01.303382Z","end":"2026-05-27T09:47:01.410115Z","steps":["trace[2071124199] 'process raft request' (duration: 106.033711ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:01.410205Z","caller":"traceutil/trace.go:171","msg":"trace[1056107536] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"100.044438ms","start":"2026-05-27T09:47:01.310149Z","end":"2026-05-27T09:47:01.410194Z","steps":["trace[1056107536] 'process raft request' (duration: 99.298202ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:01.410288Z","caller":"traceutil/trace.go:171","msg":"trace[525515093] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"108.864205ms","start":"2026-05-27T09:47:01.301416Z","end":"2026-05-27T09:47:01.410281Z","steps":["trace[525515093] 'process raft request' (duration: 107.876967ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:01.410338Z","caller":"traceutil/trace.go:171","msg":"trace[1715262628] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"107.792996ms","start":"2026-05-27T09:47:01.302532Z","end":"2026-05-27T09:47:01.410325Z","steps":["trace[1715262628] 'process raft request' (duration: 106.793987ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:01.410356Z","caller":"traceutil/trace.go:171","msg":"trace[607206841] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"119.201955ms","start":"2026-05-27T09:47:01.291146Z","end":"2026-05-27T09:47:01.410348Z","steps":["trace[607206841] 'process raft request' (duration: 118.102435ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:01.410444Z","caller":"traceutil/trace.go:171","msg":"trace[88017984] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"107.093ms","start":"2026-05-27T09:47:01.303342Z","end":"2026-05-27T09:47:01.410435Z","steps":["trace[88017984] 'process raft request' (duration: 106.04334ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:08.184091Z","caller":"traceutil/trace.go:171","msg":"trace[1257009663] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"217.866602ms","start":"2026-05-27T09:47:07.96621Z","end":"2026-05-27T09:47:08.184077Z","steps":["trace[1257009663] 'process raft request' (duration: 217.326067ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:08.184073Z","caller":"traceutil/trace.go:171","msg":"trace[434973104] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"139.401612ms","start":"2026-05-27T09:47:08.044648Z","end":"2026-05-27T09:47:08.184049Z","steps":["trace[434973104] 'read index received' (duration: 138.631695ms)","trace[434973104] 'applied index is now lower than readState.Index' (duration: 769.017µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:08.184342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.687634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T09:47:08.184457Z","caller":"traceutil/trace.go:171","msg":"trace[955152963] range","detail":"{range_begin:/registry/masterleases/199.19.213.200; range_end:; response_count:1; response_revision:595; }","duration":"139.858785ms","start":"2026-05-27T09:47:08.044584Z","end":"2026-05-27T09:47:08.184443Z","steps":["trace[955152963] 'agreement among raft nodes before linearized reading' (duration: 139.694694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:08.807153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033150031015369516,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T09:47:08.811036Z","caller":"traceutil/trace.go:171","msg":"trace[823283714] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"615.572669ms","start":"2026-05-27T09:47:08.195434Z","end":"2026-05-27T09:47:08.811007Z","steps":["trace[823283714] 'process raft request' (duration: 615.392867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:08.811808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:08.195418Z","time spent":"615.68649ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:09.453715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.455919ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:09.45387Z","caller":"traceutil/trace.go:171","msg":"trace[1875745730] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:596; }","duration":"272.629131ms","start":"2026-05-27T09:47:09.181219Z","end":"2026-05-27T09:47:09.453848Z","steps":["trace[1875745730] 'range keys from in-memory index tree' (duration: 272.439419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.454013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.671434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:47:09.45406Z","caller":"traceutil/trace.go:171","msg":"trace[1802387402] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"1.147469231s","start":"2026-05-27T09:47:08.306583Z","end":"2026-05-27T09:47:09.454053Z","steps":["trace[1802387402] 'read index received' (duration: 504.888349ms)","trace[1802387402] 'applied index is now lower than readState.Index' (duration: 642.580072ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:09.454267Z","caller":"traceutil/trace.go:171","msg":"trace[729563159] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.208589315s","start":"2026-05-27T09:47:08.24566Z","end":"2026-05-27T09:47:09.45425Z","steps":["trace[729563159] 'process raft request' (duration: 616.551488ms)","trace[729563159] 'compare' (duration: 591.288781ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:09.454307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:08.245634Z","time spent":"1.208657605s","remote":"127.0.0.1:48832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:09.454347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085894144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T09:47:09.45443Z","caller":"traceutil/trace.go:171","msg":"trace[1679215786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"1.086024535s","start":"2026-05-27T09:47:08.368379Z","end":"2026-05-27T09:47:09.454404Z","steps":["trace[1679215786] 'agreement among raft nodes before linearized reading' (duration: 1.085772503s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.45448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:08.36836Z","time spent":"1.086110346s","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T09:47:09.454497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.007251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-27T09:47:09.454629Z","caller":"traceutil/trace.go:171","msg":"trace[1457824849] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:597; }","duration":"640.131312ms","start":"2026-05-27T09:47:08.814453Z","end":"2026-05-27T09:47:09.454584Z","steps":["trace[1457824849] 'agreement among raft nodes before linearized reading' (duration: 639.839359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.454727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:08.814407Z","time spent":"640.266713ms","remote":"127.0.0.1:47812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-27T09:47:09.455068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.859311ms","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-05-27T09:47:09.454758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.438653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-27T09:47:09.455132Z","caller":"traceutil/trace.go:171","msg":"trace[849799662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"863.946841ms","start":"2026-05-27T09:47:08.591164Z","end":"2026-05-27T09:47:09.45511Z","steps":["trace[849799662] 'agreement among raft nodes before linearized reading' (duration: 863.222515ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:09.45517Z","caller":"traceutil/trace.go:171","msg":"trace[1894474941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"454.891607ms","start":"2026-05-27T09:47:09.000264Z","end":"2026-05-27T09:47:09.455156Z","steps":["trace[1894474941] 'agreement among raft nodes before linearized reading' (duration: 454.13487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.455178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:08.591144Z","time spent":"864.022702ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T09:47:09.455225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.000243Z","time spent":"454.967597ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T09:47:09.454875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.390575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-27T09:47:09.455327Z","caller":"traceutil/trace.go:171","msg":"trace[906313839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"440.864219ms","start":"2026-05-27T09:47:09.014453Z","end":"2026-05-27T09:47:09.455318Z","steps":["trace[906313839] 'agreement among raft nodes before linearized reading' (duration: 439.85185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.455356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.014443Z","time spent":"440.905109ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T09:47:09.454584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147991996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T09:47:09.454967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.215534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9sdlm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-27T09:47:09.455443Z","caller":"traceutil/trace.go:171","msg":"trace[531494878] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9sdlm; range_end:; response_count:1; response_revision:597; }","duration":"758.730548ms","start":"2026-05-27T09:47:08.696699Z","end":"2026-05-27T09:47:09.455429Z","steps":["trace[531494878] 'agreement among raft nodes before linearized reading' (duration: 757.649809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.4555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:08.696674Z","time spent":"758.811048ms","remote":"127.0.0.1:48798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-9sdlm\" "} {"level":"info","ts":"2026-05-27T09:47:09.456003Z","caller":"traceutil/trace.go:171","msg":"trace[1933526112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.148852553s","start":"2026-05-27T09:47:08.306534Z","end":"2026-05-27T09:47:09.455387Z","steps":["trace[1933526112] 'agreement among raft nodes before linearized reading' (duration: 1.147569993s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.456068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:08.30651Z","time spent":"1.149542489s","remote":"127.0.0.1:47684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T09:47:09.815729Z","caller":"traceutil/trace.go:171","msg":"trace[622705978] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"356.096338ms","start":"2026-05-27T09:47:09.45961Z","end":"2026-05-27T09:47:09.815706Z","steps":["trace[622705978] 'read index received' (duration: 271.47185ms)","trace[622705978] 'applied index is now lower than readState.Index' (duration: 84.623588ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:09.815805Z","caller":"traceutil/trace.go:171","msg":"trace[847422272] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"356.814285ms","start":"2026-05-27T09:47:09.458959Z","end":"2026-05-27T09:47:09.815773Z","steps":["trace[847422272] 'process raft request' (duration: 272.108306ms)","trace[847422272] 'compare' (duration: 84.505387ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:09.815872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.241569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-27T09:47:09.815947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.458942Z","time spent":"356.928656ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:09.815967Z","caller":"traceutil/trace.go:171","msg":"trace[1604029499] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:598; }","duration":"356.361391ms","start":"2026-05-27T09:47:09.459587Z","end":"2026-05-27T09:47:09.815948Z","steps":["trace[1604029499] 'agreement among raft nodes before linearized reading' (duration: 356.19323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.81602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.459576Z","time spent":"356.430512ms","remote":"127.0.0.1:48814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-05-27T09:47:09.817549Z","caller":"traceutil/trace.go:171","msg":"trace[1646078078] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"357.758403ms","start":"2026-05-27T09:47:09.459771Z","end":"2026-05-27T09:47:09.81753Z","steps":["trace[1646078078] 'process raft request' (duration: 357.610851ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:09.817592Z","caller":"traceutil/trace.go:171","msg":"trace[47153684] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"357.635462ms","start":"2026-05-27T09:47:09.459942Z","end":"2026-05-27T09:47:09.817578Z","steps":["trace[47153684] 'process raft request' (duration: 357.519891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.81765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.459933Z","time spent":"357.686602ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:09.817675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.459762Z","time spent":"357.840213ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:09.817789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.500751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-27T09:47:09.81784Z","caller":"traceutil/trace.go:171","msg":"trace[1788332036] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:602; }","duration":"357.572691ms","start":"2026-05-27T09:47:09.460256Z","end":"2026-05-27T09:47:09.817829Z","steps":["trace[1788332036] 'agreement among raft nodes before linearized reading' (duration: 357.348939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.818159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.460248Z","time spent":"357.898364ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-05-27T09:47:09.817941Z","caller":"traceutil/trace.go:171","msg":"trace[1548116606] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"348.318933ms","start":"2026-05-27T09:47:09.469609Z","end":"2026-05-27T09:47:09.817928Z","steps":["trace[1548116606] 'process raft request' (duration: 347.92921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.818326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.4696Z","time spent":"348.654506ms","remote":"127.0.0.1:48798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:09.817977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.72299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:09.818578Z","caller":"traceutil/trace.go:171","msg":"trace[1270141017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"353.317535ms","start":"2026-05-27T09:47:09.465245Z","end":"2026-05-27T09:47:09.818563Z","steps":["trace[1270141017] 'agreement among raft nodes before linearized reading' (duration: 352.70583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.818628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.465216Z","time spent":"353.399436ms","remote":"127.0.0.1:47668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T09:47:09.817991Z","caller":"traceutil/trace.go:171","msg":"trace[1396226974] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"356.486582ms","start":"2026-05-27T09:47:09.461476Z","end":"2026-05-27T09:47:09.817963Z","steps":["trace[1396226974] 'process raft request' (duration: 356.019118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:09.818836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:09.461448Z","time spent":"357.355519ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:26.045082Z","caller":"traceutil/trace.go:171","msg":"trace[407535616] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"150.985126ms","start":"2026-05-27T09:47:25.894075Z","end":"2026-05-27T09:47:26.04506Z","steps":["trace[407535616] 'read index received' (duration: 150.752168ms)","trace[407535616] 'applied index is now lower than readState.Index' (duration: 231.997µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:26.045126Z","caller":"traceutil/trace.go:171","msg":"trace[625531458] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"151.768523ms","start":"2026-05-27T09:47:25.893327Z","end":"2026-05-27T09:47:26.045095Z","steps":["trace[625531458] 'process raft request' (duration: 151.597527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:26.04533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.240535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T09:47:26.045374Z","caller":"traceutil/trace.go:171","msg":"trace[617653584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"151.323167ms","start":"2026-05-27T09:47:25.89404Z","end":"2026-05-27T09:47:26.045363Z","steps":["trace[617653584] 'agreement among raft nodes before linearized reading' (duration: 151.166681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:26.045387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.465818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T09:47:26.045718Z","caller":"traceutil/trace.go:171","msg":"trace[1438133972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"150.84381ms","start":"2026-05-27T09:47:25.894858Z","end":"2026-05-27T09:47:26.045702Z","steps":["trace[1438133972] 'agreement among raft nodes before linearized reading' (duration: 150.413386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:26.045427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.913381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-27T09:47:26.045856Z","caller":"traceutil/trace.go:171","msg":"trace[1445146916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"148.373697ms","start":"2026-05-27T09:47:25.897468Z","end":"2026-05-27T09:47:26.045841Z","steps":["trace[1445146916] 'agreement among raft nodes before linearized reading' (duration: 147.806068ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:26.32058Z","caller":"traceutil/trace.go:171","msg":"trace[184420415] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"271.838826ms","start":"2026-05-27T09:47:26.048709Z","end":"2026-05-27T09:47:26.320548Z","steps":["trace[184420415] 'process raft request' (duration: 210.864372ms)","trace[184420415] 'compare' (duration: 60.822699ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:26.321449Z","caller":"traceutil/trace.go:171","msg":"trace[1341914880] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"272.089405ms","start":"2026-05-27T09:47:26.049345Z","end":"2026-05-27T09:47:26.321435Z","steps":["trace[1341914880] 'process raft request' (duration: 271.981021ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:26.321787Z","caller":"traceutil/trace.go:171","msg":"trace[99692089] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"272.203879ms","start":"2026-05-27T09:47:26.04953Z","end":"2026-05-27T09:47:26.321734Z","steps":["trace[99692089] 'process raft request' (duration: 271.867577ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:18.251172Z","caller":"traceutil/trace.go:171","msg":"trace[1851068520] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"101.776798ms","start":"2026-05-27T09:48:18.149359Z","end":"2026-05-27T09:48:18.251136Z","steps":["trace[1851068520] 'process raft request' (duration: 99.134162ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:41.6023Z","caller":"traceutil/trace.go:171","msg":"trace[625750843] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1893; }","duration":"295.155646ms","start":"2026-05-27T09:48:41.307122Z","end":"2026-05-27T09:48:41.602278Z","steps":["trace[625750843] 'read index received' (duration: 295.061015ms)","trace[625750843] 'applied index is now lower than readState.Index' (duration: 93.241µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:48:41.602515Z","caller":"traceutil/trace.go:171","msg":"trace[858756866] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"410.423863ms","start":"2026-05-27T09:48:41.192037Z","end":"2026-05-27T09:48:41.602461Z","steps":["trace[858756866] 'process raft request' (duration: 410.053386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:41.602608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.458102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:48:41.602695Z","caller":"traceutil/trace.go:171","msg":"trace[161941948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1825; }","duration":"295.590994ms","start":"2026-05-27T09:48:41.307087Z","end":"2026-05-27T09:48:41.602678Z","steps":["trace[161941948] 'agreement among raft nodes before linearized reading' (duration: 295.414221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:41.602733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:48:41.192024Z","time spent":"410.612227ms","remote":"127.0.0.1:48798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:48:41.617742Z","caller":"traceutil/trace.go:171","msg":"trace[1948572510] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"306.755113ms","start":"2026-05-27T09:48:41.310971Z","end":"2026-05-27T09:48:41.617727Z","steps":["trace[1948572510] 'process raft request' (duration: 306.551199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:41.617765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.865412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4697a98e-1762-48ee-9e9c-f2bf49946400\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-27T09:48:41.617857Z","caller":"traceutil/trace.go:171","msg":"trace[806212224] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4697a98e-1762-48ee-9e9c-f2bf49946400; range_end:; response_count:1; response_revision:1826; }","duration":"196.992355ms","start":"2026-05-27T09:48:41.420842Z","end":"2026-05-27T09:48:41.617835Z","steps":["trace[806212224] 'agreement among raft nodes before linearized reading' (duration: 196.833822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:41.617928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:48:41.310951Z","time spent":"306.839835ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:48:41.618348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.463333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-27T09:48:41.618395Z","caller":"traceutil/trace.go:171","msg":"trace[698776977] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1826; }","duration":"100.549444ms","start":"2026-05-27T09:48:41.517834Z","end":"2026-05-27T09:48:41.618383Z","steps":["trace[698776977] 'agreement among raft nodes before linearized reading' (duration: 100.453222ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:41.813261Z","caller":"traceutil/trace.go:171","msg":"trace[1231829612] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"130.503689ms","start":"2026-05-27T09:48:41.682713Z","end":"2026-05-27T09:48:41.813216Z","steps":["trace[1231829612] 'process raft request' (duration: 31.474782ms)","trace[1231829612] 'compare' (duration: 98.814973ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:48:42.142175Z","caller":"traceutil/trace.go:171","msg":"trace[500610813] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"211.64021ms","start":"2026-05-27T09:48:41.930464Z","end":"2026-05-27T09:48:42.142104Z","steps":["trace[500610813] 'process raft request' (duration: 120.86573ms)","trace[500610813] 'compare' (duration: 90.19975ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:48:49.75801Z","caller":"traceutil/trace.go:171","msg":"trace[1713248963] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"109.662145ms","start":"2026-05-27T09:48:49.648309Z","end":"2026-05-27T09:48:49.757971Z","steps":["trace[1713248963] 'process raft request' (duration: 109.466772ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:15.105817Z","caller":"traceutil/trace.go:171","msg":"trace[90379183] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2138; }","duration":"153.046129ms","start":"2026-05-27T09:49:14.952751Z","end":"2026-05-27T09:49:15.105797Z","steps":["trace[90379183] 'read index received' (duration: 152.864916ms)","trace[90379183] 'applied index is now lower than readState.Index' (duration: 180.613µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:49:15.106048Z","caller":"traceutil/trace.go:171","msg":"trace[2133903180] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"158.41742ms","start":"2026-05-27T09:49:14.947614Z","end":"2026-05-27T09:49:15.106031Z","steps":["trace[2133903180] 'process raft request' (duration: 158.065865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:49:15.106243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.142161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T09:49:15.106269Z","caller":"traceutil/trace.go:171","msg":"trace[227436532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2063; }","duration":"145.212132ms","start":"2026-05-27T09:49:14.96105Z","end":"2026-05-27T09:49:15.106262Z","steps":["trace[227436532] 'agreement among raft nodes before linearized reading' (duration: 145.12855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:49:15.106306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.549106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-27T09:49:15.106384Z","caller":"traceutil/trace.go:171","msg":"trace[391715768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2063; }","duration":"153.660288ms","start":"2026-05-27T09:49:14.952714Z","end":"2026-05-27T09:49:15.106375Z","steps":["trace[391715768] 'agreement among raft nodes before linearized reading' (duration: 153.296433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:49:15.106523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.14463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T09:49:15.106605Z","caller":"traceutil/trace.go:171","msg":"trace[1225642954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2063; }","duration":"153.261942ms","start":"2026-05-27T09:49:14.953323Z","end":"2026-05-27T09:49:15.106585Z","steps":["trace[1225642954] 'agreement among raft nodes before linearized reading' (duration: 152.848336ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:15.865988Z","caller":"traceutil/trace.go:171","msg":"trace[81808483] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"132.491939ms","start":"2026-05-27T09:49:15.733473Z","end":"2026-05-27T09:49:15.865965Z","steps":["trace[81808483] 'process raft request' (duration: 132.306736ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:16.092987Z","caller":"traceutil/trace.go:171","msg":"trace[1208284612] linearizableReadLoop","detail":"{readStateIndex:2146; appliedIndex:2145; }","duration":"205.905719ms","start":"2026-05-27T09:49:15.887056Z","end":"2026-05-27T09:49:16.092962Z","steps":["trace[1208284612] 'read index received' (duration: 106.970887ms)","trace[1208284612] 'applied index is now lower than readState.Index' (duration: 98.933462ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:49:16.09324Z","caller":"traceutil/trace.go:171","msg":"trace[1160548097] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"238.601667ms","start":"2026-05-27T09:49:15.854624Z","end":"2026-05-27T09:49:16.093226Z","steps":["trace[1160548097] 'process raft request' (duration: 139.473442ms)","trace[1160548097] 'compare' (duration: 98.727999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:49:16.093458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.347208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-27T09:49:16.093534Z","caller":"traceutil/trace.go:171","msg":"trace[1991640796] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2070; }","duration":"164.469ms","start":"2026-05-27T09:49:15.929044Z","end":"2026-05-27T09:49:16.093513Z","steps":["trace[1991640796] 'agreement among raft nodes before linearized reading' (duration: 164.227667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:49:16.093756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.69023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-27T09:49:16.093806Z","caller":"traceutil/trace.go:171","msg":"trace[777452588] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2070; }","duration":"206.788992ms","start":"2026-05-27T09:49:15.887002Z","end":"2026-05-27T09:49:16.093791Z","steps":["trace[777452588] 'agreement among raft nodes before linearized reading' (duration: 206.579959ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:16.279732Z","caller":"traceutil/trace.go:171","msg":"trace[2024120085] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"181.55401ms","start":"2026-05-27T09:49:16.09815Z","end":"2026-05-27T09:49:16.279704Z","steps":["trace[2024120085] 'process raft request' (duration: 169.777054ms)","trace[2024120085] 'compare' (duration: 11.656394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:49:16.626873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.497085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:49:16.627347Z","caller":"traceutil/trace.go:171","msg":"trace[1354133166] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"154.361156ms","start":"2026-05-27T09:49:16.472968Z","end":"2026-05-27T09:49:16.627329Z","steps":["trace[1354133166] 'process raft request' (duration: 18.332033ms)","trace[1354133166] 'compare' (duration: 135.320802ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:50:21.775138Z","caller":"traceutil/trace.go:171","msg":"trace[1995862319] transaction","detail":"{read_only:false; response_revision:2528; number_of_response:1; }","duration":"301.198538ms","start":"2026-05-27T09:50:21.47376Z","end":"2026-05-27T09:50:21.774958Z","steps":["trace[1995862319] 'process raft request' (duration: 301.052096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:50:21.775281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:50:21.473746Z","time spent":"301.466021ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:50:21.776561Z","caller":"traceutil/trace.go:171","msg":"trace[1534860891] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"301.789146ms","start":"2026-05-27T09:50:21.474755Z","end":"2026-05-27T09:50:21.776544Z","steps":["trace[1534860891] 'process raft request' (duration: 301.681874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:50:21.776663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:50:21.474744Z","time spent":"301.876307ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:50:53.723703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.366654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T09:50:53.723805Z","caller":"traceutil/trace.go:171","msg":"trace[1631613968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2703; }","duration":"117.517586ms","start":"2026-05-27T09:50:53.60627Z","end":"2026-05-27T09:50:53.723788Z","steps":["trace[1631613968] 'range keys from in-memory index tree' (duration: 117.205912ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:53:52.081908Z","caller":"traceutil/trace.go:171","msg":"trace[1343257828] linearizableReadLoop","detail":"{readStateIndex:3932; appliedIndex:3931; }","duration":"159.870816ms","start":"2026-05-27T09:53:51.922018Z","end":"2026-05-27T09:53:52.081889Z","steps":["trace[1343257828] 'read index received' (duration: 84.072296ms)","trace[1343257828] 'applied index is now lower than readState.Index' (duration: 75.7976ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:53:52.081988Z","caller":"traceutil/trace.go:171","msg":"trace[1915614937] transaction","detail":"{read_only:false; response_revision:3790; number_of_response:1; }","duration":"161.263753ms","start":"2026-05-27T09:53:51.920687Z","end":"2026-05-27T09:53:52.08195Z","steps":["trace[1915614937] 'process raft request' (duration: 85.387781ms)","trace[1915614937] 'compare' (duration: 75.674376ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:53:52.082161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.133793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-27T09:53:52.082225Z","caller":"traceutil/trace.go:171","msg":"trace[1554940527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3790; }","duration":"160.247116ms","start":"2026-05-27T09:53:51.921964Z","end":"2026-05-27T09:53:52.082211Z","steps":["trace[1554940527] 'agreement among raft nodes before linearized reading' (duration: 160.03077ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:53:52.084677Z","caller":"traceutil/trace.go:171","msg":"trace[1897627270] transaction","detail":"{read_only:false; response_revision:3791; number_of_response:1; }","duration":"150.521507ms","start":"2026-05-27T09:53:51.934126Z","end":"2026-05-27T09:53:52.084648Z","steps":["trace[1897627270] 'process raft request' (duration: 150.229099ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:53:52.085342Z","caller":"traceutil/trace.go:171","msg":"trace[377158066] transaction","detail":"{read_only:false; response_revision:3792; number_of_response:1; }","duration":"135.772244ms","start":"2026-05-27T09:53:51.949558Z","end":"2026-05-27T09:53:52.08533Z","steps":["trace[377158066] 'process raft request' (duration: 135.082966ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:29.243204Z","caller":"traceutil/trace.go:171","msg":"trace[1700526949] transaction","detail":"{read_only:false; response_revision:4027; number_of_response:1; }","duration":"137.580114ms","start":"2026-05-27T09:54:29.105602Z","end":"2026-05-27T09:54:29.243182Z","steps":["trace[1700526949] 'process raft request' (duration: 137.455621ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:29.243286Z","caller":"traceutil/trace.go:171","msg":"trace[700696309] linearizableReadLoop","detail":"{readStateIndex:4188; appliedIndex:4188; }","duration":"130.921407ms","start":"2026-05-27T09:54:29.112332Z","end":"2026-05-27T09:54:29.243254Z","steps":["trace[700696309] 'read index received' (duration: 130.895366ms)","trace[700696309] 'applied index is now lower than readState.Index' (duration: 22.29µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:54:29.243421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.05784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:54:29.243488Z","caller":"traceutil/trace.go:171","msg":"trace[1492116333] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4027; }","duration":"131.158112ms","start":"2026-05-27T09:54:29.112314Z","end":"2026-05-27T09:54:29.243473Z","steps":["trace[1492116333] 'agreement among raft nodes before linearized reading' (duration: 131.026339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:29.24762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.508266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-27T09:54:29.247658Z","caller":"traceutil/trace.go:171","msg":"trace[925427970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4028; }","duration":"127.572987ms","start":"2026-05-27T09:54:29.120077Z","end":"2026-05-27T09:54:29.24765Z","steps":["trace[925427970] 'agreement among raft nodes before linearized reading' (duration: 127.452175ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:35.399251Z","caller":"traceutil/trace.go:171","msg":"trace[1530490089] transaction","detail":"{read_only:false; response_revision:4396; number_of_response:1; }","duration":"103.333074ms","start":"2026-05-27T09:54:35.295902Z","end":"2026-05-27T09:54:35.399235Z","steps":["trace[1530490089] 'process raft request' (duration: 103.14619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:39.291923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.738547ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:54:39.292041Z","caller":"traceutil/trace.go:171","msg":"trace[19909611] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4513; }","duration":"109.864189ms","start":"2026-05-27T09:54:39.182161Z","end":"2026-05-27T09:54:39.292025Z","steps":["trace[19909611] 'range keys from in-memory index tree' (duration: 109.723616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:39.292107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.111882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T09:54:39.292596Z","caller":"traceutil/trace.go:171","msg":"trace[968126521] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4514; }","duration":"201.092509ms","start":"2026-05-27T09:54:39.09149Z","end":"2026-05-27T09:54:39.292582Z","steps":["trace[968126521] 'process raft request' (duration: 91.406894ms)","trace[968126521] 'compare' (duration: 109.02162ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:54:42.424884Z","caller":"traceutil/trace.go:171","msg":"trace[783445099] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"109.871879ms","start":"2026-05-27T09:54:42.314987Z","end":"2026-05-27T09:54:42.424859Z","steps":["trace[783445099] 'process raft request' (duration: 109.631063ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:42.55925Z","caller":"traceutil/trace.go:171","msg":"trace[627049108] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"130.158302ms","start":"2026-05-27T09:54:42.429065Z","end":"2026-05-27T09:54:42.559223Z","steps":["trace[627049108] 'process raft request' (duration: 69.496477ms)","trace[627049108] 'compare' (duration: 60.41497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:54:50.074249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033150031015379061,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T09:54:50.251224Z","caller":"traceutil/trace.go:171","msg":"trace[1127355629] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"680.882756ms","start":"2026-05-27T09:54:49.570295Z","end":"2026-05-27T09:54:50.251177Z","steps":["trace[1127355629] 'process raft request' (duration: 680.60887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.251716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:49.570271Z","time spent":"681.189733ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:54:50.359507Z","caller":"traceutil/trace.go:171","msg":"trace[991627456] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"658.299453ms","start":"2026-05-27T09:54:49.701177Z","end":"2026-05-27T09:54:50.359476Z","steps":["trace[991627456] 'process raft request' (duration: 601.84746ms)","trace[991627456] 'compare' (duration: 56.232138ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:54:50.359796Z","caller":"traceutil/trace.go:171","msg":"trace[2088777720] linearizableReadLoop","detail":"{readStateIndex:4890; appliedIndex:4887; }","duration":"785.566077ms","start":"2026-05-27T09:54:49.574178Z","end":"2026-05-27T09:54:50.359744Z","steps":["trace[2088777720] 'read index received' (duration: 676.759543ms)","trace[2088777720] 'applied index is now lower than readState.Index' (duration: 108.803744ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:54:50.359954Z","caller":"traceutil/trace.go:171","msg":"trace[1898714720] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"469.901817ms","start":"2026-05-27T09:54:49.890032Z","end":"2026-05-27T09:54:50.359933Z","steps":["trace[1898714720] 'process raft request' (duration: 469.382596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.359887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:49.701143Z","time spent":"658.482066ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:54:50.360106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:49.890004Z","time spent":"469.99112ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:54:50.360158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.954496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:54:50.360287Z","caller":"traceutil/trace.go:171","msg":"trace[360748376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4715; }","duration":"786.171671ms","start":"2026-05-27T09:54:49.574093Z","end":"2026-05-27T09:54:50.360265Z","steps":["trace[360748376] 'agreement among raft nodes before linearized reading' (duration: 785.865734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.360341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:49.574058Z","time spent":"786.273333ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T09:54:50.376924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.139802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-27T09:54:50.376948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.263385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-67d656cbd8-k4786.18b3638248c89339\" ","response":"range_response_count:1 size:896"} {"level":"warn","ts":"2026-05-27T09:54:50.376952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.837737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-27T09:54:50.376992Z","caller":"traceutil/trace.go:171","msg":"trace[808361922] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4716; }","duration":"470.277406ms","start":"2026-05-27T09:54:49.906698Z","end":"2026-05-27T09:54:50.376975Z","steps":["trace[808361922] 'agreement among raft nodes before linearized reading' (duration: 470.04152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.377038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:49.906651Z","time spent":"470.378378ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-27T09:54:50.377044Z","caller":"traceutil/trace.go:171","msg":"trace[386711362] 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:4716; }","duration":"337.94354ms","start":"2026-05-27T09:54:50.039075Z","end":"2026-05-27T09:54:50.377018Z","steps":["trace[386711362] 'agreement among raft nodes before linearized reading' (duration: 337.700934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.377135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.806017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-27T09:54:50.377209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:50.03905Z","time spent":"338.108934ms","remote":"127.0.0.1:47798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-27T09:54:50.37725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.407229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"warn","ts":"2026-05-27T09:54:50.377207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.588677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T09:54:50.377373Z","caller":"traceutil/trace.go:171","msg":"trace[685280545] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4716; }","duration":"400.573682ms","start":"2026-05-27T09:54:49.976771Z","end":"2026-05-27T09:54:50.377345Z","steps":["trace[685280545] 'agreement among raft nodes before linearized reading' (duration: 400.277935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.377422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:49.976767Z","time spent":"400.643393ms","remote":"127.0.0.1:48798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12997,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-27T09:54:50.377431Z","caller":"traceutil/trace.go:171","msg":"trace[1344327227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4716; }","duration":"785.809631ms","start":"2026-05-27T09:54:49.591588Z","end":"2026-05-27T09:54:50.377398Z","steps":["trace[1344327227] 'agreement among raft nodes before linearized reading' (duration: 785.546985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.377515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:49.591574Z","time spent":"785.918504ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T09:54:50.377332Z","caller":"traceutil/trace.go:171","msg":"trace[1479397860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4716; }","duration":"463.126195ms","start":"2026-05-27T09:54:49.914119Z","end":"2026-05-27T09:54:50.377245Z","steps":["trace[1479397860] 'agreement among raft nodes before linearized reading' (duration: 462.728596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.377696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:49.914103Z","time spent":"463.573965ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T09:54:50.377444Z","caller":"traceutil/trace.go:171","msg":"trace[906372989] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-67d656cbd8-k4786.18b3638248c89339; range_end:; response_count:1; response_revision:4716; }","duration":"400.815178ms","start":"2026-05-27T09:54:49.976608Z","end":"2026-05-27T09:54:50.377423Z","steps":["trace[906372989] 'agreement among raft nodes before linearized reading' (duration: 400.102842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.377982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:49.976582Z","time spent":"401.281808ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":918,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-67d656cbd8-k4786.18b3638248c89339\" "} {"level":"info","ts":"2026-05-27T09:54:50.513794Z","caller":"traceutil/trace.go:171","msg":"trace[2121791841] linearizableReadLoop","detail":"{readStateIndex:4897; appliedIndex:4892; }","duration":"103.499576ms","start":"2026-05-27T09:54:50.410267Z","end":"2026-05-27T09:54:50.513766Z","steps":["trace[2121791841] 'read index received' (duration: 103.036945ms)","trace[2121791841] 'applied index is now lower than readState.Index' (duration: 461.431µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:54:50.513808Z","caller":"traceutil/trace.go:171","msg":"trace[746845593] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"131.759547ms","start":"2026-05-27T09:54:50.382021Z","end":"2026-05-27T09:54:50.513781Z","steps":["trace[746845593] 'process raft request' (duration: 131.388899ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:50.513891Z","caller":"traceutil/trace.go:171","msg":"trace[855027599] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"128.622997ms","start":"2026-05-27T09:54:50.385259Z","end":"2026-05-27T09:54:50.513882Z","steps":["trace[855027599] 'process raft request' (duration: 128.362751ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:50.514105Z","caller":"traceutil/trace.go:171","msg":"trace[489190525] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"128.474864ms","start":"2026-05-27T09:54:50.385566Z","end":"2026-05-27T09:54:50.514041Z","steps":["trace[489190525] 'process raft request' (duration: 128.092875ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:50.513808Z","caller":"traceutil/trace.go:171","msg":"trace[1198426802] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"128.605817ms","start":"2026-05-27T09:54:50.385163Z","end":"2026-05-27T09:54:50.513769Z","steps":["trace[1198426802] 'process raft request' (duration: 128.407542ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:50.514099Z","caller":"traceutil/trace.go:171","msg":"trace[1544757434] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"121.919477ms","start":"2026-05-27T09:54:50.392151Z","end":"2026-05-27T09:54:50.51407Z","steps":["trace[1544757434] 'process raft request' (duration: 121.556289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.514168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.280492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0b91623f-0b9a-479e-9e7d-88c760e1ad67\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-27T09:54:50.514201Z","caller":"traceutil/trace.go:171","msg":"trace[1967354503] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0b91623f-0b9a-479e-9e7d-88c760e1ad67; range_end:; response_count:1; response_revision:4722; }","duration":"129.363144ms","start":"2026-05-27T09:54:50.384826Z","end":"2026-05-27T09:54:50.514189Z","steps":["trace[1967354503] 'agreement among raft nodes before linearized reading' (duration: 129.045907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.514267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.560453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T09:54:50.514326Z","caller":"traceutil/trace.go:171","msg":"trace[1152989404] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4722; }","duration":"127.675076ms","start":"2026-05-27T09:54:50.386639Z","end":"2026-05-27T09:54:50.514314Z","steps":["trace[1152989404] 'agreement among raft nodes before linearized reading' (duration: 127.551053ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:50.646759Z","caller":"traceutil/trace.go:171","msg":"trace[781387724] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"124.103655ms","start":"2026-05-27T09:54:50.522629Z","end":"2026-05-27T09:54:50.646732Z","steps":["trace[781387724] 'process raft request' (duration: 123.807709ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:50.64679Z","caller":"traceutil/trace.go:171","msg":"trace[1077258785] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"123.910491ms","start":"2026-05-27T09:54:50.52285Z","end":"2026-05-27T09:54:50.646761Z","steps":["trace[1077258785] 'process raft request' (duration: 123.781118ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:50.64726Z","caller":"traceutil/trace.go:171","msg":"trace[1641218383] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"123.643756ms","start":"2026-05-27T09:54:50.523554Z","end":"2026-05-27T09:54:50.647198Z","steps":["trace[1641218383] 'process raft request' (duration: 123.139084ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:51.120671Z","caller":"traceutil/trace.go:171","msg":"trace[1638950881] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"134.704947ms","start":"2026-05-27T09:54:50.985928Z","end":"2026-05-27T09:54:51.120633Z","steps":["trace[1638950881] 'process raft request' (duration: 73.161374ms)","trace[1638950881] 'compare' (duration: 61.269197ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:54:52.525082Z","caller":"traceutil/trace.go:171","msg":"trace[127382510] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"268.109058ms","start":"2026-05-27T09:54:52.256957Z","end":"2026-05-27T09:54:52.525067Z","steps":["trace[127382510] 'process raft request' (duration: 267.981615ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:52.542836Z","caller":"traceutil/trace.go:171","msg":"trace[265416608] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"176.380955ms","start":"2026-05-27T09:54:52.366431Z","end":"2026-05-27T09:54:52.542812Z","steps":["trace[265416608] 'process raft request' (duration: 176.196571ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:52.543102Z","caller":"traceutil/trace.go:171","msg":"trace[460694186] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"157.557697ms","start":"2026-05-27T09:54:52.385516Z","end":"2026-05-27T09:54:52.543074Z","steps":["trace[460694186] 'process raft request' (duration: 157.255231ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:52.543146Z","caller":"traceutil/trace.go:171","msg":"trace[306152823] linearizableReadLoop","detail":"{readStateIndex:4910; appliedIndex:4907; }","duration":"129.185376ms","start":"2026-05-27T09:54:52.413943Z","end":"2026-05-27T09:54:52.543128Z","steps":["trace[306152823] 'read index received' (duration: 110.983061ms)","trace[306152823] 'applied index is now lower than readState.Index' (duration: 18.200554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:54:52.544436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.465524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:54:52.544532Z","caller":"traceutil/trace.go:171","msg":"trace[1394113370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4735; }","duration":"130.597556ms","start":"2026-05-27T09:54:52.413916Z","end":"2026-05-27T09:54:52.544513Z","steps":["trace[1394113370] 'agreement among raft nodes before linearized reading' (duration: 129.305198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:52.544603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.076474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a0f3aa0-13f1-48cc-8534-d205e4c7c757\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-05-27T09:54:52.544653Z","caller":"traceutil/trace.go:171","msg":"trace[1936279741] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2a0f3aa0-13f1-48cc-8534-d205e4c7c757; range_end:; response_count:1; response_revision:4735; }","duration":"125.160315ms","start":"2026-05-27T09:54:52.419482Z","end":"2026-05-27T09:54:52.544642Z","steps":["trace[1936279741] 'agreement among raft nodes before linearized reading' (duration: 125.020032ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:55:04.676856Z","caller":"traceutil/trace.go:171","msg":"trace[1644746904] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"112.267447ms","start":"2026-05-27T09:55:04.564567Z","end":"2026-05-27T09:55:04.676834Z","steps":["trace[1644746904] 'process raft request' (duration: 112.107954ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:55:05.056145Z","caller":"traceutil/trace.go:171","msg":"trace[96384654] linearizableReadLoop","detail":"{readStateIndex:5074; appliedIndex:5072; }","duration":"376.64438ms","start":"2026-05-27T09:55:04.679476Z","end":"2026-05-27T09:55:05.056121Z","steps":["trace[96384654] 'read index received' (duration: 376.436506ms)","trace[96384654] 'applied index is now lower than readState.Index' (duration: 206.684µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:55:05.056234Z","caller":"traceutil/trace.go:171","msg":"trace[169104570] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"468.561778ms","start":"2026-05-27T09:55:04.587626Z","end":"2026-05-27T09:55:05.056188Z","steps":["trace[169104570] 'process raft request' (duration: 468.277232ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:55:05.056265Z","caller":"traceutil/trace.go:171","msg":"trace[849651017] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"383.763814ms","start":"2026-05-27T09:55:04.672474Z","end":"2026-05-27T09:55:05.056238Z","steps":["trace[849651017] 'process raft request' (duration: 383.58605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:05.056563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:55:04.587589Z","time spent":"468.816534ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:55:05.056747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:55:04.672456Z","time spent":"384.119512ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:55:05.056489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.994348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T09:55:05.056834Z","caller":"traceutil/trace.go:171","msg":"trace[68813761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4892; }","duration":"377.374545ms","start":"2026-05-27T09:55:04.679439Z","end":"2026-05-27T09:55:05.056814Z","steps":["trace[68813761] 'agreement among raft nodes before linearized reading' (duration: 376.840644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:05.05693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:55:04.679423Z","time spent":"377.490618ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T09:55:05.085575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.521473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:55:05.085646Z","caller":"traceutil/trace.go:171","msg":"trace[469699159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4893; }","duration":"405.650266ms","start":"2026-05-27T09:55:04.679978Z","end":"2026-05-27T09:55:05.085628Z","steps":["trace[469699159] 'agreement among raft nodes before linearized reading' (duration: 405.462342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:05.085652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.128769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-27T09:55:05.085685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:55:04.679953Z","time spent":"405.723147ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T09:55:05.0857Z","caller":"traceutil/trace.go:171","msg":"trace[1298380943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4893; }","duration":"328.193901ms","start":"2026-05-27T09:55:04.757493Z","end":"2026-05-27T09:55:05.085687Z","steps":["trace[1298380943] 'agreement among raft nodes before linearized reading' (duration: 327.953716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:05.085731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:55:04.757485Z","time spent":"328.239192ms","remote":"127.0.0.1:48856","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":"warn","ts":"2026-05-27T09:55:05.085957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.61356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T09:55:05.085986Z","caller":"traceutil/trace.go:171","msg":"trace[1504181013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4893; }","duration":"396.681151ms","start":"2026-05-27T09:55:04.689296Z","end":"2026-05-27T09:55:05.085977Z","steps":["trace[1504181013] 'agreement among raft nodes before linearized reading' (duration: 396.595379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:05.086003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.252579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:55:05.086039Z","caller":"traceutil/trace.go:171","msg":"trace[916019628] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:4893; }","duration":"196.338969ms","start":"2026-05-27T09:55:04.88969Z","end":"2026-05-27T09:55:05.086029Z","steps":["trace[916019628] 'agreement among raft nodes before linearized reading' (duration: 196.274518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:05.086078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.54816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-27T09:55:05.086103Z","caller":"traceutil/trace.go:171","msg":"trace[1077993623] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4893; }","duration":"397.603662ms","start":"2026-05-27T09:55:04.688492Z","end":"2026-05-27T09:55:05.086095Z","steps":["trace[1077993623] 'agreement among raft nodes before linearized reading' (duration: 397.53577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:05.086145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:55:04.688476Z","time spent":"397.663253ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-27T09:55:05.086012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:55:04.68928Z","time spent":"396.726133ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T09:55:05.192543Z","caller":"traceutil/trace.go:171","msg":"trace[1666089196] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5075; }","duration":"103.612055ms","start":"2026-05-27T09:55:05.088902Z","end":"2026-05-27T09:55:05.192514Z","steps":["trace[1666089196] 'read index received' (duration: 97.190977ms)","trace[1666089196] 'applied index is now lower than readState.Index' (duration: 6.419818ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:55:05.192576Z","caller":"traceutil/trace.go:171","msg":"trace[2146207228] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"130.73887ms","start":"2026-05-27T09:55:05.061799Z","end":"2026-05-27T09:55:05.192538Z","steps":["trace[2146207228] 'process raft request' (duration: 124.187698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:05.192804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.871321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T09:55:05.192864Z","caller":"traceutil/trace.go:171","msg":"trace[1804827852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4894; }","duration":"103.976272ms","start":"2026-05-27T09:55:05.088871Z","end":"2026-05-27T09:55:05.192848Z","steps":["trace[1804827852] 'agreement among raft nodes before linearized reading' (duration: 103.774428ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:55:05.211673Z","caller":"traceutil/trace.go:171","msg":"trace[1642490733] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"121.298547ms","start":"2026-05-27T09:55:05.090345Z","end":"2026-05-27T09:55:05.211643Z","steps":["trace[1642490733] 'process raft request' (duration: 121.067032ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:55:05.211671Z","caller":"traceutil/trace.go:171","msg":"trace[1571618104] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"120.633872ms","start":"2026-05-27T09:55:05.09097Z","end":"2026-05-27T09:55:05.211604Z","steps":["trace[1571618104] 'process raft request' (duration: 120.5516ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:55:05.211945Z","caller":"traceutil/trace.go:171","msg":"trace[212879288] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"120.142591ms","start":"2026-05-27T09:55:05.091792Z","end":"2026-05-27T09:55:05.211934Z","steps":["trace[212879288] 'process raft request' (duration: 120.037249ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:55:05.212158Z","caller":"traceutil/trace.go:171","msg":"trace[611448212] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"120.678473ms","start":"2026-05-27T09:55:05.091445Z","end":"2026-05-27T09:55:05.212124Z","steps":["trace[611448212] 'process raft request' (duration: 120.118671ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:55:27.318393Z","caller":"traceutil/trace.go:171","msg":"trace[694092069] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"102.804233ms","start":"2026-05-27T09:55:27.215571Z","end":"2026-05-27T09:55:27.318376Z","steps":["trace[694092069] 'process raft request' (duration: 20.523012ms)","trace[694092069] 'compare' (duration: 82.078747ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:55:38.421132Z","caller":"traceutil/trace.go:171","msg":"trace[833999064] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"105.969152ms","start":"2026-05-27T09:55:38.315094Z","end":"2026-05-27T09:55:38.421063Z","steps":["trace[833999064] 'process raft request' (duration: 70.128761ms)","trace[833999064] 'compare' (duration: 35.651517ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:55:38.45842Z","caller":"traceutil/trace.go:171","msg":"trace[1582645207] linearizableReadLoop","detail":"{readStateIndex:5384; appliedIndex:5382; }","duration":"129.26748ms","start":"2026-05-27T09:55:38.329126Z","end":"2026-05-27T09:55:38.458394Z","steps":["trace[1582645207] 'read index received' (duration: 56.109198ms)","trace[1582645207] 'applied index is now lower than readState.Index' (duration: 73.157152ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:55:38.458624Z","caller":"traceutil/trace.go:171","msg":"trace[1148534061] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"138.649199ms","start":"2026-05-27T09:55:38.319949Z","end":"2026-05-27T09:55:38.458598Z","steps":["trace[1148534061] 'process raft request' (duration: 138.307952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:38.458668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.521886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-27T09:55:38.458709Z","caller":"traceutil/trace.go:171","msg":"trace[777715154] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5194; }","duration":"129.613187ms","start":"2026-05-27T09:55:38.329082Z","end":"2026-05-27T09:55:38.458695Z","steps":["trace[777715154] 'agreement among raft nodes before linearized reading' (duration: 129.406713ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:55:55.592439Z","caller":"traceutil/trace.go:171","msg":"trace[1979763427] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"128.76934ms","start":"2026-05-27T09:55:55.46365Z","end":"2026-05-27T09:55:55.592419Z","steps":["trace[1979763427] 'process raft request' (duration: 87.924704ms)","trace[1979763427] 'compare' (duration: 40.696333ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:55:55.592744Z","caller":"traceutil/trace.go:171","msg":"trace[2126650733] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"110.202708ms","start":"2026-05-27T09:55:55.482506Z","end":"2026-05-27T09:55:55.592709Z","steps":["trace[2126650733] 'process raft request' (duration: 109.867361ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:25.137853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2871} {"level":"info","ts":"2026-05-27T09:56:25.232431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2871,"took":"91.373089ms","hash":15624922,"current-db-size-bytes":53821440,"current-db-size":"54 MB","current-db-size-in-use-bytes":29159424,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-27T09:56:25.232476Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":15624922,"revision":2871,"compact-revision":-1} {"level":"info","ts":"2026-05-27T09:58:52.755136Z","caller":"traceutil/trace.go:171","msg":"trace[916785] linearizableReadLoop","detail":"{readStateIndex:6779; appliedIndex:6778; }","duration":"116.858079ms","start":"2026-05-27T09:58:52.638246Z","end":"2026-05-27T09:58:52.755104Z","steps":["trace[916785] 'read index received' (duration: 8.333175ms)","trace[916785] 'applied index is now lower than readState.Index' (duration: 108.523394ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:58:52.755209Z","caller":"traceutil/trace.go:171","msg":"trace[1416618205] transaction","detail":"{read_only:false; response_revision:6544; number_of_response:1; }","duration":"121.82262ms","start":"2026-05-27T09:58:52.63335Z","end":"2026-05-27T09:58:52.755173Z","steps":["trace[1416618205] 'process raft request' (duration: 60.318551ms)","trace[1416618205] 'compare' (duration: 61.271425ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:58:52.755565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.063402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:58:52.755624Z","caller":"traceutil/trace.go:171","msg":"trace[2046828937] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:6544; }","duration":"117.389508ms","start":"2026-05-27T09:58:52.638218Z","end":"2026-05-27T09:58:52.755608Z","steps":["trace[2046828937] 'agreement among raft nodes before linearized reading' (duration: 117.028782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:13.000354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.007462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:59:13.000741Z","caller":"traceutil/trace.go:171","msg":"trace[1386853223] linearizableReadLoop","detail":"{readStateIndex:6941; appliedIndex:6937; }","duration":"149.064499ms","start":"2026-05-27T09:59:12.85166Z","end":"2026-05-27T09:59:13.000725Z","steps":["trace[1386853223] 'read index received' (duration: 33.68µs)","trace[1386853223] 'applied index is now lower than readState.Index' (duration: 149.029949ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:59:13.000867Z","caller":"traceutil/trace.go:171","msg":"trace[1038050875] transaction","detail":"{read_only:false; response_revision:6696; number_of_response:1; }","duration":"274.547679ms","start":"2026-05-27T09:59:12.726299Z","end":"2026-05-27T09:59:13.000847Z","steps":["trace[1038050875] 'process raft request' (duration: 89.947678ms)","trace[1038050875] 'compare' (duration: 183.86074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:13.000913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.245892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T09:59:13.000939Z","caller":"traceutil/trace.go:171","msg":"trace[1687602476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6699; }","duration":"149.305404ms","start":"2026-05-27T09:59:12.851626Z","end":"2026-05-27T09:59:13.000932Z","steps":["trace[1687602476] 'agreement among raft nodes before linearized reading' (duration: 149.147241ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:13.001022Z","caller":"traceutil/trace.go:171","msg":"trace[430296578] transaction","detail":"{read_only:false; response_revision:6698; number_of_response:1; }","duration":"271.936738ms","start":"2026-05-27T09:59:12.729076Z","end":"2026-05-27T09:59:13.001013Z","steps":["trace[430296578] 'process raft request' (duration: 271.578832ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:13.001096Z","caller":"traceutil/trace.go:171","msg":"trace[310348284] transaction","detail":"{read_only:false; response_revision:6699; number_of_response:1; }","duration":"271.606152ms","start":"2026-05-27T09:59:12.729482Z","end":"2026-05-27T09:59:13.001088Z","steps":["trace[310348284] 'process raft request' (duration: 271.201596ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:13.000894Z","caller":"traceutil/trace.go:171","msg":"trace[2008791437] transaction","detail":"{read_only:false; response_revision:6697; number_of_response:1; }","duration":"272.338364ms","start":"2026-05-27T09:59:12.728508Z","end":"2026-05-27T09:59:13.000847Z","steps":["trace[2008791437] 'process raft request' (duration: 272.095781ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:01:25.144421Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5535} {"level":"info","ts":"2026-05-27T10:01:25.231002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5535,"took":"85.20462ms","hash":702348098,"current-db-size-bytes":53821440,"current-db-size":"54 MB","current-db-size-in-use-bytes":18169856,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-27T10:01:25.231081Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":702348098,"revision":5535,"compact-revision":2871}