{"level":"warn","ts":"2026-06-09T13:38:37.642622Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T13:38:37.642699Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-06-09T13:38:37.642757Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T13:38:37.642769Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-06-09T13:38:37.642786Z","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-06-09T13:38:37.64339Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-06-09T13:38:37.643462Z","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.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-06-09T13:38:37.648048Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.381249ms"} {"level":"info","ts":"2026-06-09T13:38:37.658306Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-06-09T13:38:37.658387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T13:38:37.658408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-06-09T13:38:37.658427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T13:38:37.658435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-06-09T13:38:37.658474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-06-09T13:38:37.66462Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T13:38:37.666272Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T13:38:37.667707Z","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-06-09T13:38:37.669977Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T13:38:37.670241Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T13:38:37.671006Z","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-06-09T13:38:37.671146Z","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-06-09T13:38:37.671162Z","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-06-09T13:38:37.672285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-06-09T13:38:37.672618Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-06-09T13:38:37.676107Z","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-06-09T13:38:37.676242Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-09T13:38:37.676285Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-09T13:38:37.676918Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T13:38:37.676826Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T13:38:38.459127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T13:38:38.459225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T13:38:38.459281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-06-09T13:38:38.459307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-06-09T13:38:38.459317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-09T13:38:38.45933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-06-09T13:38:38.459347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-09T13:38:38.460906Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T13:38:38.461145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T13:38:38.46134Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T13:38:38.461502Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T13:38:38.462623Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T13:38:38.462812Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T13:38:38.46285Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T13:38:38.464325Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T13:38:38.464385Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T13:38:38.465509Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T13:38:38.465941Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-06-09T13:39:02.57666Z","caller":"traceutil/trace.go:171","msg":"trace[390863433] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"170.906047ms","start":"2026-06-09T13:39:02.405626Z","end":"2026-06-09T13:39:02.576532Z","steps":["trace[390863433] 'process raft request' (duration: 170.678115ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:10.833341Z","caller":"traceutil/trace.go:171","msg":"trace[589409142] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"149.45853ms","start":"2026-06-09T13:39:10.683863Z","end":"2026-06-09T13:39:10.833321Z","steps":["trace[589409142] 'process raft request' (duration: 106.87855ms)","trace[589409142] 'compare' (duration: 42.495799ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:39:12.24837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.267096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:39:12.248538Z","caller":"traceutil/trace.go:171","msg":"trace[99718721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"105.491839ms","start":"2026-06-09T13:39:12.14302Z","end":"2026-06-09T13:39:12.248512Z","steps":["trace[99718721] 'range keys from in-memory index tree' (duration: 105.167055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:12.248461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.578072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-09T13:39:12.248888Z","caller":"traceutil/trace.go:171","msg":"trace[1184119936] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:483; }","duration":"485.066957ms","start":"2026-06-09T13:39:11.763804Z","end":"2026-06-09T13:39:12.248871Z","steps":["trace[1184119936] 'range keys from in-memory index tree' (duration: 484.352009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:12.24892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.522885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:39:12.248949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:11.763781Z","time spent":"485.147767ms","remote":"127.0.0.1:47594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-06-09T13:39:12.249029Z","caller":"traceutil/trace.go:171","msg":"trace[1692845460] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:483; }","duration":"468.694296ms","start":"2026-06-09T13:39:11.780308Z","end":"2026-06-09T13:39:12.249002Z","steps":["trace[1692845460] 'range keys from in-memory index tree' (duration: 468.397803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:12.249118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:11.780277Z","time spent":"468.792256ms","remote":"127.0.0.1:47768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-06-09T13:39:12.784437Z","caller":"traceutil/trace.go:171","msg":"trace[1635411787] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"182.105837ms","start":"2026-06-09T13:39:12.602304Z","end":"2026-06-09T13:39:12.78441Z","steps":["trace[1635411787] 'process raft request' (duration: 177.168235ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:12.784658Z","caller":"traceutil/trace.go:171","msg":"trace[1179108481] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"173.854307ms","start":"2026-06-09T13:39:12.610753Z","end":"2026-06-09T13:39:12.784608Z","steps":["trace[1179108481] 'process raft request' (duration: 173.602135ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:12.784854Z","caller":"traceutil/trace.go:171","msg":"trace[278658347] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"152.653315ms","start":"2026-06-09T13:39:12.632136Z","end":"2026-06-09T13:39:12.784789Z","steps":["trace[278658347] 'read index received' (duration: 147.52694ms)","trace[278658347] 'applied index is now lower than readState.Index' (duration: 5.123885ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:39:12.785042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.884556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-06-09T13:39:12.78513Z","caller":"traceutil/trace.go:171","msg":"trace[1319651782] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:514; }","duration":"152.979717ms","start":"2026-06-09T13:39:12.632127Z","end":"2026-06-09T13:39:12.785107Z","steps":["trace[1319651782] 'agreement among raft nodes before linearized reading' (duration: 152.766555ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:12.790965Z","caller":"traceutil/trace.go:171","msg":"trace[586353060] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"139.997675ms","start":"2026-06-09T13:39:12.646564Z","end":"2026-06-09T13:39:12.786562Z","steps":["trace[586353060] 'process raft request' (duration: 138.641883ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:14.263634Z","caller":"traceutil/trace.go:171","msg":"trace[436503240] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"120.134854ms","start":"2026-06-09T13:39:14.143463Z","end":"2026-06-09T13:39:14.263598Z","steps":["trace[436503240] 'read index received' (duration: 119.806342ms)","trace[436503240] 'applied index is now lower than readState.Index' (duration: 327.222µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:39:14.26386Z","caller":"traceutil/trace.go:171","msg":"trace[1146315781] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"154.82471ms","start":"2026-06-09T13:39:14.109022Z","end":"2026-06-09T13:39:14.263847Z","steps":["trace[1146315781] 'process raft request' (duration: 154.333116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.264193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.701589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:39:14.264484Z","caller":"traceutil/trace.go:171","msg":"trace[2034131799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"120.852121ms","start":"2026-06-09T13:39:14.143417Z","end":"2026-06-09T13:39:14.26427Z","steps":["trace[2034131799] 'agreement among raft nodes before linearized reading' (duration: 120.618519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.807085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.003353ms","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-06-09T13:39:14.807203Z","caller":"traceutil/trace.go:171","msg":"trace[268655886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"446.155564ms","start":"2026-06-09T13:39:14.361025Z","end":"2026-06-09T13:39:14.807181Z","steps":["trace[268655886] 'range keys from in-memory index tree' (duration: 445.878432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.80724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.361Z","time spent":"446.231944ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T13:39:14.807357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.031834ms","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-06-09T13:39:14.80745Z","caller":"traceutil/trace.go:171","msg":"trace[1975442765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"438.163426ms","start":"2026-06-09T13:39:14.369263Z","end":"2026-06-09T13:39:14.807426Z","steps":["trace[1975442765] 'range keys from in-memory index tree' (duration: 437.880913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.807511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.369249Z","time spent":"438.247806ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T13:39:14.821121Z","caller":"traceutil/trace.go:171","msg":"trace[785863524] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"343.048846ms","start":"2026-06-09T13:39:14.478054Z","end":"2026-06-09T13:39:14.821103Z","steps":["trace[785863524] 'process raft request' (duration: 342.918205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.822176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.478Z","time spent":"343.192056ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:39:14.827616Z","caller":"traceutil/trace.go:171","msg":"trace[1038357694] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"344.528138ms","start":"2026-06-09T13:39:14.483054Z","end":"2026-06-09T13:39:14.827582Z","steps":["trace[1038357694] 'process raft request' (duration: 344.251425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.827749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.483037Z","time spent":"344.646538ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:39:14.82796Z","caller":"traceutil/trace.go:171","msg":"trace[127575304] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"338.95461ms","start":"2026-06-09T13:39:14.488993Z","end":"2026-06-09T13:39:14.827948Z","steps":["trace[127575304] 'process raft request' (duration: 338.464936ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:14.827933Z","caller":"traceutil/trace.go:171","msg":"trace[732509369] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"327.999316ms","start":"2026-06-09T13:39:14.499907Z","end":"2026-06-09T13:39:14.827906Z","steps":["trace[732509369] 'process raft request' (duration: 327.596553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.828022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.488983Z","time spent":"339.011271ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T13:39:14.828055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.499888Z","time spent":"328.117657ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:39:14.828345Z","caller":"traceutil/trace.go:171","msg":"trace[1433902881] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"311.315894ms","start":"2026-06-09T13:39:14.517017Z","end":"2026-06-09T13:39:14.828332Z","steps":["trace[1433902881] 'process raft request' (duration: 310.634508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.828413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.51701Z","time spent":"311.373224ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:39:14.828594Z","caller":"traceutil/trace.go:171","msg":"trace[1243797239] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"315.561181ms","start":"2026-06-09T13:39:14.513022Z","end":"2026-06-09T13:39:14.828584Z","steps":["trace[1243797239] 'process raft request' (duration: 314.594502ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:14.828626Z","caller":"traceutil/trace.go:171","msg":"trace[1958899995] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"324.004813ms","start":"2026-06-09T13:39:14.504609Z","end":"2026-06-09T13:39:14.828614Z","steps":["trace[1958899995] 'process raft request' (duration: 322.938203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.828684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.504596Z","time spent":"324.060243ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T13:39:14.828654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.513015Z","time spent":"315.614321ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:39:14.828791Z","caller":"traceutil/trace.go:171","msg":"trace[1884012263] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"291.571165ms","start":"2026-06-09T13:39:14.53721Z","end":"2026-06-09T13:39:14.828782Z","steps":["trace[1884012263] 'process raft request' (duration: 290.486056ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:14.828896Z","caller":"traceutil/trace.go:171","msg":"trace[1668501760] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"283.730769ms","start":"2026-06-09T13:39:14.545157Z","end":"2026-06-09T13:39:14.828887Z","steps":["trace[1668501760] 'process raft request' (duration: 282.592919ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:14.829074Z","caller":"traceutil/trace.go:171","msg":"trace[1305907591] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"272.540665ms","start":"2026-06-09T13:39:14.556524Z","end":"2026-06-09T13:39:14.829064Z","steps":["trace[1305907591] 'process raft request' (duration: 271.369754ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:14.829128Z","caller":"traceutil/trace.go:171","msg":"trace[1244284823] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"324.397657ms","start":"2026-06-09T13:39:14.504663Z","end":"2026-06-09T13:39:14.82906Z","steps":["trace[1244284823] 'process raft request' (duration: 322.919124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:14.829462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:14.504659Z","time spent":"324.609008ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:39:14.837803Z","caller":"traceutil/trace.go:171","msg":"trace[433150804] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"186.070027ms","start":"2026-06-09T13:39:14.651711Z","end":"2026-06-09T13:39:14.837781Z","steps":["trace[433150804] 'process raft request' (duration: 185.314051ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:16.467896Z","caller":"traceutil/trace.go:171","msg":"trace[712202112] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"100.52749ms","start":"2026-06-09T13:39:16.367341Z","end":"2026-06-09T13:39:16.467869Z","steps":["trace[712202112] 'process raft request' (duration: 49.690081ms)","trace[712202112] 'compare' (duration: 50.347415ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:39:21.220756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.407514ms","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-06-09T13:39:21.220874Z","caller":"traceutil/trace.go:171","msg":"trace[451353168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"279.558725ms","start":"2026-06-09T13:39:20.941297Z","end":"2026-06-09T13:39:21.220856Z","steps":["trace[451353168] 'range keys from in-memory index tree' (duration: 279.286173ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:21.221523Z","caller":"traceutil/trace.go:171","msg":"trace[2039068347] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"279.246294ms","start":"2026-06-09T13:39:20.942242Z","end":"2026-06-09T13:39:21.221489Z","steps":["trace[2039068347] 'read index received' (duration: 244.505676ms)","trace[2039068347] 'applied index is now lower than readState.Index' (duration: 34.739278ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:39:21.221607Z","caller":"traceutil/trace.go:171","msg":"trace[712026581] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"279.539535ms","start":"2026-06-09T13:39:20.942051Z","end":"2026-06-09T13:39:21.221591Z","steps":["trace[712026581] 'process raft request' (duration: 244.730677ms)","trace[712026581] 'compare' (duration: 33.586658ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:39:21.221761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.501916ms","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-06-09T13:39:21.221799Z","caller":"traceutil/trace.go:171","msg":"trace[1589792897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"279.601116ms","start":"2026-06-09T13:39:20.942186Z","end":"2026-06-09T13:39:21.221788Z","steps":["trace[1589792897] 'agreement among raft nodes before linearized reading' (duration: 279.430965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:21.721974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220399544164236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T13:39:22.222674Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220399544164236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T13:39:22.503123Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.314296567s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T13:39:22.510035Z","caller":"traceutil/trace.go:171","msg":"trace[2009167356] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.522906132s","start":"2026-06-09T13:39:20.987108Z","end":"2026-06-09T13:39:22.510014Z","steps":["trace[2009167356] 'process raft request' (duration: 1.516664485s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:22.51029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:20.987078Z","time spent":"1.523014823s","remote":"127.0.0.1:47654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:39:22.574994Z","caller":"traceutil/trace.go:171","msg":"trace[1285254010] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:646; }","duration":"1.353355996s","start":"2026-06-09T13:39:21.22159Z","end":"2026-06-09T13:39:22.574946Z","steps":["trace[1285254010] 'read index received' (duration: 1.28181491s)","trace[1285254010] 'applied index is now lower than readState.Index' (duration: 71.540165ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:39:22.575108Z","caller":"traceutil/trace.go:171","msg":"trace[1995817866] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.349948717s","start":"2026-06-09T13:39:21.225147Z","end":"2026-06-09T13:39:22.575096Z","steps":["trace[1995817866] 'process raft request' (duration: 1.349613503s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:22.575202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:21.22513Z","time spent":"1.350001848s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:39:22.575384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432905765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:39:22.575494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.625783863s","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-06-09T13:39:22.575477Z","caller":"traceutil/trace.go:171","msg":"trace[1185396687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"1.433039047s","start":"2026-06-09T13:39:21.14242Z","end":"2026-06-09T13:39:22.575459Z","steps":["trace[1185396687] 'agreement among raft nodes before linearized reading' (duration: 1.432895785s)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:22.575538Z","caller":"traceutil/trace.go:171","msg":"trace[352444920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"1.625896354s","start":"2026-06-09T13:39:20.949631Z","end":"2026-06-09T13:39:22.575527Z","steps":["trace[352444920] 'agreement among raft nodes before linearized reading' (duration: 1.625749503s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:22.575573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:20.949608Z","time spent":"1.625957105s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T13:39:22.575577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:21.142399Z","time spent":"1.433143439s","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T13:39:22.575478Z","caller":"traceutil/trace.go:171","msg":"trace[1759907914] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.35018931s","start":"2026-06-09T13:39:21.225246Z","end":"2026-06-09T13:39:22.575435Z","steps":["trace[1759907914] 'process raft request' (duration: 1.349658994s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:22.575824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:21.22522Z","time spent":"1.350511455s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:39:22.627791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.631896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-09T13:39:22.627889Z","caller":"traceutil/trace.go:171","msg":"trace[1161609115] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:622; }","duration":"861.780357ms","start":"2026-06-09T13:39:21.766087Z","end":"2026-06-09T13:39:22.627867Z","steps":["trace[1161609115] 'agreement among raft nodes before linearized reading' (duration: 861.569624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:22.627931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:21.766062Z","time spent":"861.858798ms","remote":"127.0.0.1:47594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-06-09T13:39:22.627976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060004128s","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-06-09T13:39:22.628046Z","caller":"traceutil/trace.go:171","msg":"trace[997918128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"1.060102349s","start":"2026-06-09T13:39:21.567928Z","end":"2026-06-09T13:39:22.628031Z","steps":["trace[997918128] 'agreement among raft nodes before linearized reading' (duration: 1.059931927s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:22.628089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:21.567914Z","time spent":"1.060167209s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T13:39:22.62834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31394848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zl9pq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-09T13:39:22.628447Z","caller":"traceutil/trace.go:171","msg":"trace[1259877626] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zl9pq; range_end:; response_count:1; response_revision:622; }","duration":"1.314061761s","start":"2026-06-09T13:39:21.314333Z","end":"2026-06-09T13:39:22.628395Z","steps":["trace[1259877626] 'agreement among raft nodes before linearized reading' (duration: 1.313795998s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:22.628525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:21.314319Z","time spent":"1.314167882s","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-zl9pq\" "} {"level":"warn","ts":"2026-06-09T13:39:24.268967Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.568488625s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-09T13:39:24.272666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.571894422s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T13:39:24.273046Z","caller":"traceutil/trace.go:171","msg":"trace[1199412424] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.619833387s","start":"2026-06-09T13:39:22.653194Z","end":"2026-06-09T13:39:24.273027Z","steps":["trace[1199412424] 'process raft request' (duration: 1.619785165s)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:24.273016Z","caller":"traceutil/trace.go:171","msg":"trace[698858991] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.693299204s","start":"2026-06-09T13:39:22.579673Z","end":"2026-06-09T13:39:24.272972Z","steps":["trace[698858991] 'process raft request' (duration: 120.857989ms)","trace[698858991] 'compare' (duration: 1.571196022s)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:39:24.273116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:22.653177Z","time spent":"1.619909109s","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:39:24.273213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:22.579648Z","time spent":"1.693455651s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:39:24.273287Z","caller":"traceutil/trace.go:171","msg":"trace[1157446080] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"1.64564786s","start":"2026-06-09T13:39:22.62763Z","end":"2026-06-09T13:39:24.273278Z","steps":["trace[1157446080] 'read index received' (duration: 72.762231ms)","trace[1157446080] 'applied index is now lower than readState.Index' (duration: 1.572884529s)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:39:24.27336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:22.632954Z","time spent":"1.640405179s","remote":"127.0.0.1:47594","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-09T13:39:24.273522Z","caller":"traceutil/trace.go:171","msg":"trace[477751391] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.632237784s","start":"2026-06-09T13:39:22.641274Z","end":"2026-06-09T13:39:24.273511Z","steps":["trace[477751391] 'process raft request' (duration: 1.631633999s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:24.273572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:22.641262Z","time spent":"1.632282096s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:39:24.273602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689962222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:39:24.273646Z","caller":"traceutil/trace.go:171","msg":"trace[1984814192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"1.690034844s","start":"2026-06-09T13:39:22.583598Z","end":"2026-06-09T13:39:24.273632Z","steps":["trace[1984814192] 'agreement among raft nodes before linearized reading' (duration: 1.689960211s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:24.273677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:22.583586Z","time spent":"1.690085556s","remote":"127.0.0.1:47548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T13:39:24.273723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632577305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:39:24.273755Z","caller":"traceutil/trace.go:171","msg":"trace[972319360] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:625; }","duration":"1.632634886s","start":"2026-06-09T13:39:22.641107Z","end":"2026-06-09T13:39:24.273742Z","steps":["trace[972319360] 'agreement among raft nodes before linearized reading' (duration: 1.632584684s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:24.273783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:22.64109Z","time spent":"1.632686437s","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":27,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-06-09T13:39:25.853327Z","caller":"traceutil/trace.go:171","msg":"trace[1423544453] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"152.355636ms","start":"2026-06-09T13:39:25.700945Z","end":"2026-06-09T13:39:25.8533Z","steps":["trace[1423544453] 'process raft request' (duration: 142.320572ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:26.220113Z","caller":"traceutil/trace.go:171","msg":"trace[542547554] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"133.859031ms","start":"2026-06-09T13:39:26.086232Z","end":"2026-06-09T13:39:26.220091Z","steps":["trace[542547554] 'process raft request' (duration: 133.266397ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:26.222905Z","caller":"traceutil/trace.go:171","msg":"trace[1524446212] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"136.346305ms","start":"2026-06-09T13:39:26.086546Z","end":"2026-06-09T13:39:26.222892Z","steps":["trace[1524446212] 'process raft request' (duration: 136.113526ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:26.649812Z","caller":"traceutil/trace.go:171","msg":"trace[18289900] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"102.41914ms","start":"2026-06-09T13:39:26.547364Z","end":"2026-06-09T13:39:26.649783Z","steps":["trace[18289900] 'process raft request' (duration: 52.376375ms)","trace[18289900] 'compare' (duration: 49.877068ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:39:40.616455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.787435ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-09T13:39:40.616772Z","caller":"traceutil/trace.go:171","msg":"trace[1016648856] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"171.024191ms","start":"2026-06-09T13:39:40.445726Z","end":"2026-06-09T13:39:40.61675Z","steps":["trace[1016648856] 'process raft request' (duration: 170.879486ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:40.616897Z","caller":"traceutil/trace.go:171","msg":"trace[290188543] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"481.943504ms","start":"2026-06-09T13:39:40.134844Z","end":"2026-06-09T13:39:40.616788Z","steps":["trace[290188543] 'read index received' (duration: 255.799796ms)","trace[290188543] 'applied index is now lower than readState.Index' (duration: 226.140908ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:39:40.617262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.459112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:39:40.617326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.074248ms","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-06-09T13:39:40.617435Z","caller":"traceutil/trace.go:171","msg":"trace[378185597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"216.207693ms","start":"2026-06-09T13:39:40.401207Z","end":"2026-06-09T13:39:40.617414Z","steps":["trace[378185597] 'agreement among raft nodes before linearized reading' (duration: 215.992425ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:39:40.617436Z","caller":"traceutil/trace.go:171","msg":"trace[767714957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"475.670299ms","start":"2026-06-09T13:39:40.141733Z","end":"2026-06-09T13:39:40.617403Z","steps":["trace[767714957] 'agreement among raft nodes before linearized reading' (duration: 475.426691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:40.617504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:40.141709Z","time spent":"475.779883ms","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T13:39:40.617571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.543809ms","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-06-09T13:39:40.61763Z","caller":"traceutil/trace.go:171","msg":"trace[1677496314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"229.693764ms","start":"2026-06-09T13:39:40.387922Z","end":"2026-06-09T13:39:40.617616Z","steps":["trace[1677496314] 'agreement among raft nodes before linearized reading' (duration: 229.352032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:40.617793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.946359ms","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-06-09T13:39:40.617838Z","caller":"traceutil/trace.go:171","msg":"trace[687508287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"483.026551ms","start":"2026-06-09T13:39:40.134797Z","end":"2026-06-09T13:39:40.617823Z","steps":["trace[687508287] 'agreement among raft nodes before linearized reading' (duration: 482.882176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:40.617868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:39:40.134777Z","time spent":"483.082974ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T13:39:59.002718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.768972ms","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-06-09T13:39:59.002816Z","caller":"traceutil/trace.go:171","msg":"trace[1303141608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1108; }","duration":"171.886745ms","start":"2026-06-09T13:39:58.830903Z","end":"2026-06-09T13:39:59.002789Z","steps":["trace[1303141608] 'range keys from in-memory index tree' (duration: 171.642528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:39:59.003203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.943437ms","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-06-09T13:39:59.003344Z","caller":"traceutil/trace.go:171","msg":"trace[1380658917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1108; }","duration":"146.121711ms","start":"2026-06-09T13:39:58.857199Z","end":"2026-06-09T13:39:59.003321Z","steps":["trace[1380658917] 'range keys from in-memory index tree' (duration: 145.774392ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:40:10.948524Z","caller":"traceutil/trace.go:171","msg":"trace[812626390] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"109.730675ms","start":"2026-06-09T13:40:10.83875Z","end":"2026-06-09T13:40:10.948481Z","steps":["trace[812626390] 'read index received' (duration: 109.322244ms)","trace[812626390] 'applied index is now lower than readState.Index' (duration: 406.431µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:40:10.948652Z","caller":"traceutil/trace.go:171","msg":"trace[252125421] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"109.989892ms","start":"2026-06-09T13:40:10.83854Z","end":"2026-06-09T13:40:10.94853Z","steps":["trace[252125421] 'process raft request' (duration: 109.582932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:40:10.948913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.152377ms","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-06-09T13:40:10.949054Z","caller":"traceutil/trace.go:171","msg":"trace[404404284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"110.351022ms","start":"2026-06-09T13:40:10.838678Z","end":"2026-06-09T13:40:10.949029Z","steps":["trace[404404284] 'agreement among raft nodes before linearized reading' (duration: 109.993042ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:40:15.762707Z","caller":"traceutil/trace.go:171","msg":"trace[1777037942] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"151.557138ms","start":"2026-06-09T13:40:15.611116Z","end":"2026-06-09T13:40:15.762673Z","steps":["trace[1777037942] 'process raft request' (duration: 134.696211ms)","trace[1777037942] 'compare' (duration: 16.648272ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:40:15.764929Z","caller":"traceutil/trace.go:171","msg":"trace[1575501480] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"149.62904ms","start":"2026-06-09T13:40:15.615279Z","end":"2026-06-09T13:40:15.764908Z","steps":["trace[1575501480] 'process raft request' (duration: 149.519547ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:40:15.76506Z","caller":"traceutil/trace.go:171","msg":"trace[1980949355] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1248; }","duration":"152.887122ms","start":"2026-06-09T13:40:15.61214Z","end":"2026-06-09T13:40:15.765027Z","steps":["trace[1980949355] 'process raft request' (duration: 152.582434ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:40:28.046283Z","caller":"traceutil/trace.go:171","msg":"trace[362143703] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"380.419815ms","start":"2026-06-09T13:40:27.66583Z","end":"2026-06-09T13:40:28.04625Z","steps":["trace[362143703] 'process raft request' (duration: 380.233871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:40:28.046482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:40:27.66581Z","time spent":"380.566529ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4331,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:40:33.47426Z","caller":"traceutil/trace.go:171","msg":"trace[1671957244] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"224.132347ms","start":"2026-06-09T13:40:33.250114Z","end":"2026-06-09T13:40:33.474246Z","steps":["trace[1671957244] 'process raft request' (duration: 223.462383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:40:33.474373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.003604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:40:33.474419Z","caller":"traceutil/trace.go:171","msg":"trace[1768217085] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1513; }","duration":"169.638946ms","start":"2026-06-09T13:40:33.304352Z","end":"2026-06-09T13:40:33.473991Z","steps":["trace[1768217085] 'read index received' (duration: 169.181726ms)","trace[1768217085] 'applied index is now lower than readState.Index' (duration: 456.52µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:40:33.474494Z","caller":"traceutil/trace.go:171","msg":"trace[1596172135] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1462; }","duration":"170.149037ms","start":"2026-06-09T13:40:33.304289Z","end":"2026-06-09T13:40:33.474438Z","steps":["trace[1596172135] 'agreement among raft nodes before linearized reading' (duration: 170.015595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:40:34.233003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.005293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1823"} {"level":"info","ts":"2026-06-09T13:40:34.233093Z","caller":"traceutil/trace.go:171","msg":"trace[760513840] range","detail":"{range_begin:/registry/secrets/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1468; }","duration":"121.150336ms","start":"2026-06-09T13:40:34.111925Z","end":"2026-06-09T13:40:34.233075Z","steps":["trace[760513840] 'range keys from in-memory index tree' (duration: 120.795319ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:40:50.619578Z","caller":"traceutil/trace.go:171","msg":"trace[1850501389] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1730; }","duration":"112.898937ms","start":"2026-06-09T13:40:50.506645Z","end":"2026-06-09T13:40:50.619544Z","steps":["trace[1850501389] 'process raft request' (duration: 112.753414ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:40:50.626464Z","caller":"traceutil/trace.go:171","msg":"trace[1575707701] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1730; }","duration":"118.081867ms","start":"2026-06-09T13:40:50.50835Z","end":"2026-06-09T13:40:50.626431Z","steps":["trace[1575707701] 'process raft request' (duration: 117.763231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.269386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220399544166733,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T13:41:02.743136Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.111388467s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T13:41:02.747956Z","caller":"traceutil/trace.go:171","msg":"trace[1927980713] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"1.116275104s","start":"2026-06-09T13:41:01.631658Z","end":"2026-06-09T13:41:02.747933Z","steps":["trace[1927980713] 'process raft request' (duration: 1.111684662s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.748171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:01.631642Z","time spent":"1.116367917s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:02.797061Z","caller":"traceutil/trace.go:171","msg":"trace[939667581] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"1.164740086s","start":"2026-06-09T13:41:01.632285Z","end":"2026-06-09T13:41:02.797025Z","steps":["trace[939667581] 'process raft request' (duration: 1.164620424s)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:02.797112Z","caller":"traceutil/trace.go:171","msg":"trace[221878171] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"1.165142904s","start":"2026-06-09T13:41:01.631929Z","end":"2026-06-09T13:41:02.797072Z","steps":["trace[221878171] 'process raft request' (duration: 1.164766247s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.797477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:01.632275Z","time spent":"1.164896419s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:41:02.79753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:01.631917Z","time spent":"1.165315206s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:02.79755Z","caller":"traceutil/trace.go:171","msg":"trace[1441710652] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"1.107595854s","start":"2026-06-09T13:41:01.689933Z","end":"2026-06-09T13:41:02.797529Z","steps":["trace[1441710652] 'process raft request' (duration: 1.107024613s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.797795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:01.689902Z","time spent":"1.107764726s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:02.798012Z","caller":"traceutil/trace.go:171","msg":"trace[1494523486] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"1.085544463s","start":"2026-06-09T13:41:01.712426Z","end":"2026-06-09T13:41:02.797971Z","steps":["trace[1494523486] 'process raft request' (duration: 1.08479445s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.79812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:01.71241Z","time spent":"1.085650516s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:02.798186Z","caller":"traceutil/trace.go:171","msg":"trace[1916955164] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"1.053442794s","start":"2026-06-09T13:41:01.74473Z","end":"2026-06-09T13:41:02.798173Z","steps":["trace[1916955164] 'process raft request' (duration: 1.053134929s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.798265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:01.744715Z","time spent":"1.053505105s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:02.798377Z","caller":"traceutil/trace.go:171","msg":"trace[745633285] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1973; }","duration":"1.030065692s","start":"2026-06-09T13:41:01.7683Z","end":"2026-06-09T13:41:02.798366Z","steps":["trace[745633285] 'read index received' (duration: 975.057185ms)","trace[745633285] 'applied index is now lower than readState.Index' (duration: 55.007287ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:41:02.798675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.709215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e76f26ed-97a4-452b-b8fb-cd78a1c66cea\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-06-09T13:41:02.798797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03049436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-09T13:41:02.798808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.990813ms","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-06-09T13:41:02.798847Z","caller":"traceutil/trace.go:171","msg":"trace[738853928] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1900; }","duration":"1.030561731s","start":"2026-06-09T13:41:01.768272Z","end":"2026-06-09T13:41:02.798834Z","steps":["trace[738853928] 'agreement among raft nodes before linearized reading' (duration: 1.030455079s)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:02.798862Z","caller":"traceutil/trace.go:171","msg":"trace[158281736] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1900; }","duration":"925.061404ms","start":"2026-06-09T13:41:01.873785Z","end":"2026-06-09T13:41:02.798846Z","steps":["trace[158281736] 'agreement among raft nodes before linearized reading' (duration: 924.890821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.798699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.947651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e76f26ed-97a4-452b-b8fb-cd78a1c66cea\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-09T13:41:02.798797Z","caller":"traceutil/trace.go:171","msg":"trace[1332033848] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e76f26ed-97a4-452b-b8fb-cd78a1c66cea; range_end:; response_count:1; response_revision:1900; }","duration":"951.889648ms","start":"2026-06-09T13:41:01.846884Z","end":"2026-06-09T13:41:02.798773Z","steps":["trace[1332033848] 'agreement among raft nodes before linearized reading' (duration: 951.672964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.798894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:01.873772Z","time spent":"925.115905ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T13:41:02.799275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:01.768256Z","time spent":"1.030982248s","remote":"127.0.0.1:47594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-06-09T13:41:02.799129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:01.84686Z","time spent":"952.218534ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e76f26ed-97a4-452b-b8fb-cd78a1c66cea\" "} {"level":"warn","ts":"2026-06-09T13:41:02.799578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.217563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b76d655135526b\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-06-09T13:41:02.799725Z","caller":"traceutil/trace.go:171","msg":"trace[147475142] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"774.329031ms","start":"2026-06-09T13:41:02.02414Z","end":"2026-06-09T13:41:02.798469Z","steps":["trace[147475142] 'process raft request' (duration: 773.985275ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:02.799771Z","caller":"traceutil/trace.go:171","msg":"trace[1190051166] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b76d655135526b; range_end:; response_count:1; response_revision:1900; }","duration":"364.445838ms","start":"2026-06-09T13:41:02.435308Z","end":"2026-06-09T13:41:02.799754Z","steps":["trace[1190051166] 'agreement among raft nodes before linearized reading' (duration: 363.365108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.799813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:02.43529Z","time spent":"364.510769ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1004,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b76d655135526b\" "} {"level":"info","ts":"2026-06-09T13:41:02.798962Z","caller":"traceutil/trace.go:171","msg":"trace[1041510721] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e76f26ed-97a4-452b-b8fb-cd78a1c66cea; range_end:; response_count:1; response_revision:1900; }","duration":"186.231115ms","start":"2026-06-09T13:41:02.612709Z","end":"2026-06-09T13:41:02.79894Z","steps":["trace[1041510721] 'agreement among raft nodes before linearized reading' (duration: 185.885919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:02.799849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:02.024118Z","time spent":"775.667086ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:03.121057Z","caller":"traceutil/trace.go:171","msg":"trace[522686320] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"313.068321ms","start":"2026-06-09T13:41:02.807961Z","end":"2026-06-09T13:41:03.121029Z","steps":["trace[522686320] 'process raft request' (duration: 312.957589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:03.121215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:02.807951Z","time spent":"313.174694ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:03.121224Z","caller":"traceutil/trace.go:171","msg":"trace[2057642024] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"317.450331ms","start":"2026-06-09T13:41:02.803735Z","end":"2026-06-09T13:41:03.121185Z","steps":["trace[2057642024] 'process raft request' (duration: 309.841156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:03.121338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:02.803717Z","time spent":"317.557692ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:03.129506Z","caller":"traceutil/trace.go:171","msg":"trace[1904903306] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"239.266875ms","start":"2026-06-09T13:41:02.890216Z","end":"2026-06-09T13:41:03.129483Z","steps":["trace[1904903306] 'process raft request' (duration: 239.171624ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:03.129502Z","caller":"traceutil/trace.go:171","msg":"trace[489572120] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"302.461002ms","start":"2026-06-09T13:41:02.826992Z","end":"2026-06-09T13:41:03.129453Z","steps":["trace[489572120] 'process raft request' (duration: 302.3527ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:03.129519Z","caller":"traceutil/trace.go:171","msg":"trace[333691727] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"307.143685ms","start":"2026-06-09T13:41:02.822358Z","end":"2026-06-09T13:41:03.129502Z","steps":["trace[333691727] 'process raft request' (duration: 306.85984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:03.12978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:02.822345Z","time spent":"307.397389ms","remote":"127.0.0.1:47594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:41:03.130895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:02.826963Z","time spent":"302.684045ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:41:03.802493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.57566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T13:41:03.802524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.962275ms","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-06-09T13:41:03.80258Z","caller":"traceutil/trace.go:171","msg":"trace[1822333497] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1905; }","duration":"150.723712ms","start":"2026-06-09T13:41:03.651839Z","end":"2026-06-09T13:41:03.802563Z","steps":["trace[1822333497] 'count revisions from in-memory index tree' (duration: 150.476048ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:03.802598Z","caller":"traceutil/trace.go:171","msg":"trace[1524350023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1905; }","duration":"203.111658ms","start":"2026-06-09T13:41:03.599468Z","end":"2026-06-09T13:41:03.80258Z","steps":["trace[1524350023] 'range keys from in-memory index tree' (duration: 202.716751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:03.802726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.932999ms","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-06-09T13:41:03.802816Z","caller":"traceutil/trace.go:171","msg":"trace[2030709318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1905; }","duration":"505.302106ms","start":"2026-06-09T13:41:03.297495Z","end":"2026-06-09T13:41:03.802797Z","steps":["trace[2030709318] 'range keys from in-memory index tree' (duration: 504.691365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:03.802862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:03.297477Z","time spent":"505.374057ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T13:41:03.802929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.343134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T13:41:03.802995Z","caller":"traceutil/trace.go:171","msg":"trace[918437881] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1905; }","duration":"539.453325ms","start":"2026-06-09T13:41:03.263519Z","end":"2026-06-09T13:41:03.802972Z","steps":["trace[918437881] 'count revisions from in-memory index tree' (duration: 539.236992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:03.803044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:03.263491Z","time spent":"539.536607ms","remote":"127.0.0.1:47182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":13,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-09T13:41:15.188439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.748312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T13:41:15.188703Z","caller":"traceutil/trace.go:171","msg":"trace[1882938644] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"297.80247ms","start":"2026-06-09T13:41:14.890877Z","end":"2026-06-09T13:41:15.18868Z","steps":["trace[1882938644] 'process raft request' (duration: 297.681258ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:15.189029Z","caller":"traceutil/trace.go:171","msg":"trace[566081943] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"298.305828ms","start":"2026-06-09T13:41:14.890662Z","end":"2026-06-09T13:41:15.188967Z","steps":["trace[566081943] 'process raft request' (duration: 125.914085ms)","trace[566081943] 'compare' (duration: 171.581009ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:41:15.189107Z","caller":"traceutil/trace.go:171","msg":"trace[46883453] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"296.5806ms","start":"2026-06-09T13:41:14.89251Z","end":"2026-06-09T13:41:15.18909Z","steps":["trace[46883453] 'process raft request' (duration: 296.480438ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:15.189257Z","caller":"traceutil/trace.go:171","msg":"trace[921749934] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"297.832091ms","start":"2026-06-09T13:41:14.891378Z","end":"2026-06-09T13:41:15.18921Z","steps":["trace[921749934] 'process raft request' (duration: 297.255271ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:15.189365Z","caller":"traceutil/trace.go:171","msg":"trace[1553340509] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"296.469367ms","start":"2026-06-09T13:41:14.892878Z","end":"2026-06-09T13:41:15.189348Z","steps":["trace[1553340509] 'process raft request' (duration: 296.175732ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:15.189336Z","caller":"traceutil/trace.go:171","msg":"trace[295612156] linearizableReadLoop","detail":"{readStateIndex:2084; appliedIndex:2080; }","duration":"297.578536ms","start":"2026-06-09T13:41:14.891729Z","end":"2026-06-09T13:41:15.189307Z","steps":["trace[295612156] 'read index received' (duration: 2.889049ms)","trace[295612156] 'applied index is now lower than readState.Index' (duration: 294.688087ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:41:15.189729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.990054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11978"} {"level":"info","ts":"2026-06-09T13:41:15.189774Z","caller":"traceutil/trace.go:171","msg":"trace[1758541742] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2003; }","duration":"298.050455ms","start":"2026-06-09T13:41:14.891712Z","end":"2026-06-09T13:41:15.189762Z","steps":["trace[1758541742] 'agreement among raft nodes before linearized reading' (duration: 297.794199ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:16.030115Z","caller":"traceutil/trace.go:171","msg":"trace[1924655725] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"150.133388ms","start":"2026-06-09T13:41:15.879956Z","end":"2026-06-09T13:41:16.03009Z","steps":["trace[1924655725] 'process raft request' (duration: 149.984246ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:16.034491Z","caller":"traceutil/trace.go:171","msg":"trace[503228166] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"146.005439ms","start":"2026-06-09T13:41:15.88847Z","end":"2026-06-09T13:41:16.034475Z","steps":["trace[503228166] 'process raft request' (duration: 145.776126ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:16.034487Z","caller":"traceutil/trace.go:171","msg":"trace[474350165] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2087; }","duration":"140.015599ms","start":"2026-06-09T13:41:15.894442Z","end":"2026-06-09T13:41:16.034458Z","steps":["trace[474350165] 'read index received' (duration: 135.510894ms)","trace[474350165] 'applied index is now lower than readState.Index' (duration: 4.503005ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:41:16.034843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.364685ms","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-06-09T13:41:16.034887Z","caller":"traceutil/trace.go:171","msg":"trace[240757852] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2006; }","duration":"140.451906ms","start":"2026-06-09T13:41:15.89442Z","end":"2026-06-09T13:41:16.034872Z","steps":["trace[240757852] 'agreement among raft nodes before linearized reading' (duration: 140.148191ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:19.018976Z","caller":"traceutil/trace.go:171","msg":"trace[638888616] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"112.238728ms","start":"2026-06-09T13:41:18.906698Z","end":"2026-06-09T13:41:19.018937Z","steps":["trace[638888616] 'process raft request' (duration: 111.671879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:20.56297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220399544167034,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T13:41:20.5893Z","caller":"traceutil/trace.go:171","msg":"trace[1456658661] linearizableReadLoop","detail":"{readStateIndex:2124; appliedIndex:2123; }","duration":"526.828932ms","start":"2026-06-09T13:41:20.062446Z","end":"2026-06-09T13:41:20.589275Z","steps":["trace[1456658661] 'read index received' (duration: 526.491887ms)","trace[1456658661] 'applied index is now lower than readState.Index' (duration: 336.165µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:41:20.589594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.182295ms","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-06-09T13:41:20.589619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.34386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11985"} {"level":"warn","ts":"2026-06-09T13:41:20.589693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.925039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:41:20.589755Z","caller":"traceutil/trace.go:171","msg":"trace[2044231021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2040; }","duration":"447.0035ms","start":"2026-06-09T13:41:20.142743Z","end":"2026-06-09T13:41:20.589747Z","steps":["trace[2044231021] 'agreement among raft nodes before linearized reading' (duration: 446.926668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:20.589775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.493931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:41:20.589801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:20.142733Z","time spent":"447.05936ms","remote":"127.0.0.1:47548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T13:41:20.589808Z","caller":"traceutil/trace.go:171","msg":"trace[2049044464] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2040; }","duration":"205.573672ms","start":"2026-06-09T13:41:20.384224Z","end":"2026-06-09T13:41:20.589798Z","steps":["trace[2049044464] 'agreement among raft nodes before linearized reading' (duration: 205.518602ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:20.589697Z","caller":"traceutil/trace.go:171","msg":"trace[1588619377] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2040; }","duration":"370.456872ms","start":"2026-06-09T13:41:20.219222Z","end":"2026-06-09T13:41:20.589679Z","steps":["trace[1588619377] 'agreement among raft nodes before linearized reading' (duration: 370.254958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:20.590117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.782079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:41:20.590136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:20.21921Z","time spent":"370.914389ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12007,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-09T13:41:20.590149Z","caller":"traceutil/trace.go:171","msg":"trace[64533047] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2040; }","duration":"448.83995ms","start":"2026-06-09T13:41:20.1413Z","end":"2026-06-09T13:41:20.59014Z","steps":["trace[64533047] 'agreement among raft nodes before linearized reading' (duration: 448.785099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:20.59018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:20.141289Z","time spent":"448.8839ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-06-09T13:41:20.589647Z","caller":"traceutil/trace.go:171","msg":"trace[1528462947] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2040; }","duration":"517.279107ms","start":"2026-06-09T13:41:20.072342Z","end":"2026-06-09T13:41:20.589621Z","steps":["trace[1528462947] 'agreement among raft nodes before linearized reading' (duration: 517.081744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:20.59029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:20.072332Z","time spent":"517.949957ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T13:41:20.590505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.032671ms","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-06-09T13:41:20.590565Z","caller":"traceutil/trace.go:171","msg":"trace[1502753749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2040; }","duration":"528.161034ms","start":"2026-06-09T13:41:20.06239Z","end":"2026-06-09T13:41:20.590551Z","steps":["trace[1502753749] 'agreement among raft nodes before linearized reading' (duration: 527.011375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:20.590606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:20.062364Z","time spent":"528.231064ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-09T13:41:20.591029Z","caller":"traceutil/trace.go:171","msg":"trace[1504929265] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"533.780987ms","start":"2026-06-09T13:41:20.055586Z","end":"2026-06-09T13:41:20.589367Z","steps":["trace[1504929265] 'process raft request' (duration: 533.488072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:20.591232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:20.055565Z","time spent":"535.598316ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:37.184031Z","caller":"traceutil/trace.go:171","msg":"trace[980292115] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"101.015198ms","start":"2026-06-09T13:41:37.082991Z","end":"2026-06-09T13:41:37.184006Z","steps":["trace[980292115] 'process raft request' (duration: 100.906036ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:37.500768Z","caller":"traceutil/trace.go:171","msg":"trace[866567716] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"224.18826ms","start":"2026-06-09T13:41:37.27656Z","end":"2026-06-09T13:41:37.500749Z","steps":["trace[866567716] 'process raft request' (duration: 224.060898ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:37.501674Z","caller":"traceutil/trace.go:171","msg":"trace[1245912221] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2222; }","duration":"187.387344ms","start":"2026-06-09T13:41:37.314261Z","end":"2026-06-09T13:41:37.501649Z","steps":["trace[1245912221] 'read index received' (duration: 186.388339ms)","trace[1245912221] 'applied index is now lower than readState.Index' (duration: 997.835µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:41:37.501815Z","caller":"traceutil/trace.go:171","msg":"trace[437623674] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"197.0736ms","start":"2026-06-09T13:41:37.30473Z","end":"2026-06-09T13:41:37.501803Z","steps":["trace[437623674] 'process raft request' (duration: 196.876207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:37.50229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.077472ms","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-06-09T13:41:37.502367Z","caller":"traceutil/trace.go:171","msg":"trace[383434053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2137; }","duration":"174.179244ms","start":"2026-06-09T13:41:37.328176Z","end":"2026-06-09T13:41:37.502355Z","steps":["trace[383434053] 'agreement among raft nodes before linearized reading' (duration: 173.980521ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:37.50171Z","caller":"traceutil/trace.go:171","msg":"trace[1901871258] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"197.08103ms","start":"2026-06-09T13:41:37.304609Z","end":"2026-06-09T13:41:37.50169Z","steps":["trace[1901871258] 'process raft request' (duration: 196.841777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:37.502572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.53864ms","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-06-09T13:41:37.502624Z","caller":"traceutil/trace.go:171","msg":"trace[694847938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2137; }","duration":"176.613991ms","start":"2026-06-09T13:41:37.326002Z","end":"2026-06-09T13:41:37.502616Z","steps":["trace[694847938] 'agreement among raft nodes before linearized reading' (duration: 176.072732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:37.502288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.002533ms","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-06-09T13:41:37.504001Z","caller":"traceutil/trace.go:171","msg":"trace[2037120894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2137; }","duration":"188.802515ms","start":"2026-06-09T13:41:37.314233Z","end":"2026-06-09T13:41:37.503036Z","steps":["trace[2037120894] 'agreement among raft nodes before linearized reading' (duration: 187.912411ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:43.110989Z","caller":"traceutil/trace.go:171","msg":"trace[1149816386] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2251; }","duration":"364.426078ms","start":"2026-06-09T13:41:42.746546Z","end":"2026-06-09T13:41:43.110972Z","steps":["trace[1149816386] 'read index received' (duration: 30.691905ms)","trace[1149816386] 'applied index is now lower than readState.Index' (duration: 333.733363ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:41:43.11108Z","caller":"traceutil/trace.go:171","msg":"trace[1774303337] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"366.59705ms","start":"2026-06-09T13:41:42.744448Z","end":"2026-06-09T13:41:43.111045Z","steps":["trace[1774303337] 'process raft request' (duration: 366.385296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:43.111287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.585051ms","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-06-09T13:41:43.111314Z","caller":"traceutil/trace.go:171","msg":"trace[1313407320] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2164; }","duration":"364.772413ms","start":"2026-06-09T13:41:42.746533Z","end":"2026-06-09T13:41:43.111305Z","steps":["trace[1313407320] 'agreement among raft nodes before linearized reading' (duration: 364.51321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:43.111333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:42.746505Z","time spent":"364.824035ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T13:41:43.111559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:42.744443Z","time spent":"366.730391ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:43.683873Z","caller":"traceutil/trace.go:171","msg":"trace[1463589192] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"164.016713ms","start":"2026-06-09T13:41:43.519833Z","end":"2026-06-09T13:41:43.68385Z","steps":["trace[1463589192] 'process raft request' (duration: 163.82857ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:43.691559Z","caller":"traceutil/trace.go:171","msg":"trace[1681603661] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"170.198574ms","start":"2026-06-09T13:41:43.521343Z","end":"2026-06-09T13:41:43.691542Z","steps":["trace[1681603661] 'process raft request' (duration: 170.061192ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:43.691765Z","caller":"traceutil/trace.go:171","msg":"trace[244470351] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2254; }","duration":"164.703933ms","start":"2026-06-09T13:41:43.527047Z","end":"2026-06-09T13:41:43.691751Z","steps":["trace[244470351] 'read index received' (duration: 156.557663ms)","trace[244470351] 'applied index is now lower than readState.Index' (duration: 8.14538ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:41:43.691836Z","caller":"traceutil/trace.go:171","msg":"trace[51721426] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"167.774999ms","start":"2026-06-09T13:41:43.524037Z","end":"2026-06-09T13:41:43.691812Z","steps":["trace[51721426] 'process raft request' (duration: 167.457035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:43.692108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.041768ms","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-06-09T13:41:43.692185Z","caller":"traceutil/trace.go:171","msg":"trace[1471480855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2168; }","duration":"165.152941ms","start":"2026-06-09T13:41:43.527019Z","end":"2026-06-09T13:41:43.692172Z","steps":["trace[1471480855] 'agreement among raft nodes before linearized reading' (duration: 164.981108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:43.692329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.641113ms","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-06-09T13:41:43.692363Z","caller":"traceutil/trace.go:171","msg":"trace[355206045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2168; }","duration":"164.736374ms","start":"2026-06-09T13:41:43.527618Z","end":"2026-06-09T13:41:43.692354Z","steps":["trace[355206045] 'agreement among raft nodes before linearized reading' (duration: 164.651793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:43.692363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.825906ms","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-06-09T13:41:43.692457Z","caller":"traceutil/trace.go:171","msg":"trace[1035243251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2168; }","duration":"164.907037ms","start":"2026-06-09T13:41:43.527503Z","end":"2026-06-09T13:41:43.69241Z","steps":["trace[1035243251] 'agreement among raft nodes before linearized reading' (duration: 164.693874ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:43.815578Z","caller":"traceutil/trace.go:171","msg":"trace[439849651] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"120.174185ms","start":"2026-06-09T13:41:43.695377Z","end":"2026-06-09T13:41:43.815551Z","steps":["trace[439849651] 'process raft request' (duration: 94.723299ms)","trace[439849651] 'compare' (duration: 22.099737ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:41:43.815833Z","caller":"traceutil/trace.go:171","msg":"trace[528561522] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"117.866031ms","start":"2026-06-09T13:41:43.69795Z","end":"2026-06-09T13:41:43.815816Z","steps":["trace[528561522] 'process raft request' (duration: 117.78455ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:43.815906Z","caller":"traceutil/trace.go:171","msg":"trace[1073096575] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"119.739889ms","start":"2026-06-09T13:41:43.696139Z","end":"2026-06-09T13:41:43.815879Z","steps":["trace[1073096575] 'process raft request' (duration: 119.365573ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:44.676274Z","caller":"traceutil/trace.go:171","msg":"trace[1022509955] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"556.982498ms","start":"2026-06-09T13:41:44.119259Z","end":"2026-06-09T13:41:44.676241Z","steps":["trace[1022509955] 'read index received' (duration: 472.376243ms)","trace[1022509955] 'applied index is now lower than readState.Index' (duration: 84.604745ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:41:44.676406Z","caller":"traceutil/trace.go:171","msg":"trace[1686249418] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"777.233366ms","start":"2026-06-09T13:41:43.899108Z","end":"2026-06-09T13:41:44.676341Z","steps":["trace[1686249418] 'process raft request' (duration: 692.58013ms)","trace[1686249418] 'compare' (duration: 83.986796ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:41:44.676532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.098926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:41:44.67654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.275262ms","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-06-09T13:41:44.676577Z","caller":"traceutil/trace.go:171","msg":"trace[594549427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2172; }","duration":"533.200448ms","start":"2026-06-09T13:41:44.143365Z","end":"2026-06-09T13:41:44.676566Z","steps":["trace[594549427] 'agreement among raft nodes before linearized reading' (duration: 533.083446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:44.676611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:44.143343Z","time spent":"533.260319ms","remote":"127.0.0.1:47548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T13:41:44.676623Z","caller":"traceutil/trace.go:171","msg":"trace[1803283399] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"557.380074ms","start":"2026-06-09T13:41:44.119207Z","end":"2026-06-09T13:41:44.676587Z","steps":["trace[1803283399] 'agreement among raft nodes before linearized reading' (duration: 557.216932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:44.676658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:44.119185Z","time spent":"557.464685ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T13:41:44.676899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.85026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:41:44.676937Z","caller":"traceutil/trace.go:171","msg":"trace[1849440473] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2172; }","duration":"243.915641ms","start":"2026-06-09T13:41:44.433008Z","end":"2026-06-09T13:41:44.676923Z","steps":["trace[1849440473] 'agreement among raft nodes before linearized reading' (duration: 243.8015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:44.676792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:43.89908Z","time spent":"777.417648ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:41:44.677049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.178419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T13:41:44.677087Z","caller":"traceutil/trace.go:171","msg":"trace[720997487] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2172; }","duration":"533.217469ms","start":"2026-06-09T13:41:44.143859Z","end":"2026-06-09T13:41:44.677076Z","steps":["trace[720997487] 'agreement among raft nodes before linearized reading' (duration: 533.155718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:44.677115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:44.14383Z","time spent":"533.27798ms","remote":"127.0.0.1:47860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-09T13:41:44.952492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.475659ms","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-06-09T13:41:44.952549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.19497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b76d6c5a072429\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-09T13:41:44.95261Z","caller":"traceutil/trace.go:171","msg":"trace[1713044875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2172; }","duration":"170.640952ms","start":"2026-06-09T13:41:44.781941Z","end":"2026-06-09T13:41:44.952582Z","steps":["trace[1713044875] 'range keys from in-memory index tree' (duration: 170.207506ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:44.952631Z","caller":"traceutil/trace.go:171","msg":"trace[875902235] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b76d6c5a072429; range_end:; response_count:1; response_revision:2172; }","duration":"154.354912ms","start":"2026-06-09T13:41:44.798252Z","end":"2026-06-09T13:41:44.952607Z","steps":["trace[875902235] 'range keys from in-memory index tree' (duration: 153.921896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:45.616153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220399544167363,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T13:41:45.881987Z","caller":"traceutil/trace.go:171","msg":"trace[1945605505] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"924.589721ms","start":"2026-06-09T13:41:44.957349Z","end":"2026-06-09T13:41:45.881939Z","steps":["trace[1945605505] 'process raft request' (duration: 924.133095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:45.882232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:44.957332Z","time spent":"924.779994ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:46.337021Z","caller":"traceutil/trace.go:171","msg":"trace[980266623] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"1.022970383s","start":"2026-06-09T13:41:45.314023Z","end":"2026-06-09T13:41:46.336994Z","steps":["trace[980266623] 'process raft request' (duration: 1.011805619s)","trace[980266623] 'compare' (duration: 10.971601ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:41:46.337194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.313988Z","time spent":"1.023120185s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:46.33723Z","caller":"traceutil/trace.go:171","msg":"trace[1501090506] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"637.217026ms","start":"2026-06-09T13:41:45.699992Z","end":"2026-06-09T13:41:46.33721Z","steps":["trace[1501090506] 'process raft request' (duration: 636.958032ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:46.337555Z","caller":"traceutil/trace.go:171","msg":"trace[2104064404] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2262; }","duration":"1.222319565s","start":"2026-06-09T13:41:45.115212Z","end":"2026-06-09T13:41:46.337531Z","steps":["trace[2104064404] 'read index received' (duration: 766.252427ms)","trace[2104064404] 'applied index is now lower than readState.Index' (duration: 456.066338ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:41:46.337591Z","caller":"traceutil/trace.go:171","msg":"trace[786649985] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"623.725307ms","start":"2026-06-09T13:41:45.713852Z","end":"2026-06-09T13:41:46.337578Z","steps":["trace[786649985] 'process raft request' (duration: 623.290971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.33771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193892939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:41:46.337714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.713831Z","time spent":"623.786819ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:46.337684Z","caller":"traceutil/trace.go:171","msg":"trace[1767706547] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"621.701378ms","start":"2026-06-09T13:41:45.715906Z","end":"2026-06-09T13:41:46.337608Z","steps":["trace[1767706547] 'process raft request' (duration: 621.549826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.33779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.979692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T13:41:46.337837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.876095ms","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-06-09T13:41:46.337854Z","caller":"traceutil/trace.go:171","msg":"trace[1202231922] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2178; }","duration":"356.097974ms","start":"2026-06-09T13:41:45.981742Z","end":"2026-06-09T13:41:46.33784Z","steps":["trace[1202231922] 'agreement among raft nodes before linearized reading' (duration: 355.952302ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:46.337878Z","caller":"traceutil/trace.go:171","msg":"trace[803835885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2178; }","duration":"517.975646ms","start":"2026-06-09T13:41:45.819895Z","end":"2026-06-09T13:41:46.337871Z","steps":["trace[803835885] 'agreement among raft nodes before linearized reading' (duration: 517.867115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.337906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.819863Z","time spent":"518.034928ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T13:41:46.337894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22268725s","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-06-09T13:41:46.337918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.981717Z","time spent":"356.177455ms","remote":"127.0.0.1:47768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":56,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-06-09T13:41:46.337925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.286117ms","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-06-09T13:41:46.337954Z","caller":"traceutil/trace.go:171","msg":"trace[1875548545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2178; }","duration":"1.222786533s","start":"2026-06-09T13:41:45.115149Z","end":"2026-06-09T13:41:46.337936Z","steps":["trace[1875548545] 'agreement among raft nodes before linearized reading' (duration: 1.222459057s)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:46.337966Z","caller":"traceutil/trace.go:171","msg":"trace[530201823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2178; }","duration":"517.364748ms","start":"2026-06-09T13:41:45.82059Z","end":"2026-06-09T13:41:46.337955Z","steps":["trace[530201823] 'agreement among raft nodes before linearized reading' (duration: 517.240156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.337994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.115126Z","time spent":"1.222860544s","remote":"127.0.0.1:47846","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-06-09T13:41:46.338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.820574Z","time spent":"517.419399ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T13:41:46.338007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.057015ms","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-06-09T13:41:46.338037Z","caller":"traceutil/trace.go:171","msg":"trace[1183754739] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"622.106525ms","start":"2026-06-09T13:41:45.715921Z","end":"2026-06-09T13:41:46.338027Z","steps":["trace[1183754739] 'agreement among raft nodes before linearized reading' (duration: 621.998293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.337942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.715898Z","time spent":"621.891421ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:41:46.338058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.715913Z","time spent":"622.140606ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T13:41:46.338096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025530001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:41:46.337747Z","caller":"traceutil/trace.go:171","msg":"trace[356002529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2178; }","duration":"1.19401293s","start":"2026-06-09T13:41:45.143724Z","end":"2026-06-09T13:41:46.337737Z","steps":["trace[356002529] 'agreement among raft nodes before linearized reading' (duration: 1.193941409s)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:46.338135Z","caller":"traceutil/trace.go:171","msg":"trace[1054420137] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2178; }","duration":"1.025643173s","start":"2026-06-09T13:41:45.312483Z","end":"2026-06-09T13:41:46.338126Z","steps":["trace[1054420137] 'agreement among raft nodes before linearized reading' (duration: 1.025581962s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.338151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.14368Z","time spent":"1.194459268s","remote":"127.0.0.1:47548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T13:41:46.33817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.312449Z","time spent":"1.025708214s","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-09T13:41:46.338209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.797703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"warn","ts":"2026-06-09T13:41:46.337801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.50816ms","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-06-09T13:41:46.338264Z","caller":"traceutil/trace.go:171","msg":"trace[1484714642] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2178; }","duration":"596.900885ms","start":"2026-06-09T13:41:45.741349Z","end":"2026-06-09T13:41:46.33825Z","steps":["trace[1484714642] 'agreement among raft nodes before linearized reading' (duration: 596.637731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.338309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.741313Z","time spent":"596.980056ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12404,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-09T13:41:46.338299Z","caller":"traceutil/trace.go:171","msg":"trace[235752950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2178; }","duration":"518.066268ms","start":"2026-06-09T13:41:45.820217Z","end":"2026-06-09T13:41:46.338284Z","steps":["trace[235752950] 'agreement among raft nodes before linearized reading' (duration: 517.474589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.338359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.820196Z","time spent":"518.14594ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T13:41:46.33864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:45.699964Z","time spent":"637.312807ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:46.859601Z","caller":"traceutil/trace.go:171","msg":"trace[1988455284] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"514.516057ms","start":"2026-06-09T13:41:46.345062Z","end":"2026-06-09T13:41:46.859578Z","steps":["trace[1988455284] 'process raft request' (duration: 514.415266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.859738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:46.345052Z","time spent":"514.61748ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:46.859945Z","caller":"traceutil/trace.go:171","msg":"trace[938232454] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"515.219638ms","start":"2026-06-09T13:41:46.344711Z","end":"2026-06-09T13:41:46.85993Z","steps":["trace[938232454] 'process raft request' (duration: 479.033309ms)","trace[938232454] 'compare' (duration: 35.551739ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:41:46.859964Z","caller":"traceutil/trace.go:171","msg":"trace[746849421] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"514.810362ms","start":"2026-06-09T13:41:46.345129Z","end":"2026-06-09T13:41:46.859939Z","steps":["trace[746849421] 'process raft request' (duration: 514.417416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.86001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:46.344692Z","time spent":"515.288709ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:41:46.860116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:46.345121Z","time spent":"514.915554ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:46.860371Z","caller":"traceutil/trace.go:171","msg":"trace[594705614] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2267; }","duration":"512.544729ms","start":"2026-06-09T13:41:46.347815Z","end":"2026-06-09T13:41:46.86036Z","steps":["trace[594705614] 'read index received' (duration: 475.938914ms)","trace[594705614] 'applied index is now lower than readState.Index' (duration: 36.605065ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:41:46.860468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.66044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:41:46.860467Z","caller":"traceutil/trace.go:171","msg":"trace[1599271576] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"515.026705ms","start":"2026-06-09T13:41:46.345419Z","end":"2026-06-09T13:41:46.860446Z","steps":["trace[1599271576] 'process raft request' (duration: 514.441647ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:41:46.86051Z","caller":"traceutil/trace.go:171","msg":"trace[554652563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2182; }","duration":"512.728392ms","start":"2026-06-09T13:41:46.347773Z","end":"2026-06-09T13:41:46.860502Z","steps":["trace[554652563] 'agreement among raft nodes before linearized reading' (duration: 512.662431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.860502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.906631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:41:46.860536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:46.347762Z","time spent":"512.768312ms","remote":"127.0.0.1:47548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T13:41:46.860534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.139424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T13:41:46.860547Z","caller":"traceutil/trace.go:171","msg":"trace[1458823788] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2182; }","duration":"507.971592ms","start":"2026-06-09T13:41:46.352564Z","end":"2026-06-09T13:41:46.860535Z","steps":["trace[1458823788] 'agreement among raft nodes before linearized reading' (duration: 507.894721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.860557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:46.34541Z","time spent":"515.105056ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:46.860574Z","caller":"traceutil/trace.go:171","msg":"trace[1938247982] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2182; }","duration":"176.222765ms","start":"2026-06-09T13:41:46.684342Z","end":"2026-06-09T13:41:46.860565Z","steps":["trace[1938247982] 'agreement among raft nodes before linearized reading' (duration: 176.129843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.860579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:46.352556Z","time spent":"508.016013ms","remote":"127.0.0.1:48032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-09T13:41:46.860774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.690987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-09T13:41:46.860852Z","caller":"traceutil/trace.go:171","msg":"trace[1953635901] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2182; }","duration":"303.810078ms","start":"2026-06-09T13:41:46.557009Z","end":"2026-06-09T13:41:46.860819Z","steps":["trace[1953635901] 'agreement among raft nodes before linearized reading' (duration: 303.605955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:46.860886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:46.556981Z","time spent":"303.89833ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-09T13:41:47.493614Z","caller":"traceutil/trace.go:171","msg":"trace[2042442993] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"589.612009ms","start":"2026-06-09T13:41:46.903966Z","end":"2026-06-09T13:41:47.493578Z","steps":["trace[2042442993] 'process raft request' (duration: 588.361081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:47.494285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:41:46.903927Z","time spent":"590.145656ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:41:47.50071Z","caller":"traceutil/trace.go:171","msg":"trace[1188787414] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2272; }","duration":"252.176841ms","start":"2026-06-09T13:41:47.248515Z","end":"2026-06-09T13:41:47.500692Z","steps":["trace[1188787414] 'read index received' (duration: 243.745798ms)","trace[1188787414] 'applied index is now lower than readState.Index' (duration: 8.429893ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:41:47.500896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.379894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T13:41:47.50095Z","caller":"traceutil/trace.go:171","msg":"trace[1648062420] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2184; }","duration":"252.470985ms","start":"2026-06-09T13:41:47.248457Z","end":"2026-06-09T13:41:47.500928Z","steps":["trace[1648062420] 'agreement among raft nodes before linearized reading' (duration: 252.354203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:41:47.501041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.504989ms","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-06-09T13:41:47.501121Z","caller":"traceutil/trace.go:171","msg":"trace[812711896] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2184; }","duration":"154.630661ms","start":"2026-06-09T13:41:47.346472Z","end":"2026-06-09T13:41:47.501102Z","steps":["trace[812711896] 'agreement among raft nodes before linearized reading' (duration: 154.418318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:42:34.507152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.191964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-4jqxb\" ","response":"range_response_count:1 size:7665"} {"level":"info","ts":"2026-06-09T13:42:34.507204Z","caller":"traceutil/trace.go:171","msg":"trace[73070042] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-4jqxb; range_end:; response_count:1; response_revision:2484; }","duration":"223.279335ms","start":"2026-06-09T13:42:34.283912Z","end":"2026-06-09T13:42:34.507191Z","steps":["trace[73070042] 'range keys from in-memory index tree' (duration: 223.022221ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:43:16.832728Z","caller":"traceutil/trace.go:171","msg":"trace[2095957076] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"445.325135ms","start":"2026-06-09T13:43:16.387374Z","end":"2026-06-09T13:43:16.832699Z","steps":["trace[2095957076] 'process raft request' (duration: 445.150582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:16.833129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:16.387359Z","time spent":"445.633288ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:43:17.537598Z","caller":"traceutil/trace.go:171","msg":"trace[1602475006] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"768.268685ms","start":"2026-06-09T13:43:16.769305Z","end":"2026-06-09T13:43:17.537574Z","steps":["trace[1602475006] 'process raft request' (duration: 768.188434ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:43:17.53767Z","caller":"traceutil/trace.go:171","msg":"trace[154391232] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"1.147219818s","start":"2026-06-09T13:43:16.390403Z","end":"2026-06-09T13:43:17.537622Z","steps":["trace[154391232] 'process raft request' (duration: 1.146953645s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.538203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013560725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:43:17.538268Z","caller":"traceutil/trace.go:171","msg":"trace[1545076324] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2774; }","duration":"1.013670406s","start":"2026-06-09T13:43:16.52458Z","end":"2026-06-09T13:43:17.53825Z","steps":["trace[1545076324] 'agreement among raft nodes before linearized reading' (duration: 1.013551265s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.538305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:16.524554Z","time spent":"1.013741167s","remote":"127.0.0.1:45956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-06-09T13:43:17.537924Z","caller":"traceutil/trace.go:171","msg":"trace[1270247257] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"752.844898ms","start":"2026-06-09T13:43:16.785051Z","end":"2026-06-09T13:43:17.537896Z","steps":["trace[1270247257] 'process raft request' (duration: 752.487534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.53855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:16.390393Z","time spent":"1.147779594s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:43:17.538573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:16.785017Z","time spent":"753.391595ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:43:17.537904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:16.769265Z","time spent":"768.422146ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:43:17.538089Z","caller":"traceutil/trace.go:171","msg":"trace[1596643405] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"747.394692ms","start":"2026-06-09T13:43:16.790677Z","end":"2026-06-09T13:43:17.538072Z","steps":["trace[1596643405] 'process raft request' (duration: 747.179619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.539009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:16.790667Z","time spent":"748.274774ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:43:17.538081Z","caller":"traceutil/trace.go:171","msg":"trace[1569615808] linearizableReadLoop","detail":"{readStateIndex:2879; appliedIndex:2877; }","duration":"1.013428823s","start":"2026-06-09T13:43:16.524627Z","end":"2026-06-09T13:43:17.538056Z","steps":["trace[1569615808] 'read index received' (duration: 307.873574ms)","trace[1569615808] 'applied index is now lower than readState.Index' (duration: 705.553859ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:43:17.538092Z","caller":"traceutil/trace.go:171","msg":"trace[1368177090] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"741.35372ms","start":"2026-06-09T13:43:16.796725Z","end":"2026-06-09T13:43:17.538078Z","steps":["trace[1368177090] 'process raft request' (duration: 741.248509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.539962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:16.796701Z","time spent":"743.213052ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:43:17.537922Z","caller":"traceutil/trace.go:171","msg":"trace[1143611721] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"749.488758ms","start":"2026-06-09T13:43:16.788407Z","end":"2026-06-09T13:43:17.537896Z","steps":["trace[1143611721] 'process raft request' (duration: 749.297666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.540336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:16.788385Z","time spent":"751.897017ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:43:17.791672Z","caller":"traceutil/trace.go:171","msg":"trace[1790753584] linearizableReadLoop","detail":"{readStateIndex:2885; appliedIndex:2884; }","duration":"251.828616ms","start":"2026-06-09T13:43:17.53981Z","end":"2026-06-09T13:43:17.791638Z","steps":["trace[1790753584] 'read index received' (duration: 176.298685ms)","trace[1790753584] 'applied index is now lower than readState.Index' (duration: 75.527591ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:43:17.791819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.030586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T13:43:17.791833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148247078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:43:17.791869Z","caller":"traceutil/trace.go:171","msg":"trace[1280268372] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2774; }","duration":"1.148295259s","start":"2026-06-09T13:43:16.643563Z","end":"2026-06-09T13:43:17.791858Z","steps":["trace[1280268372] 'agreement among raft nodes before linearized reading' (duration: 1.148199807s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.791974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:16.643507Z","time spent":"1.14845187s","remote":"127.0.0.1:53102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-06-09T13:43:17.792009Z","caller":"traceutil/trace.go:171","msg":"trace[2061096457] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2774; }","duration":"430.169147ms","start":"2026-06-09T13:43:17.361679Z","end":"2026-06-09T13:43:17.791848Z","steps":["trace[2061096457] 'agreement among raft nodes before linearized reading' (duration: 430.033376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.792132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:17.361645Z","time spent":"430.475461ms","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-06-09T13:43:17.792275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.81593ms","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-06-09T13:43:17.792415Z","caller":"traceutil/trace.go:171","msg":"trace[725362056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2774; }","duration":"473.028983ms","start":"2026-06-09T13:43:17.31936Z","end":"2026-06-09T13:43:17.792389Z","steps":["trace[725362056] 'agreement among raft nodes before linearized reading' (duration: 472.566987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.792514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:17.3193Z","time spent":"473.198835ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T13:43:17.7927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.978263ms","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-06-09T13:43:17.792713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.544404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-09T13:43:17.792734Z","caller":"traceutil/trace.go:171","msg":"trace[1848990934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2774; }","duration":"469.067195ms","start":"2026-06-09T13:43:17.323659Z","end":"2026-06-09T13:43:17.792726Z","steps":["trace[1848990934] 'agreement among raft nodes before linearized reading' (duration: 468.971714ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:43:17.792751Z","caller":"traceutil/trace.go:171","msg":"trace[936666858] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2774; }","duration":"695.628705ms","start":"2026-06-09T13:43:17.097112Z","end":"2026-06-09T13:43:17.792741Z","steps":["trace[936666858] 'agreement among raft nodes before linearized reading' (duration: 695.22478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.792784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:17.097088Z","time spent":"695.689636ms","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-06-09T13:43:17.792756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:17.323617Z","time spent":"469.134725ms","remote":"127.0.0.1:47846","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-06-09T13:43:17.793063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.012933ms","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-06-09T13:43:17.793092Z","caller":"traceutil/trace.go:171","msg":"trace[578529728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2774; }","duration":"439.097194ms","start":"2026-06-09T13:43:17.353987Z","end":"2026-06-09T13:43:17.793084Z","steps":["trace[578529728] 'agreement among raft nodes before linearized reading' (duration: 438.997673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.793111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:17.35396Z","time spent":"439.146624ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T13:43:17.793296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.964512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-09T13:43:17.793323Z","caller":"traceutil/trace.go:171","msg":"trace[283716679] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2774; }","duration":"741.040843ms","start":"2026-06-09T13:43:17.052276Z","end":"2026-06-09T13:43:17.793317Z","steps":["trace[283716679] 'agreement among raft nodes before linearized reading' (duration: 740.968362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.793343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:17.052247Z","time spent":"741.090373ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-09T13:43:17.795006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.482423ms","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-06-09T13:43:17.795128Z","caller":"traceutil/trace.go:171","msg":"trace[1478050131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2774; }","duration":"446.633985ms","start":"2026-06-09T13:43:17.348479Z","end":"2026-06-09T13:43:17.795113Z","steps":["trace[1478050131] 'agreement among raft nodes before linearized reading' (duration: 443.195063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:43:17.795234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:43:17.348465Z","time spent":"446.754126ms","remote":"127.0.0.1:47846","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":"info","ts":"2026-06-09T13:45:46.262668Z","caller":"traceutil/trace.go:171","msg":"trace[60074779] transaction","detail":"{read_only:false; response_revision:3642; number_of_response:1; }","duration":"127.249472ms","start":"2026-06-09T13:45:46.135393Z","end":"2026-06-09T13:45:46.262642Z","steps":["trace[60074779] 'process raft request' (duration: 127.089609ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:45:46.268146Z","caller":"traceutil/trace.go:171","msg":"trace[550584014] transaction","detail":"{read_only:false; response_revision:3643; number_of_response:1; }","duration":"126.521147ms","start":"2026-06-09T13:45:46.141602Z","end":"2026-06-09T13:45:46.268123Z","steps":["trace[550584014] 'process raft request' (duration: 126.124679ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:45:46.268405Z","caller":"traceutil/trace.go:171","msg":"trace[1115942772] transaction","detail":"{read_only:false; response_revision:3644; number_of_response:1; }","duration":"123.392561ms","start":"2026-06-09T13:45:46.145003Z","end":"2026-06-09T13:45:46.268395Z","steps":["trace[1115942772] 'process raft request' (duration: 122.84382ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:45:46.268798Z","caller":"traceutil/trace.go:171","msg":"trace[1567703486] transaction","detail":"{read_only:false; response_revision:3645; number_of_response:1; }","duration":"121.89177ms","start":"2026-06-09T13:45:46.146881Z","end":"2026-06-09T13:45:46.268773Z","steps":["trace[1567703486] 'process raft request' (duration: 121.205585ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:45:46.268661Z","caller":"traceutil/trace.go:171","msg":"trace[1628976295] linearizableReadLoop","detail":"{readStateIndex:3788; appliedIndex:3786; }","duration":"126.314453ms","start":"2026-06-09T13:45:46.142334Z","end":"2026-06-09T13:45:46.268648Z","steps":["trace[1628976295] 'read index received' (duration: 120.138674ms)","trace[1628976295] 'applied index is now lower than readState.Index' (duration: 6.174909ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:45:46.268981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.627369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:45:46.269069Z","caller":"traceutil/trace.go:171","msg":"trace[943925788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3645; }","duration":"126.744911ms","start":"2026-06-09T13:45:46.142309Z","end":"2026-06-09T13:45:46.269054Z","steps":["trace[943925788] 'agreement among raft nodes before linearized reading' (duration: 126.612429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:03.538258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.244709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-06-09T13:47:03.538331Z","caller":"traceutil/trace.go:171","msg":"trace[2052518490] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4570; }","duration":"141.340241ms","start":"2026-06-09T13:47:03.396975Z","end":"2026-06-09T13:47:03.538316Z","steps":["trace[2052518490] 'range keys from in-memory index tree' (duration: 139.860316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:03.538358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.124317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-06-09T13:47:03.538445Z","caller":"traceutil/trace.go:171","msg":"trace[598605557] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4570; }","duration":"141.2358ms","start":"2026-06-09T13:47:03.397189Z","end":"2026-06-09T13:47:03.538424Z","steps":["trace[598605557] 'range keys from in-memory index tree' (duration: 139.874866ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:03.555667Z","caller":"traceutil/trace.go:171","msg":"trace[1927046429] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"157.009329ms","start":"2026-06-09T13:47:03.398637Z","end":"2026-06-09T13:47:03.555647Z","steps":["trace[1927046429] 'process raft request' (duration: 156.897807ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:03.717225Z","caller":"traceutil/trace.go:171","msg":"trace[1320023673] linearizableReadLoop","detail":"{readStateIndex:4758; appliedIndex:4754; }","duration":"172.072465ms","start":"2026-06-09T13:47:03.545132Z","end":"2026-06-09T13:47:03.717205Z","steps":["trace[1320023673] 'read index received' (duration: 10.564736ms)","trace[1320023673] 'applied index is now lower than readState.Index' (duration: 161.506979ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:47:03.717388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.236368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-06-09T13:47:03.71742Z","caller":"traceutil/trace.go:171","msg":"trace[181652669] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4575; }","duration":"172.309489ms","start":"2026-06-09T13:47:03.5451Z","end":"2026-06-09T13:47:03.717409Z","steps":["trace[181652669] 'agreement among raft nodes before linearized reading' (duration: 172.180207ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:03.717703Z","caller":"traceutil/trace.go:171","msg":"trace[719163203] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"312.914128ms","start":"2026-06-09T13:47:03.404781Z","end":"2026-06-09T13:47:03.717696Z","steps":["trace[719163203] 'process raft request' (duration: 312.202026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:03.717759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:03.404769Z","time spent":"312.957339ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14361,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:47:03.71785Z","caller":"traceutil/trace.go:171","msg":"trace[2145907262] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"312.782395ms","start":"2026-06-09T13:47:03.405061Z","end":"2026-06-09T13:47:03.717843Z","steps":["trace[2145907262] 'process raft request' (duration: 312.042883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:03.717889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:03.405056Z","time spent":"312.814126ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":966,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:47:03.717993Z","caller":"traceutil/trace.go:171","msg":"trace[744731045] transaction","detail":"{read_only:false; response_revision:4574; number_of_response:1; }","duration":"312.890908ms","start":"2026-06-09T13:47:03.405096Z","end":"2026-06-09T13:47:03.717987Z","steps":["trace[744731045] 'process raft request' (duration: 312.049033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:03.718037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:03.405093Z","time spent":"312.919038ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":966,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:47:03.718235Z","caller":"traceutil/trace.go:171","msg":"trace[233957182] transaction","detail":"{read_only:false; response_revision:4575; number_of_response:1; }","duration":"225.553602ms","start":"2026-06-09T13:47:03.492674Z","end":"2026-06-09T13:47:03.718228Z","steps":["trace[233957182] 'process raft request' (duration: 224.499574ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:05.033751Z","caller":"traceutil/trace.go:171","msg":"trace[618957307] transaction","detail":"{read_only:false; response_revision:4595; number_of_response:1; }","duration":"446.515088ms","start":"2026-06-09T13:47:04.587222Z","end":"2026-06-09T13:47:05.033737Z","steps":["trace[618957307] 'process raft request' (duration: 446.426797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:05.033856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:04.587206Z","time spent":"446.596529ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:05.066445Z","caller":"traceutil/trace.go:171","msg":"trace[1323059013] linearizableReadLoop","detail":"{readStateIndex:4781; appliedIndex:4778; }","duration":"441.873176ms","start":"2026-06-09T13:47:04.624546Z","end":"2026-06-09T13:47:05.066419Z","steps":["trace[1323059013] 'read index received' (duration: 409.590086ms)","trace[1323059013] 'applied index is now lower than readState.Index' (duration: 32.28218ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:05.066623Z","caller":"traceutil/trace.go:171","msg":"trace[809617292] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"470.792676ms","start":"2026-06-09T13:47:04.595814Z","end":"2026-06-09T13:47:05.066607Z","steps":["trace[809617292] 'process raft request' (duration: 470.539982ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:05.066675Z","caller":"traceutil/trace.go:171","msg":"trace[351541738] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"471.467249ms","start":"2026-06-09T13:47:04.595194Z","end":"2026-06-09T13:47:05.066661Z","steps":["trace[351541738] 'process raft request' (duration: 449.960469ms)","trace[351541738] 'compare' (duration: 20.94813ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:47:05.066764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:04.595788Z","time spent":"470.885198ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:47:05.066819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:04.595184Z","time spent":"471.56063ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:05.066609Z","caller":"traceutil/trace.go:171","msg":"trace[133508214] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"470.99134ms","start":"2026-06-09T13:47:04.595595Z","end":"2026-06-09T13:47:05.066586Z","steps":["trace[133508214] 'process raft request' (duration: 470.697985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:05.067006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.546199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88482547-f9b8-43f1-a56c-1ee05fb3d8cb\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-06-09T13:47:05.067078Z","caller":"traceutil/trace.go:171","msg":"trace[1382654295] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88482547-f9b8-43f1-a56c-1ee05fb3d8cb; range_end:; response_count:1; response_revision:4598; }","duration":"411.655821ms","start":"2026-06-09T13:47:04.655405Z","end":"2026-06-09T13:47:05.06706Z","steps":["trace[1382654295] 'agreement among raft nodes before linearized reading' (duration: 411.340445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:05.067123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:04.655393Z","time spent":"411.721612ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3795,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88482547-f9b8-43f1-a56c-1ee05fb3d8cb\" "} {"level":"warn","ts":"2026-06-09T13:47:05.067106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.560327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T13:47:05.067175Z","caller":"traceutil/trace.go:171","msg":"trace[1078837929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4598; }","duration":"442.658539ms","start":"2026-06-09T13:47:04.624503Z","end":"2026-06-09T13:47:05.067161Z","steps":["trace[1078837929] 'agreement among raft nodes before linearized reading' (duration: 442.537866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:05.067207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:04.624488Z","time spent":"442.71089ms","remote":"127.0.0.1:47846","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-06-09T13:47:05.067394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.683855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eb3121ef-5d00-4e7c-b1f7-1c719d9c6c19\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-06-09T13:47:05.067426Z","caller":"traceutil/trace.go:171","msg":"trace[147226639] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eb3121ef-5d00-4e7c-b1f7-1c719d9c6c19; range_end:; response_count:1; response_revision:4598; }","duration":"289.746677ms","start":"2026-06-09T13:47:04.777669Z","end":"2026-06-09T13:47:05.067416Z","steps":["trace[147226639] 'agreement among raft nodes before linearized reading' (duration: 289.647725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:05.067019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:04.59559Z","time spent":"471.380476ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:05.172885Z","caller":"traceutil/trace.go:171","msg":"trace[484694483] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"100.210137ms","start":"2026-06-09T13:47:05.072653Z","end":"2026-06-09T13:47:05.172863Z","steps":["trace[484694483] 'process raft request' (duration: 96.43326ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:05.173205Z","caller":"traceutil/trace.go:171","msg":"trace[893736617] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"100.011195ms","start":"2026-06-09T13:47:05.073168Z","end":"2026-06-09T13:47:05.173179Z","steps":["trace[893736617] 'process raft request' (duration: 99.631408ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:05.17356Z","caller":"traceutil/trace.go:171","msg":"trace[1942706017] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"100.229138ms","start":"2026-06-09T13:47:05.073305Z","end":"2026-06-09T13:47:05.173534Z","steps":["trace[1942706017] 'process raft request' (duration: 100.023434ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:05.174446Z","caller":"traceutil/trace.go:171","msg":"trace[1124121213] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"101.050742ms","start":"2026-06-09T13:47:05.073292Z","end":"2026-06-09T13:47:05.174343Z","steps":["trace[1124121213] 'process raft request' (duration: 99.78881ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:05.810406Z","caller":"traceutil/trace.go:171","msg":"trace[1762986212] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"138.239558ms","start":"2026-06-09T13:47:05.672142Z","end":"2026-06-09T13:47:05.810381Z","steps":["trace[1762986212] 'process raft request' (duration: 138.027465ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:07.907006Z","caller":"traceutil/trace.go:171","msg":"trace[755619418] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"211.389257ms","start":"2026-06-09T13:47:07.69559Z","end":"2026-06-09T13:47:07.906979Z","steps":["trace[755619418] 'process raft request' (duration: 211.158943ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:07.968776Z","caller":"traceutil/trace.go:171","msg":"trace[316975560] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"265.456326ms","start":"2026-06-09T13:47:07.703261Z","end":"2026-06-09T13:47:07.968718Z","steps":["trace[316975560] 'process raft request' (duration: 254.88675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:14.251444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220399544173481,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T13:47:14.411805Z","caller":"traceutil/trace.go:171","msg":"trace[1746447577] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"830.814184ms","start":"2026-06-09T13:47:13.580965Z","end":"2026-06-09T13:47:14.411779Z","steps":["trace[1746447577] 'process raft request' (duration: 830.643491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:14.411951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:13.580936Z","time spent":"830.938327ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11243,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:14.47763Z","caller":"traceutil/trace.go:171","msg":"trace[2086327574] linearizableReadLoop","detail":"{readStateIndex:4922; appliedIndex:4920; }","duration":"726.271493ms","start":"2026-06-09T13:47:13.75133Z","end":"2026-06-09T13:47:14.477601Z","steps":["trace[2086327574] 'read index received' (duration: 660.343879ms)","trace[2086327574] 'applied index is now lower than readState.Index' (duration: 65.926604ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:14.477718Z","caller":"traceutil/trace.go:171","msg":"trace[1811989757] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"273.003761ms","start":"2026-06-09T13:47:14.204681Z","end":"2026-06-09T13:47:14.477685Z","steps":["trace[1811989757] 'process raft request' (duration: 272.732457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:14.477979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.392812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:47:14.477997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.257372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6c55c44c86-kzcnc.18b76dbc33f32c18\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-06-09T13:47:14.478031Z","caller":"traceutil/trace.go:171","msg":"trace[210017503] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4733; }","duration":"340.533374ms","start":"2026-06-09T13:47:14.137484Z","end":"2026-06-09T13:47:14.478017Z","steps":["trace[210017503] 'agreement among raft nodes before linearized reading' (duration: 340.417452ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:14.478053Z","caller":"traceutil/trace.go:171","msg":"trace[1273115910] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6c55c44c86-kzcnc.18b76dbc33f32c18; range_end:; response_count:1; response_revision:4733; }","duration":"726.346914ms","start":"2026-06-09T13:47:13.75169Z","end":"2026-06-09T13:47:14.478037Z","steps":["trace[1273115910] 'agreement among raft nodes before linearized reading' (duration: 726.160801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:14.478073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:14.137449Z","time spent":"340.613656ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-09T13:47:14.478105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:13.751682Z","time spent":"726.413965ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":918,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6c55c44c86-kzcnc.18b76dbc33f32c18\" "} {"level":"warn","ts":"2026-06-09T13:47:14.478101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.388979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:47:14.478241Z","caller":"traceutil/trace.go:171","msg":"trace[732047502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4733; }","duration":"334.604021ms","start":"2026-06-09T13:47:14.143609Z","end":"2026-06-09T13:47:14.478213Z","steps":["trace[732047502] 'agreement among raft nodes before linearized reading' (duration: 334.267856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:14.478306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:14.143576Z","time spent":"334.720774ms","remote":"127.0.0.1:47548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T13:47:14.478299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.306265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-06-09T13:47:14.478468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.917626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-09T13:47:14.478525Z","caller":"traceutil/trace.go:171","msg":"trace[1616506154] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4733; }","duration":"194.588709ms","start":"2026-06-09T13:47:14.283914Z","end":"2026-06-09T13:47:14.478503Z","steps":["trace[1616506154] 'agreement among raft nodes before linearized reading' (duration: 194.110311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:14.478459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.526527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-06-09T13:47:14.478618Z","caller":"traceutil/trace.go:171","msg":"trace[1809403670] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4733; }","duration":"727.278081ms","start":"2026-06-09T13:47:13.751323Z","end":"2026-06-09T13:47:14.478601Z","steps":["trace[1809403670] 'agreement among raft nodes before linearized reading' (duration: 726.422065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:14.47866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:13.751304Z","time spent":"727.345772ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12995,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-06-09T13:47:14.478653Z","caller":"traceutil/trace.go:171","msg":"trace[1946566428] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4733; }","duration":"653.060579ms","start":"2026-06-09T13:47:13.825477Z","end":"2026-06-09T13:47:14.478538Z","steps":["trace[1946566428] 'agreement among raft nodes before linearized reading' (duration: 652.53213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:14.479388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:13.825462Z","time spent":"653.887854ms","remote":"127.0.0.1:47734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-06-09T13:47:14.663369Z","caller":"traceutil/trace.go:171","msg":"trace[2086564938] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"106.248914ms","start":"2026-06-09T13:47:14.557086Z","end":"2026-06-09T13:47:14.663335Z","steps":["trace[2086564938] 'process raft request' (duration: 105.759426ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:14.663393Z","caller":"traceutil/trace.go:171","msg":"trace[1295238212] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"106.421799ms","start":"2026-06-09T13:47:14.556948Z","end":"2026-06-09T13:47:14.66337Z","steps":["trace[1295238212] 'process raft request' (duration: 105.742487ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:14.663565Z","caller":"traceutil/trace.go:171","msg":"trace[1507369003] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"106.47903ms","start":"2026-06-09T13:47:14.557062Z","end":"2026-06-09T13:47:14.663541Z","steps":["trace[1507369003] 'process raft request' (duration: 105.746726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:15.143301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.660096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-88482547-f9b8-43f1-a56c-1ee05fb3d8cb\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-06-09T13:47:15.143386Z","caller":"traceutil/trace.go:171","msg":"trace[885698017] range","detail":"{range_begin:/registry/persistentvolumes/pvc-88482547-f9b8-43f1-a56c-1ee05fb3d8cb; range_end:; response_count:1; response_revision:4740; }","duration":"282.789348ms","start":"2026-06-09T13:47:14.860575Z","end":"2026-06-09T13:47:15.143364Z","steps":["trace[885698017] 'range keys from in-memory index tree' (duration: 282.491103ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:15.282032Z","caller":"traceutil/trace.go:171","msg":"trace[578434710] linearizableReadLoop","detail":"{readStateIndex:4933; appliedIndex:4930; }","duration":"111.645246ms","start":"2026-06-09T13:47:15.170362Z","end":"2026-06-09T13:47:15.282007Z","steps":["trace[578434710] 'read index received' (duration: 80.813292ms)","trace[578434710] 'applied index is now lower than readState.Index' (duration: 30.831104ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:15.282144Z","caller":"traceutil/trace.go:171","msg":"trace[1868851867] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"175.089476ms","start":"2026-06-09T13:47:15.107009Z","end":"2026-06-09T13:47:15.282098Z","steps":["trace[1868851867] 'process raft request' (duration: 144.238511ms)","trace[1868851867] 'compare' (duration: 30.53803ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:47:15.282341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.949631ms","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-06-09T13:47:15.282404Z","caller":"traceutil/trace.go:171","msg":"trace[597114535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4744; }","duration":"112.060703ms","start":"2026-06-09T13:47:15.170329Z","end":"2026-06-09T13:47:15.28239Z","steps":["trace[597114535] 'agreement among raft nodes before linearized reading' (duration: 111.86323ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:15.282464Z","caller":"traceutil/trace.go:171","msg":"trace[1127552686] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"174.313853ms","start":"2026-06-09T13:47:15.108139Z","end":"2026-06-09T13:47:15.282453Z","steps":["trace[1127552686] 'process raft request' (duration: 173.781094ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:15.282506Z","caller":"traceutil/trace.go:171","msg":"trace[1525036517] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"173.972837ms","start":"2026-06-09T13:47:15.108525Z","end":"2026-06-09T13:47:15.282498Z","steps":["trace[1525036517] 'process raft request' (duration: 173.437878ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:15.445892Z","caller":"traceutil/trace.go:171","msg":"trace[1229529417] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"156.271671ms","start":"2026-06-09T13:47:15.289589Z","end":"2026-06-09T13:47:15.445861Z","steps":["trace[1229529417] 'process raft request' (duration: 156.22468ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:15.446335Z","caller":"traceutil/trace.go:171","msg":"trace[1691909726] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"157.412789ms","start":"2026-06-09T13:47:15.288911Z","end":"2026-06-09T13:47:15.446323Z","steps":["trace[1691909726] 'process raft request' (duration: 156.015035ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:15.446483Z","caller":"traceutil/trace.go:171","msg":"trace[1103605382] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"157.41134ms","start":"2026-06-09T13:47:15.289062Z","end":"2026-06-09T13:47:15.446474Z","steps":["trace[1103605382] 'process raft request' (duration: 156.660267ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:15.446609Z","caller":"traceutil/trace.go:171","msg":"trace[1657940482] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"157.263267ms","start":"2026-06-09T13:47:15.289337Z","end":"2026-06-09T13:47:15.4466Z","steps":["trace[1657940482] 'process raft request' (duration: 156.440533ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:15.450573Z","caller":"traceutil/trace.go:171","msg":"trace[781980020] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"105.313165ms","start":"2026-06-09T13:47:15.345238Z","end":"2026-06-09T13:47:15.450551Z","steps":["trace[781980020] 'process raft request' (duration: 105.203883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:15.661001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.556478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T13:47:15.661116Z","caller":"traceutil/trace.go:171","msg":"trace[1121140124] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"187.40612ms","start":"2026-06-09T13:47:15.473689Z","end":"2026-06-09T13:47:15.661096Z","steps":["trace[1121140124] 'process raft request' (duration: 76.68087ms)","trace[1121140124] 'compare' (duration: 110.382495ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:17.39067Z","caller":"traceutil/trace.go:171","msg":"trace[110436640] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"102.009363ms","start":"2026-06-09T13:47:17.288646Z","end":"2026-06-09T13:47:17.390656Z","steps":["trace[110436640] 'process raft request' (duration: 101.503984ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:17.390832Z","caller":"traceutil/trace.go:171","msg":"trace[1827763269] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"101.24633ms","start":"2026-06-09T13:47:17.289578Z","end":"2026-06-09T13:47:17.390825Z","steps":["trace[1827763269] 'process raft request' (duration: 100.6688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:19.089183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.724487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63ede075-5033-4910-af2c-6c847027f5be\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-09T13:47:19.089277Z","caller":"traceutil/trace.go:171","msg":"trace[878948930] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63ede075-5033-4910-af2c-6c847027f5be; range_end:; response_count:1; response_revision:4848; }","duration":"282.87323ms","start":"2026-06-09T13:47:18.806386Z","end":"2026-06-09T13:47:19.08926Z","steps":["trace[878948930] 'range keys from in-memory index tree' (duration: 282.548904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:19.089585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.526528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63ede075-5033-4910-af2c-6c847027f5be\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-09T13:47:19.089673Z","caller":"traceutil/trace.go:171","msg":"trace[720559520] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63ede075-5033-4910-af2c-6c847027f5be; range_end:; response_count:1; response_revision:4848; }","duration":"273.63932ms","start":"2026-06-09T13:47:18.816012Z","end":"2026-06-09T13:47:19.089651Z","steps":["trace[720559520] 'range keys from in-memory index tree' (duration: 272.969638ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:19.462279Z","caller":"traceutil/trace.go:171","msg":"trace[891968425] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"366.024987ms","start":"2026-06-09T13:47:19.096223Z","end":"2026-06-09T13:47:19.462248Z","steps":["trace[891968425] 'process raft request' (duration: 365.772692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:19.462731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:19.096193Z","time spent":"366.369073ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:19.467342Z","caller":"traceutil/trace.go:171","msg":"trace[1890050135] linearizableReadLoop","detail":"{readStateIndex:5040; appliedIndex:5038; }","duration":"136.336109ms","start":"2026-06-09T13:47:19.330979Z","end":"2026-06-09T13:47:19.467315Z","steps":["trace[1890050135] 'read index received' (duration: 130.996227ms)","trace[1890050135] 'applied index is now lower than readState.Index' (duration: 5.338812ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:19.467395Z","caller":"traceutil/trace.go:171","msg":"trace[1412775260] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"117.981012ms","start":"2026-06-09T13:47:19.34935Z","end":"2026-06-09T13:47:19.467331Z","steps":["trace[1412775260] 'process raft request' (duration: 117.87425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:19.467669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.674134ms","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-06-09T13:47:19.467719Z","caller":"traceutil/trace.go:171","msg":"trace[1449972155] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4853; }","duration":"136.784666ms","start":"2026-06-09T13:47:19.330921Z","end":"2026-06-09T13:47:19.467706Z","steps":["trace[1449972155] 'agreement among raft nodes before linearized reading' (duration: 136.523512ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:19.468162Z","caller":"traceutil/trace.go:171","msg":"trace[2106370513] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"283.066817ms","start":"2026-06-09T13:47:19.185072Z","end":"2026-06-09T13:47:19.468139Z","steps":["trace[2106370513] 'process raft request' (duration: 282.025688ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:21.617312Z","caller":"traceutil/trace.go:171","msg":"trace[2130619082] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"142.811922ms","start":"2026-06-09T13:47:21.474478Z","end":"2026-06-09T13:47:21.61729Z","steps":["trace[2130619082] 'process raft request' (duration: 142.620409ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:21.629515Z","caller":"traceutil/trace.go:171","msg":"trace[299819429] linearizableReadLoop","detail":"{readStateIndex:5054; appliedIndex:5051; }","duration":"148.415869ms","start":"2026-06-09T13:47:21.481074Z","end":"2026-06-09T13:47:21.62949Z","steps":["trace[299819429] 'read index received' (duration: 136.672348ms)","trace[299819429] 'applied index is now lower than readState.Index' (duration: 11.742581ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:21.629539Z","caller":"traceutil/trace.go:171","msg":"trace[692446951] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"154.645556ms","start":"2026-06-09T13:47:21.474864Z","end":"2026-06-09T13:47:21.62951Z","steps":["trace[692446951] 'process raft request' (duration: 154.415622ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:21.629549Z","caller":"traceutil/trace.go:171","msg":"trace[1206051173] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"154.363521ms","start":"2026-06-09T13:47:21.475161Z","end":"2026-06-09T13:47:21.629524Z","steps":["trace[1206051173] 'process raft request' (duration: 154.256429ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:21.629633Z","caller":"traceutil/trace.go:171","msg":"trace[392794172] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"153.816832ms","start":"2026-06-09T13:47:21.475806Z","end":"2026-06-09T13:47:21.629622Z","steps":["trace[392794172] 'process raft request' (duration: 153.647629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:21.629832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.193624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T13:47:21.629863Z","caller":"traceutil/trace.go:171","msg":"trace[141159129] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4864; }","duration":"113.273305ms","start":"2026-06-09T13:47:21.516581Z","end":"2026-06-09T13:47:21.629854Z","steps":["trace[141159129] 'agreement among raft nodes before linearized reading' (duration: 113.191243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:21.629835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.591806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T13:47:21.630049Z","caller":"traceutil/trace.go:171","msg":"trace[943177207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4864; }","duration":"143.867111ms","start":"2026-06-09T13:47:21.48617Z","end":"2026-06-09T13:47:21.630037Z","steps":["trace[943177207] 'agreement among raft nodes before linearized reading' (duration: 143.533825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:21.63025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.180732ms","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-06-09T13:47:21.630292Z","caller":"traceutil/trace.go:171","msg":"trace[1958247135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4864; }","duration":"149.257293ms","start":"2026-06-09T13:47:21.481025Z","end":"2026-06-09T13:47:21.630283Z","steps":["trace[1958247135] 'agreement among raft nodes before linearized reading' (duration: 149.131991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:21.630361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.002522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-09T13:47:21.630371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.226262ms","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-06-09T13:47:21.630375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.806408ms","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-06-09T13:47:21.630404Z","caller":"traceutil/trace.go:171","msg":"trace[303516229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4864; }","duration":"142.311484ms","start":"2026-06-09T13:47:21.488084Z","end":"2026-06-09T13:47:21.630395Z","steps":["trace[303516229] 'agreement among raft nodes before linearized reading' (duration: 142.218732ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:21.630402Z","caller":"traceutil/trace.go:171","msg":"trace[872156098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4864; }","duration":"141.082723ms","start":"2026-06-09T13:47:21.489308Z","end":"2026-06-09T13:47:21.630391Z","steps":["trace[872156098] 'agreement among raft nodes before linearized reading' (duration: 140.9517ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:21.630421Z","caller":"traceutil/trace.go:171","msg":"trace[1737229822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4864; }","duration":"140.8759ms","start":"2026-06-09T13:47:21.489536Z","end":"2026-06-09T13:47:21.630412Z","steps":["trace[1737229822] 'agreement among raft nodes before linearized reading' (duration: 140.760547ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:22.726004Z","caller":"traceutil/trace.go:171","msg":"trace[1487409582] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"103.530735ms","start":"2026-06-09T13:47:22.622447Z","end":"2026-06-09T13:47:22.725978Z","steps":["trace[1487409582] 'process raft request' (duration: 99.948483ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:22.726005Z","caller":"traceutil/trace.go:171","msg":"trace[1418515661] linearizableReadLoop","detail":"{readStateIndex:5083; appliedIndex:5082; }","duration":"102.6031ms","start":"2026-06-09T13:47:22.623369Z","end":"2026-06-09T13:47:22.725972Z","steps":["trace[1418515661] 'read index received' (duration: 99.014108ms)","trace[1418515661] 'applied index is now lower than readState.Index' (duration: 3.587692ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:47:22.726254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.859894ms","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-06-09T13:47:22.726308Z","caller":"traceutil/trace.go:171","msg":"trace[1266737015] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4891; }","duration":"102.957305ms","start":"2026-06-09T13:47:22.62334Z","end":"2026-06-09T13:47:22.726297Z","steps":["trace[1266737015] 'agreement among raft nodes before linearized reading' (duration: 102.718821ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:22.727894Z","caller":"traceutil/trace.go:171","msg":"trace[1977806782] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"103.726279ms","start":"2026-06-09T13:47:22.624146Z","end":"2026-06-09T13:47:22.727872Z","steps":["trace[1977806782] 'process raft request' (duration: 103.521625ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:22.727998Z","caller":"traceutil/trace.go:171","msg":"trace[725218524] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"103.655178ms","start":"2026-06-09T13:47:22.624325Z","end":"2026-06-09T13:47:22.72798Z","steps":["trace[725218524] 'process raft request' (duration: 103.501765ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:22.728145Z","caller":"traceutil/trace.go:171","msg":"trace[909934295] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"103.740039ms","start":"2026-06-09T13:47:22.624393Z","end":"2026-06-09T13:47:22.728133Z","steps":["trace[909934295] 'process raft request' (duration: 103.503595ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:22.728265Z","caller":"traceutil/trace.go:171","msg":"trace[52578443] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"103.906701ms","start":"2026-06-09T13:47:22.624353Z","end":"2026-06-09T13:47:22.728259Z","steps":["trace[52578443] 'process raft request' (duration: 103.509545ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:22.728247Z","caller":"traceutil/trace.go:171","msg":"trace[1409194269] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"103.628927ms","start":"2026-06-09T13:47:22.624304Z","end":"2026-06-09T13:47:22.727933Z","steps":["trace[1409194269] 'process raft request' (duration: 103.487515ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:26.217986Z","caller":"traceutil/trace.go:171","msg":"trace[1399601965] linearizableReadLoop","detail":"{readStateIndex:5155; appliedIndex:5154; }","duration":"278.369855ms","start":"2026-06-09T13:47:25.939593Z","end":"2026-06-09T13:47:26.217963Z","steps":["trace[1399601965] 'read index received' (duration: 278.09873ms)","trace[1399601965] 'applied index is now lower than readState.Index' (duration: 270.195µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:26.218118Z","caller":"traceutil/trace.go:171","msg":"trace[1927016547] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"326.13662ms","start":"2026-06-09T13:47:25.891954Z","end":"2026-06-09T13:47:26.21809Z","steps":["trace[1927016547] 'process raft request' (duration: 325.787484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:26.218279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.66305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-06-09T13:47:26.21836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.60149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:47:26.218273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:25.891931Z","time spent":"326.257303ms","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16918,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:26.218408Z","caller":"traceutil/trace.go:171","msg":"trace[2040299572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4961; }","duration":"169.650001ms","start":"2026-06-09T13:47:26.048748Z","end":"2026-06-09T13:47:26.218398Z","steps":["trace[2040299572] 'agreement among raft nodes before linearized reading' (duration: 169.57848ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:26.21837Z","caller":"traceutil/trace.go:171","msg":"trace[488280073] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4961; }","duration":"278.753802ms","start":"2026-06-09T13:47:25.939564Z","end":"2026-06-09T13:47:26.218318Z","steps":["trace[488280073] 'agreement among raft nodes before linearized reading' (duration: 278.585479ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:26.439887Z","caller":"traceutil/trace.go:171","msg":"trace[220647195] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"168.482382ms","start":"2026-06-09T13:47:26.271381Z","end":"2026-06-09T13:47:26.439864Z","steps":["trace[220647195] 'process raft request' (duration: 168.343389ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:31.775201Z","caller":"traceutil/trace.go:171","msg":"trace[113014176] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"117.986346ms","start":"2026-06-09T13:47:31.657178Z","end":"2026-06-09T13:47:31.775165Z","steps":["trace[113014176] 'process raft request' (duration: 117.767592ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:31.8781Z","caller":"traceutil/trace.go:171","msg":"trace[983438102] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"219.820299ms","start":"2026-06-09T13:47:31.658251Z","end":"2026-06-09T13:47:31.878071Z","steps":["trace[983438102] 'process raft request' (duration: 155.806706ms)","trace[983438102] 'compare' (duration: 63.813339ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:31.878314Z","caller":"traceutil/trace.go:171","msg":"trace[501268447] linearizableReadLoop","detail":"{readStateIndex:5197; appliedIndex:5194; }","duration":"205.545797ms","start":"2026-06-09T13:47:31.672748Z","end":"2026-06-09T13:47:31.878294Z","steps":["trace[501268447] 'read index received' (duration: 103.136605ms)","trace[501268447] 'applied index is now lower than readState.Index' (duration: 102.408282ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:31.878467Z","caller":"traceutil/trace.go:171","msg":"trace[1565795502] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"220.080853ms","start":"2026-06-09T13:47:31.658372Z","end":"2026-06-09T13:47:31.878453Z","steps":["trace[1565795502] 'process raft request' (duration: 219.647356ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:31.878465Z","caller":"traceutil/trace.go:171","msg":"trace[1326398490] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"219.545354ms","start":"2026-06-09T13:47:31.658849Z","end":"2026-06-09T13:47:31.878394Z","steps":["trace[1326398490] 'process raft request' (duration: 219.350541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:31.878516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.751281ms","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-06-09T13:47:31.878546Z","caller":"traceutil/trace.go:171","msg":"trace[1138691315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5002; }","duration":"205.823952ms","start":"2026-06-09T13:47:31.672714Z","end":"2026-06-09T13:47:31.878538Z","steps":["trace[1138691315] 'agreement among raft nodes before linearized reading' (duration: 205.68829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:31.878671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.181039ms","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-06-09T13:47:31.878752Z","caller":"traceutil/trace.go:171","msg":"trace[1658078753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5002; }","duration":"199.297161ms","start":"2026-06-09T13:47:31.679436Z","end":"2026-06-09T13:47:31.878733Z","steps":["trace[1658078753] 'agreement among raft nodes before linearized reading' (duration: 199.050177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:31.878761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.211299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T13:47:31.878811Z","caller":"traceutil/trace.go:171","msg":"trace[656012396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5002; }","duration":"196.266609ms","start":"2026-06-09T13:47:31.682517Z","end":"2026-06-09T13:47:31.878784Z","steps":["trace[656012396] 'agreement among raft nodes before linearized reading' (duration: 196.170458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:31.878828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.652764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-09T13:47:31.878872Z","caller":"traceutil/trace.go:171","msg":"trace[320250261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5002; }","duration":"197.750506ms","start":"2026-06-09T13:47:31.68111Z","end":"2026-06-09T13:47:31.87886Z","steps":["trace[320250261] 'agreement among raft nodes before linearized reading' (duration: 197.581823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:31.878895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.069931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:47:31.878917Z","caller":"traceutil/trace.go:171","msg":"trace[1413257458] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5002; }","duration":"159.158313ms","start":"2026-06-09T13:47:31.71975Z","end":"2026-06-09T13:47:31.878909Z","steps":["trace[1413257458] 'agreement among raft nodes before linearized reading' (duration: 159.117672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:31.87908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.540785ms","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-06-09T13:47:31.879221Z","caller":"traceutil/trace.go:171","msg":"trace[2093019236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5002; }","duration":"199.720018ms","start":"2026-06-09T13:47:31.679486Z","end":"2026-06-09T13:47:31.879206Z","steps":["trace[2093019236] 'agreement among raft nodes before linearized reading' (duration: 199.20938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:32.211973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.449726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T13:47:32.212379Z","caller":"traceutil/trace.go:171","msg":"trace[1514427120] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"327.26543ms","start":"2026-06-09T13:47:31.885087Z","end":"2026-06-09T13:47:32.212353Z","steps":["trace[1514427120] 'process raft request' (duration: 327.047287ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:32.212388Z","caller":"traceutil/trace.go:171","msg":"trace[489512432] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"328.016103ms","start":"2026-06-09T13:47:31.884348Z","end":"2026-06-09T13:47:32.212364Z","steps":["trace[489512432] 'process raft request' (duration: 201.092579ms)","trace[489512432] 'compare' (duration: 126.208692ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:32.212492Z","caller":"traceutil/trace.go:171","msg":"trace[78097244] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"281.347963ms","start":"2026-06-09T13:47:31.931124Z","end":"2026-06-09T13:47:32.212472Z","steps":["trace[78097244] 'process raft request' (duration: 281.082899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:32.212535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:31.88432Z","time spent":"328.123435ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:32.212518Z","caller":"traceutil/trace.go:171","msg":"trace[306638087] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"327.311122ms","start":"2026-06-09T13:47:31.88518Z","end":"2026-06-09T13:47:32.212491Z","steps":["trace[306638087] 'process raft request' (duration: 326.977326ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:32.212511Z","caller":"traceutil/trace.go:171","msg":"trace[683208313] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"326.512398ms","start":"2026-06-09T13:47:31.885978Z","end":"2026-06-09T13:47:32.21249Z","steps":["trace[683208313] 'process raft request' (duration: 326.203683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:32.212679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:31.885166Z","time spent":"327.413213ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:47:32.212701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:31.885067Z","time spent":"327.373332ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:32.212759Z","caller":"traceutil/trace.go:171","msg":"trace[55393558] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"328.257127ms","start":"2026-06-09T13:47:31.884486Z","end":"2026-06-09T13:47:32.212743Z","steps":["trace[55393558] 'process raft request' (duration: 327.582166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:32.212851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:31.884457Z","time spent":"328.340899ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:47:32.212926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:31.885955Z","time spent":"326.6345ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:48:02.594508Z","caller":"traceutil/trace.go:171","msg":"trace[1330821551] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"127.056971ms","start":"2026-06-09T13:48:02.467425Z","end":"2026-06-09T13:48:02.594482Z","steps":["trace[1330821551] 'process raft request' (duration: 76.919798ms)","trace[1330821551] 'compare' (duration: 49.98682ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:48:02.594737Z","caller":"traceutil/trace.go:171","msg":"trace[714047077] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"126.520283ms","start":"2026-06-09T13:48:02.468194Z","end":"2026-06-09T13:48:02.594714Z","steps":["trace[714047077] 'process raft request' (duration: 126.256098ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:02.5949Z","caller":"traceutil/trace.go:171","msg":"trace[1467175047] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"124.965307ms","start":"2026-06-09T13:48:02.469929Z","end":"2026-06-09T13:48:02.594894Z","steps":["trace[1467175047] 'process raft request' (duration: 124.713603ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:11.022227Z","caller":"traceutil/trace.go:171","msg":"trace[1574109852] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"221.145924ms","start":"2026-06-09T13:48:10.801046Z","end":"2026-06-09T13:48:11.022192Z","steps":["trace[1574109852] 'process raft request' (duration: 199.666329ms)","trace[1574109852] 'compare' (duration: 21.378184ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:48:11.023736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.012503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-09T13:48:11.023775Z","caller":"traceutil/trace.go:171","msg":"trace[1733684921] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5335; }","duration":"279.093065ms","start":"2026-06-09T13:48:10.744671Z","end":"2026-06-09T13:48:11.023765Z","steps":["trace[1733684921] 'range keys from in-memory index tree' (duration: 277.308847ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:22.198982Z","caller":"traceutil/trace.go:171","msg":"trace[885716452] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"135.220546ms","start":"2026-06-09T13:48:22.063736Z","end":"2026-06-09T13:48:22.198957Z","steps":["trace[885716452] 'process raft request' (duration: 131.887962ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:22.20024Z","caller":"traceutil/trace.go:171","msg":"trace[1828583902] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"128.087932ms","start":"2026-06-09T13:48:22.072116Z","end":"2026-06-09T13:48:22.200204Z","steps":["trace[1828583902] 'process raft request' (duration: 128.041741ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:22.200411Z","caller":"traceutil/trace.go:171","msg":"trace[161948367] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"128.334766ms","start":"2026-06-09T13:48:22.072067Z","end":"2026-06-09T13:48:22.200402Z","steps":["trace[161948367] 'process raft request' (duration: 127.968611ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:38.502858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2887} {"level":"info","ts":"2026-06-09T13:48:38.623857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2887,"took":"119.610417ms","hash":1315517366,"current-db-size-bytes":53485568,"current-db-size":"54 MB","current-db-size-in-use-bytes":28884992,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-09T13:48:38.62396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1315517366,"revision":2887,"compact-revision":-1} {"level":"info","ts":"2026-06-09T13:51:09.924927Z","caller":"traceutil/trace.go:171","msg":"trace[1156018766] transaction","detail":"{read_only:false; response_revision:6584; number_of_response:1; }","duration":"243.482131ms","start":"2026-06-09T13:51:09.681418Z","end":"2026-06-09T13:51:09.9249Z","steps":["trace[1156018766] 'process raft request' (duration: 233.200784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:51:15.348674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.480308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T13:51:15.348743Z","caller":"traceutil/trace.go:171","msg":"trace[866316694] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6609; }","duration":"101.599459ms","start":"2026-06-09T13:51:15.247128Z","end":"2026-06-09T13:51:15.348727Z","steps":["trace[866316694] 'count revisions from in-memory index tree' (duration: 101.357086ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:51:15.885611Z","caller":"traceutil/trace.go:171","msg":"trace[1373957414] transaction","detail":"{read_only:false; response_revision:6613; number_of_response:1; }","duration":"180.536506ms","start":"2026-06-09T13:51:15.705053Z","end":"2026-06-09T13:51:15.885589Z","steps":["trace[1373957414] 'process raft request' (duration: 104.578492ms)","trace[1373957414] 'compare' (duration: 75.681051ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:51:17.212014Z","caller":"traceutil/trace.go:171","msg":"trace[85873729] transaction","detail":"{read_only:false; response_revision:6618; number_of_response:1; }","duration":"202.787862ms","start":"2026-06-09T13:51:17.009181Z","end":"2026-06-09T13:51:17.211969Z","steps":["trace[85873729] 'process raft request' (duration: 198.247527ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:51:17.212108Z","caller":"traceutil/trace.go:171","msg":"trace[1114022891] linearizableReadLoop","detail":"{readStateIndex:6866; appliedIndex:6865; }","duration":"114.560604ms","start":"2026-06-09T13:51:17.097487Z","end":"2026-06-09T13:51:17.212048Z","steps":["trace[1114022891] 'read index received' (duration: 109.900578ms)","trace[1114022891] 'applied index is now lower than readState.Index' (duration: 4.656766ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:51:17.241007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.945144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-06-09T13:51:17.241074Z","caller":"traceutil/trace.go:171","msg":"trace[903806608] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6618; }","duration":"210.045046ms","start":"2026-06-09T13:51:17.031012Z","end":"2026-06-09T13:51:17.241057Z","steps":["trace[903806608] 'agreement among raft nodes before linearized reading' (duration: 181.255225ms)","trace[903806608] 'range keys from in-memory index tree' (duration: 28.594968ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:53:38.508108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5538} {"level":"info","ts":"2026-06-09T13:53:38.632301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5538,"took":"123.022879ms","hash":1824494470,"current-db-size-bytes":53485568,"current-db-size":"54 MB","current-db-size-in-use-bytes":18452480,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-09T13:53:38.632373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1824494470,"revision":5538,"compact-revision":2887}