{"level":"warn","ts":"2026-05-15T08:07:24.90703Z","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-15T08:07:24.907116Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.233: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.233:2380","--initial-cluster=instance=https://199.19.213.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.233: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-15T08:07:24.907181Z","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-15T08:07:24.907188Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-05-15T08:07:24.907202Z","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-15T08:07:24.907824Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"]} {"level":"info","ts":"2026-05-15T08:07:24.907898Z","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.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.233: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-15T08:07:24.912234Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.147667ms"} {"level":"info","ts":"2026-05-15T08:07:24.918875Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de5c2ed1f829ccc5","cluster-id":"1c2360c7fdb72bdb"} {"level":"info","ts":"2026-05-15T08:07:24.918951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-15T08:07:24.918973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 0"} {"level":"info","ts":"2026-05-15T08:07:24.918983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de5c2ed1f829ccc5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-15T08:07:24.918992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 1"} {"level":"info","ts":"2026-05-15T08:07:24.919028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"warn","ts":"2026-05-15T08:07:24.92986Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-15T08:07:24.931261Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-15T08:07:24.936673Z","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-15T08:07:24.940643Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de5c2ed1f829ccc5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-15T08:07:24.941877Z","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-15T08:07:24.94184Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de5c2ed1f829ccc5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-15T08:07:24.943077Z","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-15T08:07:24.943655Z","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-15T08:07:24.94421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"info","ts":"2026-05-15T08:07:24.944319Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","added-peer-id":"de5c2ed1f829ccc5","added-peer-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-05-15T08:07:24.945564Z","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-15T08:07:24.945642Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-05-15T08:07:24.945689Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-05-15T08:07:24.945765Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de5c2ed1f829ccc5","initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-15T08:07:24.945803Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-15T08:07:25.520163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-15T08:07:25.520228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-15T08:07:25.520252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgPreVoteResp from de5c2ed1f829ccc5 at term 1"} {"level":"info","ts":"2026-05-15T08:07:25.520267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became candidate at term 2"} {"level":"info","ts":"2026-05-15T08:07:25.520273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgVoteResp from de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-05-15T08:07:25.520286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became leader at term 2"} {"level":"info","ts":"2026-05-15T08:07:25.520294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de5c2ed1f829ccc5 elected leader de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-05-15T08:07:25.53491Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de5c2ed1f829ccc5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.233:2379]}","request-path":"/0/members/de5c2ed1f829ccc5/attributes","cluster-id":"1c2360c7fdb72bdb","publish-timeout":"7s"} {"level":"info","ts":"2026-05-15T08:07:25.53516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T08:07:25.535271Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-15T08:07:25.53528Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T08:07:25.53516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T08:07:25.535312Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-15T08:07:25.536242Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T08:07:25.536413Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T08:07:25.536462Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T08:07:25.537352Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-15T08:07:25.538634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.233:2379"} {"level":"info","ts":"2026-05-15T08:07:42.808893Z","caller":"traceutil/trace.go:171","msg":"trace[97945083] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:457; }","duration":"164.128389ms","start":"2026-05-15T08:07:42.644733Z","end":"2026-05-15T08:07:42.808861Z","steps":["trace[97945083] 'read index received' (duration: 163.798591ms)","trace[97945083] 'applied index is now lower than readState.Index' (duration: 328.598µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:07:42.808953Z","caller":"traceutil/trace.go:171","msg":"trace[934621890] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"321.249138ms","start":"2026-05-15T08:07:42.487664Z","end":"2026-05-15T08:07:42.808913Z","steps":["trace[934621890] 'process raft request' (duration: 320.93637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:07:42.809252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.075119ms","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-15T08:07:42.809338Z","caller":"traceutil/trace.go:171","msg":"trace[1338982275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:446; }","duration":"101.211812ms","start":"2026-05-15T08:07:42.708115Z","end":"2026-05-15T08:07:42.809327Z","steps":["trace[1338982275] 'agreement among raft nodes before linearized reading' (duration: 101.079339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:07:42.809335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.544839ms","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-15T08:07:42.809406Z","caller":"traceutil/trace.go:171","msg":"trace[144281274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:446; }","duration":"164.726844ms","start":"2026-05-15T08:07:42.644663Z","end":"2026-05-15T08:07:42.80939Z","steps":["trace[144281274] 'agreement among raft nodes before linearized reading' (duration: 164.481078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:07:42.809716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:07:42.487632Z","time spent":"321.457684ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:07:43.201811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.603862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:07:43.201964Z","caller":"traceutil/trace.go:171","msg":"trace[2100074777] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"387.443035ms","start":"2026-05-15T08:07:42.814458Z","end":"2026-05-15T08:07:43.201901Z","steps":["trace[2100074777] 'process raft request' (duration: 170.511805ms)","trace[2100074777] 'compare' (duration: 216.414448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:07:43.202038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:07:42.814429Z","time spent":"387.574678ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:07:43.360933Z","caller":"traceutil/trace.go:171","msg":"trace[117598565] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:458; }","duration":"390.774228ms","start":"2026-05-15T08:07:42.970132Z","end":"2026-05-15T08:07:43.360906Z","steps":["trace[117598565] 'read index received' (duration: 14.927776ms)","trace[117598565] 'applied index is now lower than readState.Index' (duration: 375.844082ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:07:43.361032Z","caller":"traceutil/trace.go:171","msg":"trace[417686769] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"545.092367ms","start":"2026-05-15T08:07:42.81591Z","end":"2026-05-15T08:07:43.361003Z","steps":["trace[417686769] 'process raft request' (duration: 540.506572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:07:43.361089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.943096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T08:07:43.361089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.954272ms","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-15T08:07:43.361142Z","caller":"traceutil/trace.go:171","msg":"trace[1973084656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"391.051583ms","start":"2026-05-15T08:07:42.970076Z","end":"2026-05-15T08:07:43.361127Z","steps":["trace[1973084656] 'agreement among raft nodes before linearized reading' (duration: 390.934311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:07:43.361178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:07:42.815897Z","time spent":"545.20042ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:07:43.361178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:07:42.970057Z","time spent":"391.112975ms","remote":"127.0.0.1:39624","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-15T08:07:43.36114Z","caller":"traceutil/trace.go:171","msg":"trace[142355519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"269.037537ms","start":"2026-05-15T08:07:43.092084Z","end":"2026-05-15T08:07:43.361122Z","steps":["trace[142355519] 'agreement among raft nodes before linearized reading' (duration: 268.957625ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.414726Z","caller":"traceutil/trace.go:171","msg":"trace[1024753675] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"163.914001ms","start":"2026-05-15T08:07:54.250791Z","end":"2026-05-15T08:07:54.414705Z","steps":["trace[1024753675] 'process raft request' (duration: 163.778958ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.430492Z","caller":"traceutil/trace.go:171","msg":"trace[715103454] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"179.441716ms","start":"2026-05-15T08:07:54.251031Z","end":"2026-05-15T08:07:54.430472Z","steps":["trace[715103454] 'process raft request' (duration: 179.295702ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.43057Z","caller":"traceutil/trace.go:171","msg":"trace[1880158747] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"179.398876ms","start":"2026-05-15T08:07:54.251152Z","end":"2026-05-15T08:07:54.430551Z","steps":["trace[1880158747] 'process raft request' (duration: 179.249462ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.430767Z","caller":"traceutil/trace.go:171","msg":"trace[1798070328] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"173.752837ms","start":"2026-05-15T08:07:54.256994Z","end":"2026-05-15T08:07:54.430747Z","steps":["trace[1798070328] 'process raft request' (duration: 173.427737ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.430809Z","caller":"traceutil/trace.go:171","msg":"trace[199050742] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"173.56175ms","start":"2026-05-15T08:07:54.257232Z","end":"2026-05-15T08:07:54.430794Z","steps":["trace[199050742] 'process raft request' (duration: 173.466307ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.430824Z","caller":"traceutil/trace.go:171","msg":"trace[1246487514] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"172.167249ms","start":"2026-05-15T08:07:54.258644Z","end":"2026-05-15T08:07:54.430811Z","steps":["trace[1246487514] 'process raft request' (duration: 172.081457ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.430974Z","caller":"traceutil/trace.go:171","msg":"trace[2017436830] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"162.025416ms","start":"2026-05-15T08:07:54.26894Z","end":"2026-05-15T08:07:54.430966Z","steps":["trace[2017436830] 'process raft request' (duration: 161.818779ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.431042Z","caller":"traceutil/trace.go:171","msg":"trace[1105752171] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"162.008024ms","start":"2026-05-15T08:07:54.269024Z","end":"2026-05-15T08:07:54.431032Z","steps":["trace[1105752171] 'process raft request' (duration: 161.913362ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.4311Z","caller":"traceutil/trace.go:171","msg":"trace[2134102887] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"147.695885ms","start":"2026-05-15T08:07:54.283399Z","end":"2026-05-15T08:07:54.431095Z","steps":["trace[2134102887] 'process raft request' (duration: 147.603542ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.431179Z","caller":"traceutil/trace.go:171","msg":"trace[1588990854] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"143.785248ms","start":"2026-05-15T08:07:54.287384Z","end":"2026-05-15T08:07:54.43117Z","steps":["trace[1588990854] 'process raft request' (duration: 143.661534ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.437695Z","caller":"traceutil/trace.go:171","msg":"trace[587137586] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"131.954973ms","start":"2026-05-15T08:07:54.299335Z","end":"2026-05-15T08:07:54.43129Z","steps":["trace[587137586] 'process raft request' (duration: 131.896511ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.598983Z","caller":"traceutil/trace.go:171","msg":"trace[1115936073] transaction","detail":"{read_only:false; number_of_response:1; response_revision:531; }","duration":"106.75236ms","start":"2026-05-15T08:07:54.492204Z","end":"2026-05-15T08:07:54.598956Z","steps":["trace[1115936073] 'process raft request' (duration: 106.500021ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:54.599342Z","caller":"traceutil/trace.go:171","msg":"trace[1243529613] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"100.724228ms","start":"2026-05-15T08:07:54.498578Z","end":"2026-05-15T08:07:54.599302Z","steps":["trace[1243529613] 'process raft request' (duration: 100.318775ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:55.058861Z","caller":"traceutil/trace.go:171","msg":"trace[2061660008] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"138.097051ms","start":"2026-05-15T08:07:54.920738Z","end":"2026-05-15T08:07:55.058835Z","steps":["trace[2061660008] 'process raft request' (duration: 98.194639ms)","trace[2061660008] 'compare' (duration: 39.720577ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:07:55.059617Z","caller":"traceutil/trace.go:171","msg":"trace[1707792224] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"119.586227ms","start":"2026-05-15T08:07:54.940007Z","end":"2026-05-15T08:07:55.059594Z","steps":["trace[1707792224] 'process raft request' (duration: 119.452274ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:55.05985Z","caller":"traceutil/trace.go:171","msg":"trace[1704998880] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"120.360449ms","start":"2026-05-15T08:07:54.939477Z","end":"2026-05-15T08:07:55.059838Z","steps":["trace[1704998880] 'process raft request' (duration: 119.665269ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:55.462124Z","caller":"traceutil/trace.go:171","msg":"trace[60128601] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"206.901346ms","start":"2026-05-15T08:07:55.255189Z","end":"2026-05-15T08:07:55.46209Z","steps":["trace[60128601] 'read index received' (duration: 1.844786ms)","trace[60128601] 'applied index is now lower than readState.Index' (duration: 205.05567ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:07:55.462219Z","caller":"traceutil/trace.go:171","msg":"trace[1619657193] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"291.460174ms","start":"2026-05-15T08:07:55.170709Z","end":"2026-05-15T08:07:55.462169Z","steps":["trace[1619657193] 'process raft request' (duration: 234.786033ms)","trace[1619657193] 'compare' (duration: 56.474625ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:07:55.462391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.178674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T08:07:55.462458Z","caller":"traceutil/trace.go:171","msg":"trace[1456756123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"207.296288ms","start":"2026-05-15T08:07:55.255145Z","end":"2026-05-15T08:07:55.462441Z","steps":["trace[1456756123] 'agreement among raft nodes before linearized reading' (duration: 207.05049ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:07:59.763628Z","caller":"traceutil/trace.go:171","msg":"trace[413873238] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"256.224364ms","start":"2026-05-15T08:07:59.50738Z","end":"2026-05-15T08:07:59.763605Z","steps":["trace[413873238] 'read index received' (duration: 256.084268ms)","trace[413873238] 'applied index is now lower than readState.Index' (duration: 139.245µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:07:59.763738Z","caller":"traceutil/trace.go:171","msg":"trace[598246314] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"274.712435ms","start":"2026-05-15T08:07:59.489007Z","end":"2026-05-15T08:07:59.763719Z","steps":["trace[598246314] 'process raft request' (duration: 274.479618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:07:59.763908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.612314ms","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-15T08:07:59.763943Z","caller":"traceutil/trace.go:171","msg":"trace[752183117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"235.679537ms","start":"2026-05-15T08:07:59.528254Z","end":"2026-05-15T08:07:59.763934Z","steps":["trace[752183117] 'agreement among raft nodes before linearized reading' (duration: 235.491351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:07:59.764014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.625026ms","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-15T08:07:59.764052Z","caller":"traceutil/trace.go:171","msg":"trace[57314010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"256.687519ms","start":"2026-05-15T08:07:59.507354Z","end":"2026-05-15T08:07:59.764042Z","steps":["trace[57314010] 'agreement among raft nodes before linearized reading' (duration: 256.364119ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:01.301495Z","caller":"traceutil/trace.go:171","msg":"trace[1209369035] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"403.44425ms","start":"2026-05-15T08:08:00.898014Z","end":"2026-05-15T08:08:01.301459Z","steps":["trace[1209369035] 'read index received' (duration: 402.21015ms)","trace[1209369035] 'applied index is now lower than readState.Index' (duration: 1.23239ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:08:01.301754Z","caller":"traceutil/trace.go:171","msg":"trace[1716165590] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"552.918677ms","start":"2026-05-15T08:08:00.748818Z","end":"2026-05-15T08:08:01.301737Z","steps":["trace[1716165590] 'process raft request' (duration: 551.679717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:01.302136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:00.74879Z","time spent":"553.072241ms","remote":"127.0.0.1:39486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:08:01.302445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.390941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T08:08:01.302603Z","caller":"traceutil/trace.go:171","msg":"trace[1749980676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"404.66266ms","start":"2026-05-15T08:08:00.89791Z","end":"2026-05-15T08:08:01.302572Z","steps":["trace[1749980676] 'agreement among raft nodes before linearized reading' (duration: 404.174713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:01.302696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:00.897887Z","time spent":"404.795324ms","remote":"127.0.0.1:39624","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-15T08:08:01.330505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.030307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:01.330569Z","caller":"traceutil/trace.go:171","msg":"trace[1022823993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"239.13978ms","start":"2026-05-15T08:08:01.091414Z","end":"2026-05-15T08:08:01.330554Z","steps":["trace[1022823993] 'agreement among raft nodes before linearized reading' (duration: 239.053557ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:02.002741Z","caller":"traceutil/trace.go:171","msg":"trace[1143122374] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"694.065645ms","start":"2026-05-15T08:08:01.30865Z","end":"2026-05-15T08:08:02.002716Z","steps":["trace[1143122374] 'process raft request' (duration: 693.91765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:02.002895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:01.308624Z","time spent":"694.195568ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:08:03.620082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.661593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:08:03.620283Z","caller":"traceutil/trace.go:171","msg":"trace[492299422] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"2.242423337s","start":"2026-05-15T08:08:01.377844Z","end":"2026-05-15T08:08:03.620268Z","steps":["trace[492299422] 'process raft request' (duration: 2.242317033s)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:03.620321Z","caller":"traceutil/trace.go:171","msg":"trace[876896530] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"2.279086286s","start":"2026-05-15T08:08:01.341206Z","end":"2026-05-15T08:08:03.620292Z","steps":["trace[876896530] 'process raft request' (duration: 1.467127903s)","trace[876896530] 'compare' (duration: 811.026652ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:08:03.620364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:01.377818Z","time spent":"2.242515719s","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:08:03.620386Z","caller":"traceutil/trace.go:171","msg":"trace[62928175] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:622; }","duration":"1.851761336s","start":"2026-05-15T08:08:01.768558Z","end":"2026-05-15T08:08:03.620319Z","steps":["trace[62928175] 'read index received' (duration: 234.005022ms)","trace[62928175] 'applied index is now lower than readState.Index' (duration: 1.617754614s)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:08:03.620491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:01.34118Z","time spent":"2.279211742s","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:08:03.650284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.881720108s","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-15T08:08:03.650349Z","caller":"traceutil/trace.go:171","msg":"trace[87878802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"1.881819573s","start":"2026-05-15T08:08:01.768512Z","end":"2026-05-15T08:08:03.650332Z","steps":["trace[87878802] 'agreement among raft nodes before linearized reading' (duration: 1.852035135s)","trace[87878802] 'range keys from in-memory index tree' (duration: 29.632853ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:08:03.650338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559481757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T08:08:03.650379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:01.76849Z","time spent":"1.881881504s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-15T08:08:03.65038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.878352799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-15T08:08:03.650419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.87841048s","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-15T08:08:03.650455Z","caller":"traceutil/trace.go:171","msg":"trace[959652743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.878461674s","start":"2026-05-15T08:08:01.771975Z","end":"2026-05-15T08:08:03.650437Z","steps":["trace[959652743] 'agreement among raft nodes before linearized reading' (duration: 1.878256407s)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:03.65047Z","caller":"traceutil/trace.go:171","msg":"trace[526951565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.878525245s","start":"2026-05-15T08:08:01.771933Z","end":"2026-05-15T08:08:03.650458Z","steps":["trace[526951565] 'agreement among raft nodes before linearized reading' (duration: 1.878325448s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:03.6505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:01.771961Z","time spent":"1.878530465s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-15T08:08:03.650502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:01.7719Z","time spent":"1.878594316s","remote":"127.0.0.1:39624","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-15T08:08:03.650407Z","caller":"traceutil/trace.go:171","msg":"trace[1788241348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"1.55959936s","start":"2026-05-15T08:08:02.090788Z","end":"2026-05-15T08:08:03.650387Z","steps":["trace[1788241348] 'agreement among raft nodes before linearized reading' (duration: 1.559468695s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:03.650553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:02.090762Z","time spent":"1.559783097s","remote":"127.0.0.1:39390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T08:08:05.59715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.199672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:05.597211Z","caller":"traceutil/trace.go:171","msg":"trace[1200281730] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:644; }","duration":"137.305264ms","start":"2026-05-15T08:08:05.459895Z","end":"2026-05-15T08:08:05.5972Z","steps":["trace[1200281730] 'range keys from in-memory index tree' (duration: 137.12496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:05.597302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.382147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:05.59732Z","caller":"traceutil/trace.go:171","msg":"trace[1332199118] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:644; }","duration":"137.420958ms","start":"2026-05-15T08:08:05.459895Z","end":"2026-05-15T08:08:05.597315Z","steps":["trace[1332199118] 'range keys from in-memory index tree' (duration: 137.282294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:05.597384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.323055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:05.597404Z","caller":"traceutil/trace.go:171","msg":"trace[422249687] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:644; }","duration":"137.452779ms","start":"2026-05-15T08:08:05.459945Z","end":"2026-05-15T08:08:05.597398Z","steps":["trace[422249687] 'count revisions from in-memory index tree' (duration: 137.214512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:05.597477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.229472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:05.597496Z","caller":"traceutil/trace.go:171","msg":"trace[1616938506] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:644; }","duration":"125.278064ms","start":"2026-05-15T08:08:05.472212Z","end":"2026-05-15T08:08:05.59749Z","steps":["trace[1616938506] 'range keys from in-memory index tree' (duration: 125.155949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:05.597579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.491956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:05.597596Z","caller":"traceutil/trace.go:171","msg":"trace[1806176618] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:644; }","duration":"119.510256ms","start":"2026-05-15T08:08:05.478082Z","end":"2026-05-15T08:08:05.597592Z","steps":["trace[1806176618] 'range keys from in-memory index tree' (duration: 119.411463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:05.597667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.526617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:05.597682Z","caller":"traceutil/trace.go:171","msg":"trace[1822622040] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:644; }","duration":"119.565498ms","start":"2026-05-15T08:08:05.478112Z","end":"2026-05-15T08:08:05.597678Z","steps":["trace[1822622040] 'count revisions from in-memory index tree' (duration: 119.476696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:05.597736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.058611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:05.597755Z","caller":"traceutil/trace.go:171","msg":"trace[1532315559] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:644; }","duration":"119.093791ms","start":"2026-05-15T08:08:05.478655Z","end":"2026-05-15T08:08:05.597749Z","steps":["trace[1532315559] 'range keys from in-memory index tree' (duration: 119.0195ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:27.34483Z","caller":"traceutil/trace.go:171","msg":"trace[340251954] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"136.398609ms","start":"2026-05-15T08:08:27.208409Z","end":"2026-05-15T08:08:27.344808Z","steps":["trace[340251954] 'read index received' (duration: 136.18117ms)","trace[340251954] 'applied index is now lower than readState.Index' (duration: 216.529µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:08:27.344966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.528243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:27.345001Z","caller":"traceutil/trace.go:171","msg":"trace[1697232289] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:794; }","duration":"136.608845ms","start":"2026-05-15T08:08:27.208381Z","end":"2026-05-15T08:08:27.34499Z","steps":["trace[1697232289] 'agreement among raft nodes before linearized reading' (duration: 136.529173ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:27.344952Z","caller":"traceutil/trace.go:171","msg":"trace[506384853] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"154.753225ms","start":"2026-05-15T08:08:27.190139Z","end":"2026-05-15T08:08:27.344892Z","steps":["trace[506384853] 'process raft request' (duration: 154.530146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:30.892011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755373660278488567,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T08:08:31.392669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755373660278488567,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T08:08:31.693682Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.383226012s","expected-duration":"1s"} {"level":"info","ts":"2026-05-15T08:08:31.694244Z","caller":"traceutil/trace.go:171","msg":"trace[239355740] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"1.3023901s","start":"2026-05-15T08:08:30.391811Z","end":"2026-05-15T08:08:31.694201Z","steps":["trace[239355740] 'read index received' (duration: 1.302231874s)","trace[239355740] 'applied index is now lower than readState.Index' (duration: 157.456µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:08:31.694537Z","caller":"traceutil/trace.go:171","msg":"trace[125973009] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"1.384155482s","start":"2026-05-15T08:08:30.310369Z","end":"2026-05-15T08:08:31.694525Z","steps":["trace[125973009] 'process raft request' (duration: 1.383710583s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:31.694688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:30.310354Z","time spent":"1.384217324s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:08:31.694991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303154092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tff6b\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-15T08:08:31.695065Z","caller":"traceutil/trace.go:171","msg":"trace[1703597530] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tff6b; range_end:; response_count:1; response_revision:813; }","duration":"1.303285267s","start":"2026-05-15T08:08:30.391764Z","end":"2026-05-15T08:08:31.695049Z","steps":["trace[1703597530] 'agreement among raft nodes before linearized reading' (duration: 1.302945783s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:31.6951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:30.391724Z","time spent":"1.303369112s","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tff6b\" "} {"level":"warn","ts":"2026-05-15T08:08:31.707247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.909989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T08:08:31.707291Z","caller":"traceutil/trace.go:171","msg":"trace[1161759769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"346.989313ms","start":"2026-05-15T08:08:31.360292Z","end":"2026-05-15T08:08:31.707281Z","steps":["trace[1161759769] 'agreement among raft nodes before linearized reading' (duration: 346.866218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:31.707315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:31.360277Z","time spent":"347.033015ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-15T08:08:33.877621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.147359ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-15T08:08:33.877729Z","caller":"traceutil/trace.go:171","msg":"trace[1465593287] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"179.100742ms","start":"2026-05-15T08:08:33.698612Z","end":"2026-05-15T08:08:33.877713Z","steps":["trace[1465593287] 'read index received' (duration: 57.893991ms)","trace[1465593287] 'applied index is now lower than readState.Index' (duration: 121.205411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:08:33.87791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.309231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T08:08:33.877992Z","caller":"traceutil/trace.go:171","msg":"trace[1071964011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"179.444998ms","start":"2026-05-15T08:08:33.698538Z","end":"2026-05-15T08:08:33.877983Z","steps":["trace[1071964011] 'agreement among raft nodes before linearized reading' (duration: 179.26266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:33.878196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.06191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T08:08:33.878372Z","caller":"traceutil/trace.go:171","msg":"trace[622461700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:821; }","duration":"155.258728ms","start":"2026-05-15T08:08:33.723086Z","end":"2026-05-15T08:08:33.878345Z","steps":["trace[622461700] 'agreement among raft nodes before linearized reading' (duration: 154.755216ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:34.081027Z","caller":"traceutil/trace.go:171","msg":"trace[1338809409] transaction","detail":"{read_only:false; number_of_response:1; response_revision:827; }","duration":"134.122555ms","start":"2026-05-15T08:08:33.946882Z","end":"2026-05-15T08:08:34.081004Z","steps":["trace[1338809409] 'process raft request' (duration: 133.391183ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:36.882454Z","caller":"traceutil/trace.go:171","msg":"trace[1997787296] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"280.459543ms","start":"2026-05-15T08:08:36.601973Z","end":"2026-05-15T08:08:36.882432Z","steps":["trace[1997787296] 'read index received' (duration: 280.302456ms)","trace[1997787296] 'applied index is now lower than readState.Index' (duration: 156.167µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:08:36.882514Z","caller":"traceutil/trace.go:171","msg":"trace[377156890] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"346.749233ms","start":"2026-05-15T08:08:36.535732Z","end":"2026-05-15T08:08:36.882481Z","steps":["trace[377156890] 'process raft request' (duration: 346.602147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:36.882605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.365465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:36.882645Z","caller":"traceutil/trace.go:171","msg":"trace[1331495853] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:0; response_revision:868; }","duration":"180.45291ms","start":"2026-05-15T08:08:36.702181Z","end":"2026-05-15T08:08:36.882634Z","steps":["trace[1331495853] 'agreement among raft nodes before linearized reading' (duration: 180.381456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:36.882674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:36.53571Z","time spent":"346.871649ms","remote":"127.0.0.1:39472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T08:08:36.882691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.691223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-05-15T08:08:36.883226Z","caller":"traceutil/trace.go:171","msg":"trace[551388642] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:868; }","duration":"281.256278ms","start":"2026-05-15T08:08:36.60194Z","end":"2026-05-15T08:08:36.882926Z","steps":["trace[551388642] 'agreement among raft nodes before linearized reading' (duration: 280.566728ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:37.66188Z","caller":"traceutil/trace.go:171","msg":"trace[902667605] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"139.731775ms","start":"2026-05-15T08:08:37.522116Z","end":"2026-05-15T08:08:37.661848Z","steps":["trace[902667605] 'process raft request' (duration: 124.719419ms)","trace[902667605] 'compare' (duration: 14.886441ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:08:37.682158Z","caller":"traceutil/trace.go:171","msg":"trace[1459948922] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"159.964216ms","start":"2026-05-15T08:08:37.522163Z","end":"2026-05-15T08:08:37.682127Z","steps":["trace[1459948922] 'process raft request' (duration: 159.790809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:39.095313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.877264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:08:39.095384Z","caller":"traceutil/trace.go:171","msg":"trace[609786873] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"376.220613ms","start":"2026-05-15T08:08:38.719154Z","end":"2026-05-15T08:08:39.095374Z","steps":["trace[609786873] 'process raft request' (duration: 167.240284ms)","trace[609786873] 'compare' (duration: 208.740038ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:08:39.095418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:38.719128Z","time spent":"376.277356ms","remote":"127.0.0.1:39472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:08:39.175316Z","caller":"traceutil/trace.go:171","msg":"trace[198081213] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:916; }","duration":"394.950099ms","start":"2026-05-15T08:08:38.780346Z","end":"2026-05-15T08:08:39.175296Z","steps":["trace[198081213] 'read index received' (duration: 106.061268ms)","trace[198081213] 'applied index is now lower than readState.Index' (duration: 288.887621ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:08:39.17558Z","caller":"traceutil/trace.go:171","msg":"trace[1657470758] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"455.024909ms","start":"2026-05-15T08:08:38.720542Z","end":"2026-05-15T08:08:39.175567Z","steps":["trace[1657470758] 'process raft request' (duration: 454.645883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:39.175679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:38.720532Z","time spent":"455.092012ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4762,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:08:39.175884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.529974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:39.175934Z","caller":"traceutil/trace.go:171","msg":"trace[1412145378] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:890; }","duration":"395.681161ms","start":"2026-05-15T08:08:38.780242Z","end":"2026-05-15T08:08:39.175923Z","steps":["trace[1412145378] 'agreement among raft nodes before linearized reading' (duration: 395.597617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:39.175978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:38.780222Z","time spent":"395.744173ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-15T08:08:39.178462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.026038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-15T08:08:39.17851Z","caller":"traceutil/trace.go:171","msg":"trace[712526187] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:891; }","duration":"335.104702ms","start":"2026-05-15T08:08:38.843394Z","end":"2026-05-15T08:08:39.178499Z","steps":["trace[712526187] 'agreement among raft nodes before linearized reading' (duration: 335.010697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:39.178544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:38.843379Z","time spent":"335.156093ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"info","ts":"2026-05-15T08:08:39.178802Z","caller":"traceutil/trace.go:171","msg":"trace[567964791] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"165.446931ms","start":"2026-05-15T08:08:39.013345Z","end":"2026-05-15T08:08:39.178792Z","steps":["trace[567964791] 'process raft request' (duration: 164.977301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:39.178904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.697213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-15T08:08:39.178957Z","caller":"traceutil/trace.go:171","msg":"trace[1563711697] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:891; }","duration":"334.761697ms","start":"2026-05-15T08:08:38.84418Z","end":"2026-05-15T08:08:39.178942Z","steps":["trace[1563711697] 'agreement among raft nodes before linearized reading' (duration: 334.672623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:39.178998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:38.844175Z","time spent":"334.812868ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"info","ts":"2026-05-15T08:08:39.434706Z","caller":"traceutil/trace.go:171","msg":"trace[459071494] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"248.071583ms","start":"2026-05-15T08:08:39.186605Z","end":"2026-05-15T08:08:39.434676Z","steps":["trace[459071494] 'process raft request' (duration: 242.108865ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:52.391148Z","caller":"traceutil/trace.go:171","msg":"trace[2131468087] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1178; }","duration":"300.297351ms","start":"2026-05-15T08:08:52.090828Z","end":"2026-05-15T08:08:52.391125Z","steps":["trace[2131468087] 'read index received' (duration: 300.197306ms)","trace[2131468087] 'applied index is now lower than readState.Index' (duration: 99.365µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:08:52.391278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.435888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:08:52.391317Z","caller":"traceutil/trace.go:171","msg":"trace[787115609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"300.528101ms","start":"2026-05-15T08:08:52.090777Z","end":"2026-05-15T08:08:52.391306Z","steps":["trace[787115609] 'agreement among raft nodes before linearized reading' (duration: 300.443037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:52.391349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:52.09076Z","time spent":"300.581425ms","remote":"127.0.0.1:39390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T08:08:52.39118Z","caller":"traceutil/trace.go:171","msg":"trace[25302905] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"313.827253ms","start":"2026-05-15T08:08:52.07733Z","end":"2026-05-15T08:08:52.391158Z","steps":["trace[25302905] 'process raft request' (duration: 313.690846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:52.391531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:08:52.077314Z","time spent":"314.146236ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:08:52.391598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.506634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-15T08:08:52.391578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.198514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T08:08:52.391683Z","caller":"traceutil/trace.go:171","msg":"trace[459667644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1148; }","duration":"275.63195ms","start":"2026-05-15T08:08:52.116036Z","end":"2026-05-15T08:08:52.391668Z","steps":["trace[459667644] 'agreement among raft nodes before linearized reading' (duration: 275.400639ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:52.391745Z","caller":"traceutil/trace.go:171","msg":"trace[1184567403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1148; }","duration":"298.383584ms","start":"2026-05-15T08:08:52.093345Z","end":"2026-05-15T08:08:52.391729Z","steps":["trace[1184567403] 'agreement among raft nodes before linearized reading' (duration: 298.061139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:52.391627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.74351ms","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-15T08:08:52.391796Z","caller":"traceutil/trace.go:171","msg":"trace[1925039798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1148; }","duration":"281.9511ms","start":"2026-05-15T08:08:52.109836Z","end":"2026-05-15T08:08:52.391787Z","steps":["trace[1925039798] 'agreement among raft nodes before linearized reading' (duration: 281.725589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:08:52.391587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.97285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-15T08:08:52.391881Z","caller":"traceutil/trace.go:171","msg":"trace[280155440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1148; }","duration":"275.289514ms","start":"2026-05-15T08:08:52.116579Z","end":"2026-05-15T08:08:52.391869Z","steps":["trace[280155440] 'agreement among raft nodes before linearized reading' (duration: 274.925868ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:08:53.604069Z","caller":"traceutil/trace.go:171","msg":"trace[1733548017] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"101.522276ms","start":"2026-05-15T08:08:53.502517Z","end":"2026-05-15T08:08:53.60404Z","steps":["trace[1733548017] 'process raft request' (duration: 101.352708ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:02.507113Z","caller":"traceutil/trace.go:171","msg":"trace[463012644] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"101.202291ms","start":"2026-05-15T08:09:02.405886Z","end":"2026-05-15T08:09:02.507088Z","steps":["trace[463012644] 'process raft request' (duration: 100.706778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:27.259828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.806063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T08:09:27.259899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.324151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T08:09:27.259989Z","caller":"traceutil/trace.go:171","msg":"trace[1726392250] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1483; }","duration":"179.482358ms","start":"2026-05-15T08:09:27.080484Z","end":"2026-05-15T08:09:27.259966Z","steps":["trace[1726392250] 'count revisions from in-memory index tree' (duration: 179.207035ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:27.259927Z","caller":"traceutil/trace.go:171","msg":"trace[647403398] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1483; }","duration":"275.973031ms","start":"2026-05-15T08:09:26.983935Z","end":"2026-05-15T08:09:27.259908Z","steps":["trace[647403398] 'count revisions from in-memory index tree' (duration: 275.718208ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:39.317541Z","caller":"traceutil/trace.go:171","msg":"trace[677378593] linearizableReadLoop","detail":"{readStateIndex:1783; appliedIndex:1782; }","duration":"119.27145ms","start":"2026-05-15T08:09:39.198238Z","end":"2026-05-15T08:09:39.31751Z","steps":["trace[677378593] 'read index received' (duration: 112.929764ms)","trace[677378593] 'applied index is now lower than readState.Index' (duration: 6.339936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:09:39.317756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.493122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:09:39.317825Z","caller":"traceutil/trace.go:171","msg":"trace[811718549] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com; range_end:; response_count:0; response_revision:1719; }","duration":"119.612258ms","start":"2026-05-15T08:09:39.198194Z","end":"2026-05-15T08:09:39.317806Z","steps":["trace[811718549] 'agreement among raft nodes before linearized reading' (duration: 119.4818ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:39.318125Z","caller":"traceutil/trace.go:171","msg":"trace[1933431642] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"158.689079ms","start":"2026-05-15T08:09:39.159016Z","end":"2026-05-15T08:09:39.317705Z","steps":["trace[1933431642] 'process raft request' (duration: 152.149842ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:44.971965Z","caller":"traceutil/trace.go:171","msg":"trace[1364680749] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"226.425363ms","start":"2026-05-15T08:09:44.74552Z","end":"2026-05-15T08:09:44.971946Z","steps":["trace[1364680749] 'read index received' (duration: 226.209812ms)","trace[1364680749] 'applied index is now lower than readState.Index' (duration: 214.661µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:09:44.972041Z","caller":"traceutil/trace.go:171","msg":"trace[2013648151] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"251.902014ms","start":"2026-05-15T08:09:44.720108Z","end":"2026-05-15T08:09:44.97201Z","steps":["trace[2013648151] 'process raft request' (duration: 251.676702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:44.972176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.465116ms","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-15T08:09:44.972213Z","caller":"traceutil/trace.go:171","msg":"trace[130299419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1790; }","duration":"221.56163ms","start":"2026-05-15T08:09:44.750642Z","end":"2026-05-15T08:09:44.972204Z","steps":["trace[130299419] 'agreement among raft nodes before linearized reading' (duration: 221.412453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:44.972235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.008614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T08:09:44.972294Z","caller":"traceutil/trace.go:171","msg":"trace[602457644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1790; }","duration":"217.114469ms","start":"2026-05-15T08:09:44.755165Z","end":"2026-05-15T08:09:44.972279Z","steps":["trace[602457644] 'agreement among raft nodes before linearized reading' (duration: 216.902658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:44.972318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.75187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-15T08:09:44.972339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.771339ms","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-15T08:09:44.972419Z","caller":"traceutil/trace.go:171","msg":"trace[535541708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1790; }","duration":"226.922509ms","start":"2026-05-15T08:09:44.745477Z","end":"2026-05-15T08:09:44.972399Z","steps":["trace[535541708] 'agreement among raft nodes before linearized reading' (duration: 226.548579ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:44.972435Z","caller":"traceutil/trace.go:171","msg":"trace[573756148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1790; }","duration":"220.888685ms","start":"2026-05-15T08:09:44.751526Z","end":"2026-05-15T08:09:44.972415Z","steps":["trace[573756148] 'agreement among raft nodes before linearized reading' (duration: 220.675374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:53.376165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755373660278490677,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T08:09:53.470873Z","caller":"traceutil/trace.go:171","msg":"trace[1045905897] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"595.679524ms","start":"2026-05-15T08:09:52.875164Z","end":"2026-05-15T08:09:53.470844Z","steps":["trace[1045905897] 'read index received' (duration: 595.444032ms)","trace[1045905897] 'applied index is now lower than readState.Index' (duration: 234.492µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:09:53.470926Z","caller":"traceutil/trace.go:171","msg":"trace[877489605] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"706.042599ms","start":"2026-05-15T08:09:52.764851Z","end":"2026-05-15T08:09:53.470894Z","steps":["trace[877489605] 'process raft request' (duration: 705.849509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:53.471044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.854553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T08:09:53.47109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:52.764822Z","time spent":"706.175066ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:09:53.471097Z","caller":"traceutil/trace.go:171","msg":"trace[1145733225] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1905; }","duration":"595.977549ms","start":"2026-05-15T08:09:52.875104Z","end":"2026-05-15T08:09:53.471082Z","steps":["trace[1145733225] 'agreement among raft nodes before linearized reading' (duration: 595.870074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:53.471145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:52.875071Z","time spent":"596.062805ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-15T08:09:53.471181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.21665ms","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-15T08:09:53.471213Z","caller":"traceutil/trace.go:171","msg":"trace[398670207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1905; }","duration":"459.279154ms","start":"2026-05-15T08:09:53.011925Z","end":"2026-05-15T08:09:53.471204Z","steps":["trace[398670207] 'agreement among raft nodes before linearized reading' (duration: 459.152046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:53.471203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.998243ms","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-15T08:09:53.471237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:53.01191Z","time spent":"459.320776ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-15T08:09:53.471263Z","caller":"traceutil/trace.go:171","msg":"trace[1113080948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1905; }","duration":"469.115331ms","start":"2026-05-15T08:09:53.002131Z","end":"2026-05-15T08:09:53.471246Z","steps":["trace[1113080948] 'agreement among raft nodes before linearized reading' (duration: 468.901919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:53.471313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:53.002105Z","time spent":"469.196465ms","remote":"127.0.0.1:39624","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-15T08:09:53.471384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.507995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-05-15T08:09:53.471399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.388629ms","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-15T08:09:53.471414Z","caller":"traceutil/trace.go:171","msg":"trace[221261424] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1905; }","duration":"253.549257ms","start":"2026-05-15T08:09:53.217857Z","end":"2026-05-15T08:09:53.471406Z","steps":["trace[221261424] 'agreement among raft nodes before linearized reading' (duration: 253.455842ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:53.471427Z","caller":"traceutil/trace.go:171","msg":"trace[913761304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1905; }","duration":"459.440422ms","start":"2026-05-15T08:09:53.011977Z","end":"2026-05-15T08:09:53.471417Z","steps":["trace[913761304] 'agreement among raft nodes before linearized reading' (duration: 459.371138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:53.471452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:53.011962Z","time spent":"459.484115ms","remote":"127.0.0.1:39624","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-15T08:09:53.471479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.905268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a59baf9d-43af-489e-82ac-459b0cef85ae\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-05-15T08:09:53.471419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.154536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-15T08:09:53.47154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.127829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-15T08:09:53.471563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.192191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T08:09:53.471585Z","caller":"traceutil/trace.go:171","msg":"trace[388929135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1905; }","duration":"460.192341ms","start":"2026-05-15T08:09:53.011371Z","end":"2026-05-15T08:09:53.471563Z","steps":["trace[388929135] 'agreement among raft nodes before linearized reading' (duration: 460.110877ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:53.471573Z","caller":"traceutil/trace.go:171","msg":"trace[373019014] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1905; }","duration":"459.331486ms","start":"2026-05-15T08:09:53.012219Z","end":"2026-05-15T08:09:53.47155Z","steps":["trace[373019014] 'agreement among raft nodes before linearized reading' (duration: 458.828869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:53.471617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:53.011354Z","time spent":"460.256314ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-15T08:09:53.471616Z","caller":"traceutil/trace.go:171","msg":"trace[1737293167] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1905; }","duration":"258.268614ms","start":"2026-05-15T08:09:53.213333Z","end":"2026-05-15T08:09:53.471601Z","steps":["trace[1737293167] 'agreement among raft nodes before linearized reading' (duration: 258.178309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:09:53.471649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:53.012205Z","time spent":"459.425161ms","remote":"127.0.0.1:39624","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-15T08:09:53.471517Z","caller":"traceutil/trace.go:171","msg":"trace[370914039] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a59baf9d-43af-489e-82ac-459b0cef85ae; range_end:; response_count:1; response_revision:1905; }","duration":"210.979033ms","start":"2026-05-15T08:09:53.260525Z","end":"2026-05-15T08:09:53.471504Z","steps":["trace[370914039] 'agreement among raft nodes before linearized reading' (duration: 210.91462ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:53.726041Z","caller":"traceutil/trace.go:171","msg":"trace[701710636] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"249.80027ms","start":"2026-05-15T08:09:53.476212Z","end":"2026-05-15T08:09:53.726012Z","steps":["trace[701710636] 'process raft request' (duration: 159.982627ms)","trace[701710636] 'compare' (duration: 89.680745ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:09:53.729533Z","caller":"traceutil/trace.go:171","msg":"trace[1550717874] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"252.65069ms","start":"2026-05-15T08:09:53.476863Z","end":"2026-05-15T08:09:53.729513Z","steps":["trace[1550717874] 'process raft request' (duration: 252.479391ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:53.729859Z","caller":"traceutil/trace.go:171","msg":"trace[348986081] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"253.505274ms","start":"2026-05-15T08:09:53.476343Z","end":"2026-05-15T08:09:53.729849Z","steps":["trace[348986081] 'process raft request' (duration: 252.878511ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:53.729852Z","caller":"traceutil/trace.go:171","msg":"trace[61842237] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"252.697262ms","start":"2026-05-15T08:09:53.477134Z","end":"2026-05-15T08:09:53.729832Z","steps":["trace[61842237] 'process raft request' (duration: 252.242388ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:53.730092Z","caller":"traceutil/trace.go:171","msg":"trace[1733879601] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"252.770106ms","start":"2026-05-15T08:09:53.47731Z","end":"2026-05-15T08:09:53.73008Z","steps":["trace[1733879601] 'process raft request' (duration: 252.110312ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:53.730243Z","caller":"traceutil/trace.go:171","msg":"trace[9392400] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"118.89951ms","start":"2026-05-15T08:09:53.611333Z","end":"2026-05-15T08:09:53.730232Z","steps":["trace[9392400] 'process raft request' (duration: 118.197232ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:53.730465Z","caller":"traceutil/trace.go:171","msg":"trace[1712033088] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"253.094453ms","start":"2026-05-15T08:09:53.477361Z","end":"2026-05-15T08:09:53.730455Z","steps":["trace[1712033088] 'process raft request' (duration: 252.112642ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:53.730578Z","caller":"traceutil/trace.go:171","msg":"trace[1353441807] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"155.901523ms","start":"2026-05-15T08:09:53.57466Z","end":"2026-05-15T08:09:53.730561Z","steps":["trace[1353441807] 'process raft request' (duration: 154.843417ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:09:59.994614Z","caller":"traceutil/trace.go:171","msg":"trace[339437897] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"243.600754ms","start":"2026-05-15T08:09:59.750983Z","end":"2026-05-15T08:09:59.994584Z","steps":["trace[339437897] 'process raft request' (duration: 243.427495ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:00.068297Z","caller":"traceutil/trace.go:171","msg":"trace[1678960936] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2032; }","duration":"309.264728ms","start":"2026-05-15T08:09:59.758998Z","end":"2026-05-15T08:10:00.068263Z","steps":["trace[1678960936] 'read index received' (duration: 235.343009ms)","trace[1678960936] 'applied index is now lower than readState.Index' (duration: 73.920319ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:00.06851Z","caller":"traceutil/trace.go:171","msg":"trace[1907751354] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"315.200153ms","start":"2026-05-15T08:09:59.753292Z","end":"2026-05-15T08:10:00.068493Z","steps":["trace[1907751354] 'process raft request' (duration: 301.518349ms)","trace[1907751354] 'compare' (duration: 13.303954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:10:00.068688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:59.75327Z","time spent":"315.289096ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:10:00.068886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.688718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-15T08:10:00.068902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.568434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T08:10:00.068964Z","caller":"traceutil/trace.go:171","msg":"trace[59555881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1961; }","duration":"308.826104ms","start":"2026-05-15T08:09:59.760116Z","end":"2026-05-15T08:10:00.068942Z","steps":["trace[59555881] 'agreement among raft nodes before linearized reading' (duration: 308.621853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:00.068961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.163131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-15T08:10:00.069007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.975196ms","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-15T08:10:00.06907Z","caller":"traceutil/trace.go:171","msg":"trace[710782970] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1961; }","duration":"298.32367ms","start":"2026-05-15T08:09:59.770721Z","end":"2026-05-15T08:10:00.069045Z","steps":["trace[710782970] 'agreement among raft nodes before linearized reading' (duration: 297.977181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:00.069105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.759591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T08:10:00.069105Z","caller":"traceutil/trace.go:171","msg":"trace[1955225605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1961; }","duration":"310.131944ms","start":"2026-05-15T08:09:59.758951Z","end":"2026-05-15T08:10:00.069083Z","steps":["trace[1955225605] 'agreement among raft nodes before linearized reading' (duration: 309.821838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:00.069127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.126541ms","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-15T08:10:00.069138Z","caller":"traceutil/trace.go:171","msg":"trace[1235199164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1961; }","duration":"308.806393ms","start":"2026-05-15T08:09:59.76032Z","end":"2026-05-15T08:10:00.069126Z","steps":["trace[1235199164] 'agreement among raft nodes before linearized reading' (duration: 308.721778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:00.069159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:59.758926Z","time spent":"310.224268ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-15T08:10:00.069171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:59.760308Z","time spent":"308.858246ms","remote":"127.0.0.1:39624","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-15T08:10:00.069169Z","caller":"traceutil/trace.go:171","msg":"trace[925418126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1961; }","duration":"309.177983ms","start":"2026-05-15T08:09:59.75998Z","end":"2026-05-15T08:10:00.069158Z","steps":["trace[925418126] 'agreement among raft nodes before linearized reading' (duration: 309.058836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:00.069228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:59.759976Z","time spent":"309.235746ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-15T08:10:00.069001Z","caller":"traceutil/trace.go:171","msg":"trace[157324073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1961; }","duration":"309.707731ms","start":"2026-05-15T08:09:59.759265Z","end":"2026-05-15T08:10:00.068973Z","steps":["trace[157324073] 'agreement among raft nodes before linearized reading' (duration: 309.396065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:00.069298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:59.759254Z","time spent":"310.034029ms","remote":"127.0.0.1:39624","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-15T08:10:00.069011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:09:59.760089Z","time spent":"308.914429ms","remote":"127.0.0.1:39624","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-15T08:10:00.069354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.687843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:10:00.069383Z","caller":"traceutil/trace.go:171","msg":"trace[1885334195] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1961; }","duration":"292.750215ms","start":"2026-05-15T08:09:59.776621Z","end":"2026-05-15T08:10:00.069372Z","steps":["trace[1885334195] 'agreement among raft nodes before linearized reading' (duration: 292.704763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.561852Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755373660278490880,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T08:10:06.574394Z","caller":"traceutil/trace.go:171","msg":"trace[936623545] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"561.347824ms","start":"2026-05-15T08:10:06.013015Z","end":"2026-05-15T08:10:06.574363Z","steps":["trace[936623545] 'process raft request' (duration: 561.179104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.574628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.01297Z","time spent":"561.499533ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:10:06.636247Z","caller":"traceutil/trace.go:171","msg":"trace[329125296] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2080; }","duration":"575.465896ms","start":"2026-05-15T08:10:06.060759Z","end":"2026-05-15T08:10:06.636225Z","steps":["trace[329125296] 'read index received' (duration: 513.565211ms)","trace[329125296] 'applied index is now lower than readState.Index' (duration: 61.899465ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:06.636386Z","caller":"traceutil/trace.go:171","msg":"trace[339263825] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"544.219152ms","start":"2026-05-15T08:10:06.092133Z","end":"2026-05-15T08:10:06.636352Z","steps":["trace[339263825] 'process raft request' (duration: 543.969139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.636556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.092113Z","time spent":"544.360131ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:10:06.63659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.796553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11980"} {"level":"warn","ts":"2026-05-15T08:10:06.636618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.721233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-15T08:10:06.636632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.500967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-15T08:10:06.636694Z","caller":"traceutil/trace.go:171","msg":"trace[1036112252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2008; }","duration":"514.827917ms","start":"2026-05-15T08:10:06.121847Z","end":"2026-05-15T08:10:06.636675Z","steps":["trace[1036112252] 'agreement among raft nodes before linearized reading' (duration: 514.626218ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:06.636703Z","caller":"traceutil/trace.go:171","msg":"trace[1097120373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2008; }","duration":"513.612373ms","start":"2026-05-15T08:10:06.123077Z","end":"2026-05-15T08:10:06.636689Z","steps":["trace[1097120373] 'agreement among raft nodes before linearized reading' (duration: 513.37885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.636426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.048383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T08:10:06.636826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.86155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-15T08:10:06.636834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.123047Z","time spent":"513.774642ms","remote":"127.0.0.1:39624","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":"info","ts":"2026-05-15T08:10:06.636858Z","caller":"traceutil/trace.go:171","msg":"trace[1711841148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2008; }","duration":"514.959054ms","start":"2026-05-15T08:10:06.121891Z","end":"2026-05-15T08:10:06.63685Z","steps":["trace[1711841148] 'agreement among raft nodes before linearized reading' (duration: 514.871389ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:06.63686Z","caller":"traceutil/trace.go:171","msg":"trace[297505284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2008; }","duration":"544.510068ms","start":"2026-05-15T08:10:06.092338Z","end":"2026-05-15T08:10:06.636848Z","steps":["trace[297505284] 'agreement among raft nodes before linearized reading' (duration: 544.041023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.636884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.121866Z","time spent":"515.011407ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-15T08:10:06.636631Z","caller":"traceutil/trace.go:171","msg":"trace[2136750167] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2008; }","duration":"575.863647ms","start":"2026-05-15T08:10:06.060754Z","end":"2026-05-15T08:10:06.636617Z","steps":["trace[2136750167] 'agreement among raft nodes before linearized reading' (duration: 575.568021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.636741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.121836Z","time spent":"514.895691ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-15T08:10:06.636926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.317791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T08:10:06.637015Z","caller":"traceutil/trace.go:171","msg":"trace[433559172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2008; }","duration":"517.425566ms","start":"2026-05-15T08:10:06.119575Z","end":"2026-05-15T08:10:06.637Z","steps":["trace[433559172] 'agreement among raft nodes before linearized reading' (duration: 517.185283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.637057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.092331Z","time spent":"544.55888ms","remote":"127.0.0.1:39390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T08:10:06.637059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.822393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-15T08:10:06.637089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.119566Z","time spent":"517.51226ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-15T08:10:06.637136Z","caller":"traceutil/trace.go:171","msg":"trace[446357166] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2008; }","duration":"525.919239ms","start":"2026-05-15T08:10:06.111205Z","end":"2026-05-15T08:10:06.637125Z","steps":["trace[446357166] 'agreement among raft nodes before linearized reading' (duration: 525.658425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.636932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.060702Z","time spent":"576.221966ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12003,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-15T08:10:06.637165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.111194Z","time spent":"525.962691ms","remote":"127.0.0.1:46950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-15T08:10:06.637397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.782862ms","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-15T08:10:06.637499Z","caller":"traceutil/trace.go:171","msg":"trace[1423167972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2008; }","duration":"514.464678ms","start":"2026-05-15T08:10:06.123004Z","end":"2026-05-15T08:10:06.637469Z","steps":["trace[1423167972] 'agreement among raft nodes before linearized reading' (duration: 513.74685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.63756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:06.122995Z","time spent":"514.547432ms","remote":"127.0.0.1:39624","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-15T08:10:06.801628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.315781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:10:06.801781Z","caller":"traceutil/trace.go:171","msg":"trace[604716538] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"158.870128ms","start":"2026-05-15T08:10:06.642884Z","end":"2026-05-15T08:10:06.801754Z","steps":["trace[604716538] 'process raft request' (duration: 27.299434ms)","trace[604716538] 'compare' (duration: 131.127981ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:06.877891Z","caller":"traceutil/trace.go:171","msg":"trace[830870136] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"234.695074ms","start":"2026-05-15T08:10:06.643174Z","end":"2026-05-15T08:10:06.877869Z","steps":["trace[830870136] 'process raft request' (duration: 234.575598ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:06.878294Z","caller":"traceutil/trace.go:171","msg":"trace[632529848] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"235.000341ms","start":"2026-05-15T08:10:06.643285Z","end":"2026-05-15T08:10:06.878285Z","steps":["trace[632529848] 'process raft request' (duration: 234.556927ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:06.878388Z","caller":"traceutil/trace.go:171","msg":"trace[1023083383] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"234.947899ms","start":"2026-05-15T08:10:06.643426Z","end":"2026-05-15T08:10:06.878374Z","steps":["trace[1023083383] 'process raft request' (duration: 234.858274ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:06.878444Z","caller":"traceutil/trace.go:171","msg":"trace[225453113] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"235.074824ms","start":"2026-05-15T08:10:06.643363Z","end":"2026-05-15T08:10:06.878438Z","steps":["trace[225453113] 'process raft request' (duration: 234.825751ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:06.878401Z","caller":"traceutil/trace.go:171","msg":"trace[365906019] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2082; }","duration":"232.234493ms","start":"2026-05-15T08:10:06.646116Z","end":"2026-05-15T08:10:06.878351Z","steps":["trace[365906019] 'read index received' (duration: 24.080712ms)","trace[365906019] 'applied index is now lower than readState.Index' (duration: 208.152941ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:06.878596Z","caller":"traceutil/trace.go:171","msg":"trace[1237249021] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"221.320273ms","start":"2026-05-15T08:10:06.657264Z","end":"2026-05-15T08:10:06.878584Z","steps":["trace[1237249021] 'process raft request' (duration: 221.051838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:06.878705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.568432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:10:06.878772Z","caller":"traceutil/trace.go:171","msg":"trace[1321639118] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2014; }","duration":"232.677247ms","start":"2026-05-15T08:10:06.646086Z","end":"2026-05-15T08:10:06.878764Z","steps":["trace[1321639118] 'agreement among raft nodes before linearized reading' (duration: 232.55061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:07.029418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.401716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:10:07.029549Z","caller":"traceutil/trace.go:171","msg":"trace[695581087] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"150.99219ms","start":"2026-05-15T08:10:06.878534Z","end":"2026-05-15T08:10:07.029527Z","steps":["trace[695581087] 'read index received' (duration: 44.341771ms)","trace[695581087] 'applied index is now lower than readState.Index' (duration: 106.649069ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:07.029972Z","caller":"traceutil/trace.go:171","msg":"trace[861917314] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"238.14201ms","start":"2026-05-15T08:10:06.79168Z","end":"2026-05-15T08:10:07.029822Z","steps":["trace[861917314] 'process raft request' (duration: 131.252007ms)","trace[861917314] 'compare' (duration: 106.205227ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:10:07.0301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.246009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-15T08:10:07.030173Z","caller":"traceutil/trace.go:171","msg":"trace[1455586910] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2015; }","duration":"159.361786ms","start":"2026-05-15T08:10:06.870791Z","end":"2026-05-15T08:10:07.030153Z","steps":["trace[1455586910] 'agreement among raft nodes before linearized reading' (duration: 159.158405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:07.030399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.053617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:10:07.030467Z","caller":"traceutil/trace.go:171","msg":"trace[2013885495] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2015; }","duration":"149.148132ms","start":"2026-05-15T08:10:06.881301Z","end":"2026-05-15T08:10:07.030449Z","steps":["trace[2013885495] 'agreement among raft nodes before linearized reading' (duration: 149.031916ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:11.020693Z","caller":"traceutil/trace.go:171","msg":"trace[2091090735] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"431.345041ms","start":"2026-05-15T08:10:10.589329Z","end":"2026-05-15T08:10:11.020674Z","steps":["trace[2091090735] 'process raft request' (duration: 431.216415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:11.020821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:10.589305Z","time spent":"431.450437ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:10:11.112066Z","caller":"traceutil/trace.go:171","msg":"trace[90289106] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"220.546431ms","start":"2026-05-15T08:10:10.891505Z","end":"2026-05-15T08:10:11.112051Z","steps":["trace[90289106] 'process raft request' (duration: 220.489588ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:11.11232Z","caller":"traceutil/trace.go:171","msg":"trace[1838263000] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"462.925805ms","start":"2026-05-15T08:10:10.64927Z","end":"2026-05-15T08:10:11.112196Z","steps":["trace[1838263000] 'process raft request' (duration: 462.060049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:11.112405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:10.649246Z","time spent":"463.099304ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:10:11.112391Z","caller":"traceutil/trace.go:171","msg":"trace[2081282016] linearizableReadLoop","detail":"{readStateIndex:2117; appliedIndex:2115; }","duration":"451.915755ms","start":"2026-05-15T08:10:10.660445Z","end":"2026-05-15T08:10:11.112361Z","steps":["trace[2081282016] 'read index received' (duration: 360.117785ms)","trace[2081282016] 'applied index is now lower than readState.Index' (duration: 91.79514ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:11.112432Z","caller":"traceutil/trace.go:171","msg":"trace[1208203724] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"174.542013ms","start":"2026-05-15T08:10:10.937858Z","end":"2026-05-15T08:10:11.1124Z","steps":["trace[1208203724] 'process raft request' (duration: 174.168953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:11.112568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.122417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-15T08:10:11.112593Z","caller":"traceutil/trace.go:171","msg":"trace[2144070114] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2044; }","duration":"452.19181ms","start":"2026-05-15T08:10:10.660391Z","end":"2026-05-15T08:10:11.112583Z","steps":["trace[2144070114] 'agreement among raft nodes before linearized reading' (duration: 452.089035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:11.112612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:10.66037Z","time spent":"452.237512ms","remote":"127.0.0.1:46950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-15T08:10:11.112836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.173374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T08:10:11.112882Z","caller":"traceutil/trace.go:171","msg":"trace[1912495434] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2045; }","duration":"340.274319ms","start":"2026-05-15T08:10:10.772595Z","end":"2026-05-15T08:10:11.112869Z","steps":["trace[1912495434] 'agreement among raft nodes before linearized reading' (duration: 340.148662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:11.112893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.260925ms","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-15T08:10:11.112931Z","caller":"traceutil/trace.go:171","msg":"trace[962201007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2045; }","duration":"211.299306ms","start":"2026-05-15T08:10:10.901614Z","end":"2026-05-15T08:10:11.112913Z","steps":["trace[962201007] 'agreement among raft nodes before linearized reading' (duration: 211.226873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:11.113045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.650426ms","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-15T08:10:11.113072Z","caller":"traceutil/trace.go:171","msg":"trace[2065917986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2045; }","duration":"211.687528ms","start":"2026-05-15T08:10:10.901377Z","end":"2026-05-15T08:10:11.113065Z","steps":["trace[2065917986] 'agreement among raft nodes before linearized reading' (duration: 211.567332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:11.113252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.110811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T08:10:11.113276Z","caller":"traceutil/trace.go:171","msg":"trace[480749379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2045; }","duration":"212.166233ms","start":"2026-05-15T08:10:10.901102Z","end":"2026-05-15T08:10:11.113269Z","steps":["trace[480749379] 'agreement among raft nodes before linearized reading' (duration: 212.095539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:11.113401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.21572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T08:10:11.113423Z","caller":"traceutil/trace.go:171","msg":"trace[912247209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2045; }","duration":"216.294964ms","start":"2026-05-15T08:10:10.897122Z","end":"2026-05-15T08:10:11.113417Z","steps":["trace[912247209] 'agreement among raft nodes before linearized reading' (duration: 216.214829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:11.112923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:10.772572Z","time spent":"340.340233ms","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-15T08:10:11.113526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.61189ms","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-15T08:10:11.113553Z","caller":"traceutil/trace.go:171","msg":"trace[98441803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2045; }","duration":"297.660202ms","start":"2026-05-15T08:10:10.815885Z","end":"2026-05-15T08:10:11.113545Z","steps":["trace[98441803] 'agreement among raft nodes before linearized reading' (duration: 297.596739ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:33.384288Z","caller":"traceutil/trace.go:171","msg":"trace[1367905800] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"282.609863ms","start":"2026-05-15T08:10:33.101658Z","end":"2026-05-15T08:10:33.384267Z","steps":["trace[1367905800] 'process raft request' (duration: 282.452504ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:33.39678Z","caller":"traceutil/trace.go:171","msg":"trace[246973140] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"140.030678ms","start":"2026-05-15T08:10:33.256515Z","end":"2026-05-15T08:10:33.396546Z","steps":["trace[246973140] 'process raft request' (duration: 139.719431ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:33.397297Z","caller":"traceutil/trace.go:171","msg":"trace[948591807] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2243; }","duration":"123.856837ms","start":"2026-05-15T08:10:33.273389Z","end":"2026-05-15T08:10:33.397246Z","steps":["trace[948591807] 'read index received' (duration: 110.811798ms)","trace[948591807] 'applied index is now lower than readState.Index' (duration: 13.043499ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:33.39769Z","caller":"traceutil/trace.go:171","msg":"trace[76793045] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"139.253634ms","start":"2026-05-15T08:10:33.2584Z","end":"2026-05-15T08:10:33.397654Z","steps":["trace[76793045] 'process raft request' (duration: 137.873769ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:33.397748Z","caller":"traceutil/trace.go:171","msg":"trace[915859574] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"194.622995ms","start":"2026-05-15T08:10:33.201736Z","end":"2026-05-15T08:10:33.396359Z","steps":["trace[915859574] 'process raft request' (duration: 194.387303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:33.398397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.99731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T08:10:33.398477Z","caller":"traceutil/trace.go:171","msg":"trace[1941743293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2167; }","duration":"125.107224ms","start":"2026-05-15T08:10:33.273336Z","end":"2026-05-15T08:10:33.398443Z","steps":["trace[1941743293] 'agreement among raft nodes before linearized reading' (duration: 124.756385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:33.398562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.493734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-15T08:10:33.398627Z","caller":"traceutil/trace.go:171","msg":"trace[505198614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2167; }","duration":"120.60205ms","start":"2026-05-15T08:10:33.278008Z","end":"2026-05-15T08:10:33.39861Z","steps":["trace[505198614] 'agreement among raft nodes before linearized reading' (duration: 119.746883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:33.398893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.943003ms","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-15T08:10:33.398971Z","caller":"traceutil/trace.go:171","msg":"trace[819898882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2167; }","duration":"120.040449ms","start":"2026-05-15T08:10:33.278916Z","end":"2026-05-15T08:10:33.398956Z","steps":["trace[819898882] 'agreement among raft nodes before linearized reading' (duration: 119.857849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:33.399039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.042299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-15T08:10:33.399116Z","caller":"traceutil/trace.go:171","msg":"trace[1132834484] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2167; }","duration":"113.126163ms","start":"2026-05-15T08:10:33.285958Z","end":"2026-05-15T08:10:33.399084Z","steps":["trace[1132834484] 'agreement among raft nodes before linearized reading' (duration: 112.816836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:33.399119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.796476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-15T08:10:33.398946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.600485ms","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-15T08:10:33.399264Z","caller":"traceutil/trace.go:171","msg":"trace[2124297396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2167; }","duration":"113.448011ms","start":"2026-05-15T08:10:33.285795Z","end":"2026-05-15T08:10:33.399243Z","steps":["trace[2124297396] 'agreement among raft nodes before linearized reading' (duration: 112.104548ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:33.399189Z","caller":"traceutil/trace.go:171","msg":"trace[2114093301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2167; }","duration":"119.909223ms","start":"2026-05-15T08:10:33.279252Z","end":"2026-05-15T08:10:33.399161Z","steps":["trace[2114093301] 'agreement among raft nodes before linearized reading' (duration: 119.486509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:33.653291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.960163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:10:33.653502Z","caller":"traceutil/trace.go:171","msg":"trace[1881277469] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"245.772708ms","start":"2026-05-15T08:10:33.407714Z","end":"2026-05-15T08:10:33.653487Z","steps":["trace[1881277469] 'process raft request' (duration: 245.716166ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:33.653747Z","caller":"traceutil/trace.go:171","msg":"trace[2105953885] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"245.852743ms","start":"2026-05-15T08:10:33.407867Z","end":"2026-05-15T08:10:33.653719Z","steps":["trace[2105953885] 'process raft request' (duration: 245.764948ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:33.653812Z","caller":"traceutil/trace.go:171","msg":"trace[987958461] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"246.170489ms","start":"2026-05-15T08:10:33.407635Z","end":"2026-05-15T08:10:33.653806Z","steps":["trace[987958461] 'process raft request' (duration: 245.752746ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:33.653786Z","caller":"traceutil/trace.go:171","msg":"trace[1776400760] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"246.281106ms","start":"2026-05-15T08:10:33.407492Z","end":"2026-05-15T08:10:33.653773Z","steps":["trace[1776400760] 'process raft request' (duration: 112.758243ms)","trace[1776400760] 'compare' (duration: 132.834276ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:33.653957Z","caller":"traceutil/trace.go:171","msg":"trace[1632383117] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"246.138249ms","start":"2026-05-15T08:10:33.407807Z","end":"2026-05-15T08:10:33.653945Z","steps":["trace[1632383117] 'process raft request' (duration: 245.652672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:34.139268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.730203ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-15T08:10:34.139369Z","caller":"traceutil/trace.go:171","msg":"trace[1787672172] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"111.480417ms","start":"2026-05-15T08:10:34.02787Z","end":"2026-05-15T08:10:34.13935Z","steps":["trace[1787672172] 'read index received' (duration: 10.570885ms)","trace[1787672172] 'applied index is now lower than readState.Index' (duration: 100.908032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:10:34.139508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.598805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T08:10:34.139683Z","caller":"traceutil/trace.go:171","msg":"trace[33115432] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2172; }","duration":"111.839767ms","start":"2026-05-15T08:10:34.027814Z","end":"2026-05-15T08:10:34.139654Z","steps":["trace[33115432] 'agreement among raft nodes before linearized reading' (duration: 111.584402ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:35.014961Z","caller":"traceutil/trace.go:171","msg":"trace[1819717461] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"117.679245ms","start":"2026-05-15T08:10:34.897256Z","end":"2026-05-15T08:10:35.014935Z","steps":["trace[1819717461] 'process raft request' (duration: 117.505475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:35.256835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.246114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:10:35.256937Z","caller":"traceutil/trace.go:171","msg":"trace[1148381024] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"166.097506ms","start":"2026-05-15T08:10:35.090824Z","end":"2026-05-15T08:10:35.256922Z","steps":["trace[1148381024] 'read index received' (duration: 28.588117ms)","trace[1148381024] 'applied index is now lower than readState.Index' (duration: 137.508299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:10:35.256992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.171539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:10:35.257016Z","caller":"traceutil/trace.go:171","msg":"trace[1268072616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2174; }","duration":"166.222601ms","start":"2026-05-15T08:10:35.090785Z","end":"2026-05-15T08:10:35.257007Z","steps":["trace[1268072616] 'agreement among raft nodes before linearized reading' (duration: 166.17894ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:35.257017Z","caller":"traceutil/trace.go:171","msg":"trace[1760174287] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"282.467ms","start":"2026-05-15T08:10:34.97453Z","end":"2026-05-15T08:10:35.256997Z","steps":["trace[1760174287] 'process raft request' (duration: 144.984214ms)","trace[1760174287] 'compare' (duration: 137.023081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:10:36.822204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005052569s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:10:36.822467Z","caller":"traceutil/trace.go:171","msg":"trace[824181663] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2255; }","duration":"1.410273016s","start":"2026-05-15T08:10:35.412176Z","end":"2026-05-15T08:10:36.822449Z","steps":["trace[824181663] 'read index received' (duration: 404.833986ms)","trace[824181663] 'applied index is now lower than readState.Index' (duration: 1.00543805s)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:36.822618Z","caller":"traceutil/trace.go:171","msg":"trace[581259463] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"1.414423522s","start":"2026-05-15T08:10:35.408159Z","end":"2026-05-15T08:10:36.822583Z","steps":["trace[581259463] 'process raft request' (duration: 1.414245643s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.822774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410604564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-15T08:10:36.822816Z","caller":"traceutil/trace.go:171","msg":"trace[502803267] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"1.410664187s","start":"2026-05-15T08:10:35.412142Z","end":"2026-05-15T08:10:36.822806Z","steps":["trace[502803267] 'agreement among raft nodes before linearized reading' (duration: 1.410539721s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.822804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.408149Z","time spent":"1.414561729s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:10:36.822851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.412133Z","time spent":"1.41070962s","remote":"127.0.0.1:46950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-15T08:10:36.823074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39546141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18afaed7f7b6be51\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-15T08:10:36.823084Z","caller":"traceutil/trace.go:171","msg":"trace[519461916] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"1.433801028s","start":"2026-05-15T08:10:35.389261Z","end":"2026-05-15T08:10:36.823062Z","steps":["trace[519461916] 'process raft request' (duration: 427.818398ms)","trace[519461916] 'compare' (duration: 1.004868589s)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:36.823111Z","caller":"traceutil/trace.go:171","msg":"trace[332967063] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18afaed7f7b6be51; range_end:; response_count:1; response_revision:2178; }","duration":"1.395537474s","start":"2026-05-15T08:10:35.427562Z","end":"2026-05-15T08:10:36.8231Z","steps":["trace[332967063] 'agreement among raft nodes before linearized reading' (duration: 1.395450369s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.823141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.427541Z","time spent":"1.395592546s","remote":"127.0.0.1:39472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18afaed7f7b6be51\" "} {"level":"warn","ts":"2026-05-15T08:10:36.823175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.389234Z","time spent":"1.433893783s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:10:36.823291Z","caller":"traceutil/trace.go:171","msg":"trace[65636270] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"1.418978391s","start":"2026-05-15T08:10:35.404301Z","end":"2026-05-15T08:10:36.82328Z","steps":["trace[65636270] 'process raft request' (duration: 1.417994427s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.823322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164447538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T08:10:36.823348Z","caller":"traceutil/trace.go:171","msg":"trace[1372586217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2178; }","duration":"1.16448964s","start":"2026-05-15T08:10:35.65885Z","end":"2026-05-15T08:10:36.82334Z","steps":["trace[1372586217] 'agreement among raft nodes before linearized reading' (duration: 1.164415667s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.823352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.40428Z","time spent":"1.419042724s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:10:36.823374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.658842Z","time spent":"1.164525573s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-15T08:10:36.823477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165454843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T08:10:36.823505Z","caller":"traceutil/trace.go:171","msg":"trace[2052501588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2178; }","duration":"1.165504537s","start":"2026-05-15T08:10:35.657994Z","end":"2026-05-15T08:10:36.823499Z","steps":["trace[2052501588] 'agreement among raft nodes before linearized reading' (duration: 1.165434262s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.82353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.657984Z","time spent":"1.165541608s","remote":"127.0.0.1:39624","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-15T08:10:36.823634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166197404s","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-15T08:10:36.823661Z","caller":"traceutil/trace.go:171","msg":"trace[2085964705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2178; }","duration":"1.166243197s","start":"2026-05-15T08:10:35.657411Z","end":"2026-05-15T08:10:36.823655Z","steps":["trace[2085964705] 'agreement among raft nodes before linearized reading' (duration: 1.166173433s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.823685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.6574Z","time spent":"1.166280249s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-15T08:10:36.82362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164362604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-15T08:10:36.823783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166349332s","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-15T08:10:36.82381Z","caller":"traceutil/trace.go:171","msg":"trace[1168751507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2178; }","duration":"1.166412936s","start":"2026-05-15T08:10:35.65739Z","end":"2026-05-15T08:10:36.823803Z","steps":["trace[1168751507] 'agreement among raft nodes before linearized reading' (duration: 1.166349042s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.823833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.657369Z","time spent":"1.166459068s","remote":"127.0.0.1:39624","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-15T08:10:36.823881Z","caller":"traceutil/trace.go:171","msg":"trace[680041929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2178; }","duration":"1.164619158s","start":"2026-05-15T08:10:35.659166Z","end":"2026-05-15T08:10:36.823786Z","steps":["trace[680041929] 'agreement among raft nodes before linearized reading' (duration: 1.16409881s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.82398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.659138Z","time spent":"1.16482919s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-15T08:10:36.824035Z","caller":"traceutil/trace.go:171","msg":"trace[696269900] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"1.416773631s","start":"2026-05-15T08:10:35.407251Z","end":"2026-05-15T08:10:36.824025Z","steps":["trace[696269900] 'process raft request' (duration: 1.415120891s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.824108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:35.407232Z","time spent":"1.416843065s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:10:36.824269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.077691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:10:36.824342Z","caller":"traceutil/trace.go:171","msg":"trace[573221990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2178; }","duration":"732.158925ms","start":"2026-05-15T08:10:36.092162Z","end":"2026-05-15T08:10:36.824321Z","steps":["trace[573221990] 'agreement among raft nodes before linearized reading' (duration: 732.050799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:36.824391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:36.092103Z","time spent":"732.279561ms","remote":"127.0.0.1:39390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T08:10:37.670743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.732627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:10:37.671116Z","caller":"traceutil/trace.go:171","msg":"trace[66336062] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2259; }","duration":"679.379471ms","start":"2026-05-15T08:10:36.99172Z","end":"2026-05-15T08:10:37.6711Z","steps":["trace[66336062] 'read index received' (duration: 168.202219ms)","trace[66336062] 'applied index is now lower than readState.Index' (duration: 511.176342ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:10:37.671233Z","caller":"traceutil/trace.go:171","msg":"trace[1726344720] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"843.774299ms","start":"2026-05-15T08:10:36.827447Z","end":"2026-05-15T08:10:37.671221Z","steps":["trace[1726344720] 'process raft request' (duration: 332.465291ms)","trace[1726344720] 'compare' (duration: 510.355856ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:10:37.671307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:36.827434Z","time spent":"843.836972ms","remote":"127.0.0.1:39472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:10:37.671691Z","caller":"traceutil/trace.go:171","msg":"trace[1056288793] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"842.677899ms","start":"2026-05-15T08:10:36.828999Z","end":"2026-05-15T08:10:37.671677Z","steps":["trace[1056288793] 'process raft request' (duration: 841.852604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:37.671763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:36.82899Z","time spent":"842.736942ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:10:37.671794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.044157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T08:10:37.671836Z","caller":"traceutil/trace.go:171","msg":"trace[1387132977] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"842.626816ms","start":"2026-05-15T08:10:36.829178Z","end":"2026-05-15T08:10:37.671805Z","steps":["trace[1387132977] 'process raft request' (duration: 841.821473ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:37.671967Z","caller":"traceutil/trace.go:171","msg":"trace[1648566081] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"842.663539ms","start":"2026-05-15T08:10:36.829292Z","end":"2026-05-15T08:10:37.671955Z","steps":["trace[1648566081] 'process raft request' (duration: 841.739369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:37.672081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:36.829286Z","time spent":"842.715471ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:10:37.671998Z","caller":"traceutil/trace.go:171","msg":"trace[1962906383] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"842.727122ms","start":"2026-05-15T08:10:36.829145Z","end":"2026-05-15T08:10:37.671872Z","steps":["trace[1962906383] 'process raft request' (duration: 841.819403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:37.672258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.389614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:10:37.672282Z","caller":"traceutil/trace.go:171","msg":"trace[1930269520] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"842.548062ms","start":"2026-05-15T08:10:36.829726Z","end":"2026-05-15T08:10:37.672274Z","steps":["trace[1930269520] 'process raft request' (duration: 841.337526ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:10:37.672307Z","caller":"traceutil/trace.go:171","msg":"trace[204365736] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2184; }","duration":"204.473989ms","start":"2026-05-15T08:10:37.46782Z","end":"2026-05-15T08:10:37.672294Z","steps":["trace[204365736] 'agreement among raft nodes before linearized reading' (duration: 204.407065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:37.672322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:36.829717Z","time spent":"842.584594ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:10:37.672517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.608857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12384"} {"level":"warn","ts":"2026-05-15T08:10:37.672512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:36.82914Z","time spent":"843.124054ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:10:37.672565Z","caller":"traceutil/trace.go:171","msg":"trace[575883102] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2184; }","duration":"483.692912ms","start":"2026-05-15T08:10:37.18886Z","end":"2026-05-15T08:10:37.672553Z","steps":["trace[575883102] 'agreement among raft nodes before linearized reading' (duration: 483.554175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:37.672624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:37.188835Z","time spent":"483.781327ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12407,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-15T08:10:37.672062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:36.829169Z","time spent":"842.787075ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:10:37.67192Z","caller":"traceutil/trace.go:171","msg":"trace[1730734841] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2184; }","duration":"680.213077ms","start":"2026-05-15T08:10:36.991683Z","end":"2026-05-15T08:10:37.671896Z","steps":["trace[1730734841] 'agreement among raft nodes before linearized reading' (duration: 680.026787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:37.672762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:36.991664Z","time spent":"681.085914ms","remote":"127.0.0.1:39868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-15T08:10:37.672137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.097631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-15T08:10:37.672882Z","caller":"traceutil/trace.go:171","msg":"trace[404505454] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2184; }","duration":"603.872815ms","start":"2026-05-15T08:10:37.068996Z","end":"2026-05-15T08:10:37.672868Z","steps":["trace[404505454] 'agreement among raft nodes before linearized reading' (duration: 603.04637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:10:37.67292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:10:37.068978Z","time spent":"603.932258ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-15T08:12:00.67475Z","caller":"traceutil/trace.go:171","msg":"trace[2039672339] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"106.185393ms","start":"2026-05-15T08:12:00.568537Z","end":"2026-05-15T08:12:00.674723Z","steps":["trace[2039672339] 'process raft request' (duration: 105.97109ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:34.541566Z","caller":"traceutil/trace.go:171","msg":"trace[1745786958] linearizableReadLoop","detail":"{readStateIndex:4695; appliedIndex:4689; }","duration":"104.292305ms","start":"2026-05-15T08:15:34.437252Z","end":"2026-05-15T08:15:34.541544Z","steps":["trace[1745786958] 'read index received' (duration: 5.896726ms)","trace[1745786958] 'applied index is now lower than readState.Index' (duration: 98.394699ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:15:34.541606Z","caller":"traceutil/trace.go:171","msg":"trace[856054554] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"111.567511ms","start":"2026-05-15T08:15:34.430003Z","end":"2026-05-15T08:15:34.54157Z","steps":["trace[856054554] 'process raft request' (duration: 111.287629ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:34.541664Z","caller":"traceutil/trace.go:171","msg":"trace[1954205291] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"111.866063ms","start":"2026-05-15T08:15:34.429789Z","end":"2026-05-15T08:15:34.541655Z","steps":["trace[1954205291] 'process raft request' (duration: 105.489176ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:34.541815Z","caller":"traceutil/trace.go:171","msg":"trace[987082181] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"110.667343ms","start":"2026-05-15T08:15:34.431137Z","end":"2026-05-15T08:15:34.541804Z","steps":["trace[987082181] 'process raft request' (duration: 110.182442ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:34.541975Z","caller":"traceutil/trace.go:171","msg":"trace[2135522595] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"112.004419ms","start":"2026-05-15T08:15:34.429963Z","end":"2026-05-15T08:15:34.541967Z","steps":["trace[2135522595] 'process raft request' (duration: 111.291878ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:34.542056Z","caller":"traceutil/trace.go:171","msg":"trace[1259801327] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"104.561446ms","start":"2026-05-15T08:15:34.437463Z","end":"2026-05-15T08:15:34.542024Z","steps":["trace[1259801327] 'process raft request' (duration: 103.888957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:34.542165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.8975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-15T08:15:34.542193Z","caller":"traceutil/trace.go:171","msg":"trace[320648097] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4526; }","duration":"104.957803ms","start":"2026-05-15T08:15:34.437229Z","end":"2026-05-15T08:15:34.542187Z","steps":["trace[320648097] 'agreement among raft nodes before linearized reading' (duration: 104.834918ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:35.440103Z","caller":"traceutil/trace.go:171","msg":"trace[525038733] transaction","detail":"{read_only:false; response_revision:4545; number_of_response:1; }","duration":"132.85152ms","start":"2026-05-15T08:15:35.307222Z","end":"2026-05-15T08:15:35.440073Z","steps":["trace[525038733] 'process raft request' (duration: 132.667333ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:35.524215Z","caller":"traceutil/trace.go:171","msg":"trace[1052805623] transaction","detail":"{read_only:false; response_revision:4546; number_of_response:1; }","duration":"208.5221ms","start":"2026-05-15T08:15:35.315665Z","end":"2026-05-15T08:15:35.524187Z","steps":["trace[1052805623] 'process raft request' (duration: 208.344733ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:40.935491Z","caller":"traceutil/trace.go:171","msg":"trace[2046824201] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"215.44251ms","start":"2026-05-15T08:15:40.720018Z","end":"2026-05-15T08:15:40.93546Z","steps":["trace[2046824201] 'process raft request' (duration: 215.20452ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:41.109515Z","caller":"traceutil/trace.go:171","msg":"trace[1418680506] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"124.332014ms","start":"2026-05-15T08:15:40.98515Z","end":"2026-05-15T08:15:41.109482Z","steps":["trace[1418680506] 'process raft request' (duration: 118.095269ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:41.145401Z","caller":"traceutil/trace.go:171","msg":"trace[768890062] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"159.394098ms","start":"2026-05-15T08:15:40.985982Z","end":"2026-05-15T08:15:41.145376Z","steps":["trace[768890062] 'process raft request' (duration: 159.22363ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:41.145455Z","caller":"traceutil/trace.go:171","msg":"trace[391772944] linearizableReadLoop","detail":"{readStateIndex:4805; appliedIndex:4801; }","duration":"103.529274ms","start":"2026-05-15T08:15:41.041909Z","end":"2026-05-15T08:15:41.145438Z","steps":["trace[391772944] 'read index received' (duration: 61.363778ms)","trace[391772944] 'applied index is now lower than readState.Index' (duration: 42.164816ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:15:41.145507Z","caller":"traceutil/trace.go:171","msg":"trace[509249268] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"159.194279ms","start":"2026-05-15T08:15:40.986297Z","end":"2026-05-15T08:15:41.145491Z","steps":["trace[509249268] 'process raft request' (duration: 158.99886ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:41.145556Z","caller":"traceutil/trace.go:171","msg":"trace[672155167] transaction","detail":"{read_only:false; response_revision:4632; number_of_response:1; }","duration":"157.649404ms","start":"2026-05-15T08:15:40.987899Z","end":"2026-05-15T08:15:41.145549Z","steps":["trace[672155167] 'process raft request' (duration: 157.456155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:41.145749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.834866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:15:41.14579Z","caller":"traceutil/trace.go:171","msg":"trace[1403328629] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4632; }","duration":"103.93571ms","start":"2026-05-15T08:15:41.041846Z","end":"2026-05-15T08:15:41.145782Z","steps":["trace[1403328629] 'agreement among raft nodes before linearized reading' (duration: 103.843676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:41.566504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.297524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-15T08:15:41.566578Z","caller":"traceutil/trace.go:171","msg":"trace[1148161047] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4660; }","duration":"125.389088ms","start":"2026-05-15T08:15:41.441171Z","end":"2026-05-15T08:15:41.56656Z","steps":["trace[1148161047] 'agreement among raft nodes before linearized reading' (duration: 125.21021ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:41.824349Z","caller":"traceutil/trace.go:171","msg":"trace[197341118] linearizableReadLoop","detail":"{readStateIndex:4838; appliedIndex:4835; }","duration":"176.170017ms","start":"2026-05-15T08:15:41.648157Z","end":"2026-05-15T08:15:41.824327Z","steps":["trace[197341118] 'read index received' (duration: 143.87223ms)","trace[197341118] 'applied index is now lower than readState.Index' (duration: 32.296947ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:15:41.824534Z","caller":"traceutil/trace.go:171","msg":"trace[2045156467] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"217.124602ms","start":"2026-05-15T08:15:41.607392Z","end":"2026-05-15T08:15:41.824517Z","steps":["trace[2045156467] 'process raft request' (duration: 184.624166ms)","trace[2045156467] 'compare' (duration: 31.931812ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:15:41.824598Z","caller":"traceutil/trace.go:171","msg":"trace[55045017] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"217.106601ms","start":"2026-05-15T08:15:41.607467Z","end":"2026-05-15T08:15:41.824573Z","steps":["trace[55045017] 'process raft request' (duration: 216.719774ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:41.82476Z","caller":"traceutil/trace.go:171","msg":"trace[2109658349] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"217.162373ms","start":"2026-05-15T08:15:41.607567Z","end":"2026-05-15T08:15:41.824729Z","steps":["trace[2109658349] 'process raft request' (duration: 216.708684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:41.824784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.443886ms","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-15T08:15:41.824878Z","caller":"traceutil/trace.go:171","msg":"trace[606279093] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4665; }","duration":"200.575941ms","start":"2026-05-15T08:15:41.624279Z","end":"2026-05-15T08:15:41.824855Z","steps":["trace[606279093] 'agreement among raft nodes before linearized reading' (duration: 200.265648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:41.824889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.956803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-05-15T08:15:41.825045Z","caller":"traceutil/trace.go:171","msg":"trace[997709372] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4665; }","duration":"216.13844ms","start":"2026-05-15T08:15:41.608886Z","end":"2026-05-15T08:15:41.825024Z","steps":["trace[997709372] 'agreement among raft nodes before linearized reading' (duration: 215.876808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:41.825379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.889044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1189"} {"level":"info","ts":"2026-05-15T08:15:41.825442Z","caller":"traceutil/trace.go:171","msg":"trace[705509587] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"165.188373ms","start":"2026-05-15T08:15:41.660238Z","end":"2026-05-15T08:15:41.825426Z","steps":["trace[705509587] 'process raft request' (duration: 164.772576ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:41.825422Z","caller":"traceutil/trace.go:171","msg":"trace[2016913684] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:4665; }","duration":"217.959657ms","start":"2026-05-15T08:15:41.607451Z","end":"2026-05-15T08:15:41.825411Z","steps":["trace[2016913684] 'agreement among raft nodes before linearized reading' (duration: 216.965865ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:15:49.871814Z","caller":"traceutil/trace.go:171","msg":"trace[420530580] linearizableReadLoop","detail":"{readStateIndex:4995; appliedIndex:4994; }","duration":"359.693345ms","start":"2026-05-15T08:15:49.512091Z","end":"2026-05-15T08:15:49.871784Z","steps":["trace[420530580] 'read index received' (duration: 359.479506ms)","trace[420530580] 'applied index is now lower than readState.Index' (duration: 212.729µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:15:49.87197Z","caller":"traceutil/trace.go:171","msg":"trace[500924396] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"419.182438ms","start":"2026-05-15T08:15:49.452725Z","end":"2026-05-15T08:15:49.871908Z","steps":["trace[500924396] 'process raft request' (duration: 418.905766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:49.872274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:15:49.452706Z","time spent":"419.486122ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13863,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:15:49.872447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.322413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-15T08:15:49.872498Z","caller":"traceutil/trace.go:171","msg":"trace[612982023] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4816; }","duration":"360.442376ms","start":"2026-05-15T08:15:49.512042Z","end":"2026-05-15T08:15:49.872485Z","steps":["trace[612982023] 'agreement among raft nodes before linearized reading' (duration: 360.009178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:49.87255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:15:49.51202Z","time spent":"360.521181ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-15T08:15:55.064713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755373660278497718,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T08:15:55.169481Z","caller":"traceutil/trace.go:171","msg":"trace[643075433] linearizableReadLoop","detail":"{readStateIndex:5047; appliedIndex:5046; }","duration":"605.648446ms","start":"2026-05-15T08:15:54.563808Z","end":"2026-05-15T08:15:55.169457Z","steps":["trace[643075433] 'read index received' (duration: 605.375454ms)","trace[643075433] 'applied index is now lower than readState.Index' (duration: 272.102µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:15:55.169572Z","caller":"traceutil/trace.go:171","msg":"trace[1843013879] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"677.500804ms","start":"2026-05-15T08:15:54.492021Z","end":"2026-05-15T08:15:55.169522Z","steps":["trace[1843013879] 'process raft request' (duration: 677.283985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:55.169751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:15:54.492002Z","time spent":"677.660362ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:15:55.169879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.048782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-15T08:15:55.169992Z","caller":"traceutil/trace.go:171","msg":"trace[991599069] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4867; }","duration":"606.211368ms","start":"2026-05-15T08:15:54.563763Z","end":"2026-05-15T08:15:55.169975Z","steps":["trace[991599069] 'agreement among raft nodes before linearized reading' (duration: 605.920087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:55.170062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:15:54.563747Z","time spent":"606.303944ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-15T08:15:55.170252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.780091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T08:15:55.17033Z","caller":"traceutil/trace.go:171","msg":"trace[105774951] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4867; }","duration":"441.914427ms","start":"2026-05-15T08:15:54.728399Z","end":"2026-05-15T08:15:55.170313Z","steps":["trace[105774951] 'agreement among raft nodes before linearized reading' (duration: 441.489949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:15:55.170369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:15:54.728376Z","time spent":"441.983969ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":32,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-05-15T08:17:25.565963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2873} {"level":"info","ts":"2026-05-15T08:17:25.665875Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2873,"took":"98.732142ms","hash":1378641456,"current-db-size-bytes":54140928,"current-db-size":"54 MB","current-db-size-in-use-bytes":29470720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-15T08:17:25.666223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1378641456,"revision":2873,"compact-revision":-1} {"level":"info","ts":"2026-05-15T08:22:25.572771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5596} {"level":"info","ts":"2026-05-15T08:22:25.678698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5596,"took":"104.365505ms","hash":1278842667,"current-db-size-bytes":54140928,"current-db-size":"54 MB","current-db-size-in-use-bytes":18157568,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-15T08:22:25.678757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1278842667,"revision":5596,"compact-revision":2873}