{"level":"warn","ts":"2026-06-16T00:26:02.29391Z","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-16T00:26:02.293989Z","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-16T00:26:02.294049Z","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-16T00:26:02.29406Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-06-16T00:26:02.294077Z","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-16T00:26:02.294722Z","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-16T00:26:02.294791Z","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-16T00:26:02.296919Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.931989ms"} {"level":"info","ts":"2026-06-16T00:26:02.308088Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-06-16T00:26:02.308202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T00:26:02.308229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-06-16T00:26:02.308242Z","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-16T00:26:02.308251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-06-16T00:26:02.308286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-06-16T00:26:02.313431Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T00:26:02.314854Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T00:26:02.315974Z","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-16T00:26:02.31885Z","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-16T00:26:02.326954Z","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-16T00:26:02.327024Z","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-16T00:26:02.327042Z","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-16T00:26:02.327126Z","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-16T00:26:02.328239Z","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-16T00:26:02.32847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-06-16T00:26:02.32847Z","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-16T00:26:02.328507Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T00:26:02.328598Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-16T00:26:02.328652Z","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-16T00:26:02.328665Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-16T00:26:03.10872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T00:26:03.108775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T00:26:03.108804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-06-16T00:26:03.108826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-06-16T00:26:03.108841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-16T00:26:03.108855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-06-16T00:26:03.108869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-16T00:26:03.109928Z","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-16T00:26:03.110137Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:26:03.110503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:26:03.110526Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:26:03.110585Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T00:26:03.110626Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T00:26:03.110999Z","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-16T00:26:03.111059Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:26:03.11108Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:26:03.115974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-06-16T00:26:03.116824Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T00:26:10.682543Z","caller":"traceutil/trace.go:171","msg":"trace[1064557771] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"223.402076ms","start":"2026-06-16T00:26:10.459112Z","end":"2026-06-16T00:26:10.682514Z","steps":["trace[1064557771] 'process raft request' (duration: 223.214643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:10.979279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.494411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-16T00:26:10.979351Z","caller":"traceutil/trace.go:171","msg":"trace[863229866] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:330; }","duration":"201.641112ms","start":"2026-06-16T00:26:10.777695Z","end":"2026-06-16T00:26:10.979336Z","steps":["trace[863229866] 'range keys from in-memory index tree' (duration: 201.358559ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:11.216151Z","caller":"traceutil/trace.go:171","msg":"trace[2098095757] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"178.018412ms","start":"2026-06-16T00:26:11.03811Z","end":"2026-06-16T00:26:11.216128Z","steps":["trace[2098095757] 'read index received' (duration: 177.835729ms)","trace[2098095757] 'applied index is now lower than readState.Index' (duration: 180.893µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:26:11.216293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.170604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-16T00:26:11.216329Z","caller":"traceutil/trace.go:171","msg":"trace[585771530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"178.260326ms","start":"2026-06-16T00:26:11.038058Z","end":"2026-06-16T00:26:11.216319Z","steps":["trace[585771530] 'agreement among raft nodes before linearized reading' (duration: 178.154654ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:11.216578Z","caller":"traceutil/trace.go:171","msg":"trace[1333016517] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"232.496431ms","start":"2026-06-16T00:26:10.984Z","end":"2026-06-16T00:26:11.216497Z","steps":["trace[1333016517] 'process raft request' (duration: 231.953832ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:11.32645Z","caller":"traceutil/trace.go:171","msg":"trace[584600716] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"101.758844ms","start":"2026-06-16T00:26:11.224655Z","end":"2026-06-16T00:26:11.326413Z","steps":["trace[584600716] 'read index received' (duration: 60.984581ms)","trace[584600716] 'applied index is now lower than readState.Index' (duration: 40.772763ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:26:11.326666Z","caller":"traceutil/trace.go:171","msg":"trace[352076329] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"104.579193ms","start":"2026-06-16T00:26:11.221959Z","end":"2026-06-16T00:26:11.326538Z","steps":["trace[352076329] 'process raft request' (duration: 63.682208ms)","trace[352076329] 'compare' (duration: 40.610821ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:26:11.326676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.980707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-16T00:26:11.326833Z","caller":"traceutil/trace.go:171","msg":"trace[1980426583] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:332; }","duration":"102.19264ms","start":"2026-06-16T00:26:11.224622Z","end":"2026-06-16T00:26:11.326815Z","steps":["trace[1980426583] 'agreement among raft nodes before linearized reading' (duration: 101.960016ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:40.703396Z","caller":"traceutil/trace.go:171","msg":"trace[79131800] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"209.72809ms","start":"2026-06-16T00:26:40.493598Z","end":"2026-06-16T00:26:40.703326Z","steps":["trace[79131800] 'process raft request' (duration: 203.46848ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:47.974538Z","caller":"traceutil/trace.go:171","msg":"trace[649780777] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"323.710991ms","start":"2026-06-16T00:26:47.650802Z","end":"2026-06-16T00:26:47.974513Z","steps":["trace[649780777] 'read index received' (duration: 323.469088ms)","trace[649780777] 'applied index is now lower than readState.Index' (duration: 240.993µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:26:47.974645Z","caller":"traceutil/trace.go:171","msg":"trace[1565761398] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"357.836946ms","start":"2026-06-16T00:26:47.616776Z","end":"2026-06-16T00:26:47.974613Z","steps":["trace[1565761398] 'process raft request' (duration: 357.569073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:47.974829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.999804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5s572\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-16T00:26:47.974939Z","caller":"traceutil/trace.go:171","msg":"trace[1749473478] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5s572; range_end:; response_count:1; response_revision:645; }","duration":"324.132375ms","start":"2026-06-16T00:26:47.650773Z","end":"2026-06-16T00:26:47.974906Z","steps":["trace[1749473478] 'agreement among raft nodes before linearized reading' (duration: 323.862902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:47.974988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:47.65076Z","time spent":"324.221126ms","remote":"127.0.0.1:37890","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-5s572\" "} {"level":"warn","ts":"2026-06-16T00:26:47.975884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:47.616755Z","time spent":"358.036709ms","remote":"127.0.0.1:37982","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-16T00:27:03.451961Z","caller":"traceutil/trace.go:171","msg":"trace[412601957] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"167.206804ms","start":"2026-06-16T00:27:03.284715Z","end":"2026-06-16T00:27:03.451921Z","steps":["trace[412601957] 'process raft request' (duration: 167.028762ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:27:36.265527Z","caller":"traceutil/trace.go:171","msg":"trace[139779105] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"169.276849ms","start":"2026-06-16T00:27:36.096227Z","end":"2026-06-16T00:27:36.265504Z","steps":["trace[139779105] 'process raft request' (duration: 149.665361ms)","trace[139779105] 'compare' (duration: 19.349796ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:27:45.809655Z","caller":"traceutil/trace.go:171","msg":"trace[1285266407] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"137.442964ms","start":"2026-06-16T00:27:45.67218Z","end":"2026-06-16T00:27:45.809623Z","steps":["trace[1285266407] 'process raft request' (duration: 137.145461ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:28:06.214841Z","caller":"traceutil/trace.go:171","msg":"trace[1866271477] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"117.469893ms","start":"2026-06-16T00:28:06.097348Z","end":"2026-06-16T00:28:06.214818Z","steps":["trace[1866271477] 'process raft request' (duration: 117.349552ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:28:29.111708Z","caller":"traceutil/trace.go:171","msg":"trace[614470474] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"122.312703ms","start":"2026-06-16T00:28:28.989368Z","end":"2026-06-16T00:28:29.111681Z","steps":["trace[614470474] 'process raft request' (duration: 122.062766ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:28:29.544902Z","caller":"traceutil/trace.go:171","msg":"trace[1647100271] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"379.15256ms","start":"2026-06-16T00:28:29.165706Z","end":"2026-06-16T00:28:29.544859Z","steps":["trace[1647100271] 'read index received' (duration: 374.254586ms)","trace[1647100271] 'applied index is now lower than readState.Index' (duration: 4.895983ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:28:29.545354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.622413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5473299e-2670-4485-93fc-eaa6aa1030bc\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-16T00:28:29.545445Z","caller":"traceutil/trace.go:171","msg":"trace[1348580929] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5473299e-2670-4485-93fc-eaa6aa1030bc; range_end:; response_count:1; response_revision:1902; }","duration":"379.768147ms","start":"2026-06-16T00:28:29.165654Z","end":"2026-06-16T00:28:29.545422Z","steps":["trace[1348580929] 'agreement among raft nodes before linearized reading' (duration: 379.488069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:28:29.545492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:28:29.165619Z","time spent":"379.86352ms","remote":"127.0.0.1:37890","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-5473299e-2670-4485-93fc-eaa6aa1030bc\" "} {"level":"warn","ts":"2026-06-16T00:28:29.545714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.23453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:28:29.545755Z","caller":"traceutil/trace.go:171","msg":"trace[145344997] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"429.164182ms","start":"2026-06-16T00:28:29.116556Z","end":"2026-06-16T00:28:29.54572Z","steps":["trace[145344997] 'process raft request' (duration: 423.462806ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:28:29.546499Z","caller":"traceutil/trace.go:171","msg":"trace[1285548778] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1902; }","duration":"120.223449ms","start":"2026-06-16T00:28:29.426222Z","end":"2026-06-16T00:28:29.546445Z","steps":["trace[1285548778] 'agreement among raft nodes before linearized reading' (duration: 119.119307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:28:29.546613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:28:29.116513Z","time spent":"430.020997ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:28:53.67755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.996851ms","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-16T00:28:53.67764Z","caller":"traceutil/trace.go:171","msg":"trace[1282765849] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2085; }","duration":"194.117604ms","start":"2026-06-16T00:28:53.483502Z","end":"2026-06-16T00:28:53.67762Z","steps":["trace[1282765849] 'range keys from in-memory index tree' (duration: 193.829167ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:29:00.368966Z","caller":"traceutil/trace.go:171","msg":"trace[1051802504] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"125.034999ms","start":"2026-06-16T00:29:00.243891Z","end":"2026-06-16T00:29:00.368926Z","steps":["trace[1051802504] 'process raft request' (duration: 124.863044ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:29:00.643023Z","caller":"traceutil/trace.go:171","msg":"trace[1600936163] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"232.981888ms","start":"2026-06-16T00:29:00.409995Z","end":"2026-06-16T00:29:00.642976Z","steps":["trace[1600936163] 'process raft request' (duration: 214.591075ms)","trace[1600936163] 'compare' (duration: 18.193778ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:29:01.202174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.583838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-16T00:29:01.202273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.161697ms","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-16T00:29:01.202321Z","caller":"traceutil/trace.go:171","msg":"trace[74278794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2130; }","duration":"416.233859ms","start":"2026-06-16T00:29:00.786074Z","end":"2026-06-16T00:29:01.202308Z","steps":["trace[74278794] 'range keys from in-memory index tree' (duration: 416.061245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:29:01.202357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:00.786059Z","time spent":"416.29021ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T00:29:01.202279Z","caller":"traceutil/trace.go:171","msg":"trace[595175464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2130; }","duration":"446.711011ms","start":"2026-06-16T00:29:00.755524Z","end":"2026-06-16T00:29:01.202235Z","steps":["trace[595175464] 'range keys from in-memory index tree' (duration: 446.361182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:29:01.202586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:00.755506Z","time spent":"447.06865ms","remote":"127.0.0.1:37982","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-16T00:29:01.203224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.39303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-06-16T00:29:01.203272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.78532ms","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-16T00:29:01.203387Z","caller":"traceutil/trace.go:171","msg":"trace[1559188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2130; }","duration":"419.970236ms","start":"2026-06-16T00:29:00.78339Z","end":"2026-06-16T00:29:01.20336Z","steps":["trace[1559188] 'range keys from in-memory index tree' (duration: 418.694872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:29:01.203454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:00.783369Z","time spent":"420.070248ms","remote":"127.0.0.1:37982","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-16T00:29:01.203476Z","caller":"traceutil/trace.go:171","msg":"trace[1656502673] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"401.470011ms","start":"2026-06-16T00:29:00.801941Z","end":"2026-06-16T00:29:01.203411Z","steps":["trace[1656502673] 'process raft request' (duration: 126.696641ms)","trace[1656502673] 'compare' (duration: 273.508127ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:29:01.203677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:00.801921Z","time spent":"401.671007ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:29:01.315362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.055393ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-16T00:29:01.315595Z","caller":"traceutil/trace.go:171","msg":"trace[236689381] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2203; }","duration":"231.672371ms","start":"2026-06-16T00:29:01.0839Z","end":"2026-06-16T00:29:01.315573Z","steps":["trace[236689381] 'read index received' (duration: 114.923817ms)","trace[236689381] 'applied index is now lower than readState.Index' (duration: 116.746214ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:29:01.315846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.833025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:29:01.315905Z","caller":"traceutil/trace.go:171","msg":"trace[1956428549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2132; }","duration":"232.0161ms","start":"2026-06-16T00:29:01.083855Z","end":"2026-06-16T00:29:01.315871Z","steps":["trace[1956428549] 'agreement among raft nodes before linearized reading' (duration: 231.811995ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:29:01.31564Z","caller":"traceutil/trace.go:171","msg":"trace[581139418] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"108.684688ms","start":"2026-06-16T00:29:01.206937Z","end":"2026-06-16T00:29:01.315622Z","steps":["trace[581139418] 'process raft request' (duration: 108.536044ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:29:01.363703Z","caller":"traceutil/trace.go:171","msg":"trace[705345473] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"155.848163ms","start":"2026-06-16T00:29:01.207828Z","end":"2026-06-16T00:29:01.363676Z","steps":["trace[705345473] 'process raft request' (duration: 155.630568ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:29:01.363862Z","caller":"traceutil/trace.go:171","msg":"trace[1133836271] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"155.349909ms","start":"2026-06-16T00:29:01.20849Z","end":"2026-06-16T00:29:01.36384Z","steps":["trace[1133836271] 'process raft request' (duration: 155.112853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:29:02.13986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.470443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:29:02.139988Z","caller":"traceutil/trace.go:171","msg":"trace[844718442] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2210; }","duration":"262.946336ms","start":"2026-06-16T00:29:01.877019Z","end":"2026-06-16T00:29:02.139966Z","steps":["trace[844718442] 'read index received' (duration: 9.224996ms)","trace[844718442] 'applied index is now lower than readState.Index' (duration: 253.71938ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:29:02.140118Z","caller":"traceutil/trace.go:171","msg":"trace[1362789588] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"409.051929ms","start":"2026-06-16T00:29:01.731047Z","end":"2026-06-16T00:29:02.140099Z","steps":["trace[1362789588] 'process raft request' (duration: 155.245648ms)","trace[1362789588] 'compare' (duration: 253.217907ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:29:02.140214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:01.731022Z","time spent":"409.143842ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:29:02.140511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.480179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T00:29:02.140568Z","caller":"traceutil/trace.go:171","msg":"trace[2094917472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2137; }","duration":"263.565272ms","start":"2026-06-16T00:29:01.876987Z","end":"2026-06-16T00:29:02.140552Z","steps":["trace[2094917472] 'agreement among raft nodes before linearized reading' (duration: 263.384817ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:29:02.524921Z","caller":"traceutil/trace.go:171","msg":"trace[812275888] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"380.71667ms","start":"2026-06-16T00:29:02.144182Z","end":"2026-06-16T00:29:02.524899Z","steps":["trace[812275888] 'process raft request' (duration: 377.792645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:29:02.525093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:02.144151Z","time spent":"380.843574ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:29:02.525132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.467856ms","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-16T00:29:02.524909Z","caller":"traceutil/trace.go:171","msg":"trace[745836709] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2211; }","duration":"151.218678ms","start":"2026-06-16T00:29:02.373663Z","end":"2026-06-16T00:29:02.524882Z","steps":["trace[745836709] 'read index received' (duration: 148.299954ms)","trace[745836709] 'applied index is now lower than readState.Index' (duration: 2.917664ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:29:02.525191Z","caller":"traceutil/trace.go:171","msg":"trace[696555973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2138; }","duration":"151.574329ms","start":"2026-06-16T00:29:02.373601Z","end":"2026-06-16T00:29:02.525175Z","steps":["trace[696555973] 'agreement among raft nodes before linearized reading' (duration: 151.364683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:29:02.525341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.171188ms","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-16T00:29:02.525397Z","caller":"traceutil/trace.go:171","msg":"trace[2019251399] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2138; }","duration":"140.24914ms","start":"2026-06-16T00:29:02.385129Z","end":"2026-06-16T00:29:02.525379Z","steps":["trace[2019251399] 'agreement among raft nodes before linearized reading' (duration: 140.107366ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:29:02.839677Z","caller":"traceutil/trace.go:171","msg":"trace[447845881] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"309.872238ms","start":"2026-06-16T00:29:02.529773Z","end":"2026-06-16T00:29:02.839645Z","steps":["trace[447845881] 'process raft request' (duration: 309.591321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:29:02.839837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:02.529732Z","time spent":"310.029432ms","remote":"127.0.0.1:37982","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-16T00:29:03.182692Z","caller":"traceutil/trace.go:171","msg":"trace[1221436758] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2213; }","duration":"321.886024ms","start":"2026-06-16T00:29:02.860785Z","end":"2026-06-16T00:29:03.182671Z","steps":["trace[1221436758] 'read index received' (duration: 310.945427ms)","trace[1221436758] 'applied index is now lower than readState.Index' (duration: 10.939677ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:29:03.182972Z","caller":"traceutil/trace.go:171","msg":"trace[377169656] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"534.008074ms","start":"2026-06-16T00:29:02.648951Z","end":"2026-06-16T00:29:03.18296Z","steps":["trace[377169656] 'process raft request' (duration: 522.877602ms)","trace[377169656] 'compare' (duration: 10.411704ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:29:03.183079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:02.648925Z","time spent":"534.083695ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:29:03.183465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.674036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b9683c6edc1742\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-16T00:29:03.183473Z","caller":"traceutil/trace.go:171","msg":"trace[1108429517] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"445.397169ms","start":"2026-06-16T00:29:02.738049Z","end":"2026-06-16T00:29:03.183446Z","steps":["trace[1108429517] 'process raft request' (duration: 444.560027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:29:03.18346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.414005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:29:03.183514Z","caller":"traceutil/trace.go:171","msg":"trace[1915405356] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b9683c6edc1742; range_end:; response_count:1; response_revision:2141; }","duration":"322.755438ms","start":"2026-06-16T00:29:02.860744Z","end":"2026-06-16T00:29:03.183499Z","steps":["trace[1915405356] 'agreement among raft nodes before linearized reading' (duration: 322.607325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:29:03.183554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:02.860724Z","time spent":"322.823099ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b9683c6edc1742\" "} {"level":"warn","ts":"2026-06-16T00:29:03.183608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:29:02.738023Z","time spent":"445.505682ms","remote":"127.0.0.1:37982","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-16T00:29:03.183634Z","caller":"traceutil/trace.go:171","msg":"trace[599364498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2141; }","duration":"100.823856ms","start":"2026-06-16T00:29:03.082717Z","end":"2026-06-16T00:29:03.183541Z","steps":["trace[599364498] 'agreement among raft nodes before linearized reading' (duration: 100.425735ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:30:13.281727Z","caller":"traceutil/trace.go:171","msg":"trace[1938715466] linearizableReadLoop","detail":"{readStateIndex:2709; appliedIndex:2708; }","duration":"357.660084ms","start":"2026-06-16T00:30:12.92404Z","end":"2026-06-16T00:30:13.2817Z","steps":["trace[1938715466] 'read index received' (duration: 357.564642ms)","trace[1938715466] 'applied index is now lower than readState.Index' (duration: 94.432µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:30:13.28186Z","caller":"traceutil/trace.go:171","msg":"trace[620349567] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"441.801436ms","start":"2026-06-16T00:30:12.840049Z","end":"2026-06-16T00:30:13.28185Z","steps":["trace[620349567] 'process raft request' (duration: 441.48936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:13.281983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:30:12.840029Z","time spent":"441.854427ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:30:13.282155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.091023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:30:13.282248Z","caller":"traceutil/trace.go:171","msg":"trace[1679699777] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2618; }","duration":"358.237506ms","start":"2026-06-16T00:30:12.923989Z","end":"2026-06-16T00:30:13.282226Z","steps":["trace[1679699777] 'agreement among raft nodes before linearized reading' (duration: 358.085284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:13.282292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:30:12.923969Z","time spent":"358.313328ms","remote":"127.0.0.1:38020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":5,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:30:13.316175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.331973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:30:13.316236Z","caller":"traceutil/trace.go:171","msg":"trace[1910299379] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2619; }","duration":"209.435675ms","start":"2026-06-16T00:30:13.106785Z","end":"2026-06-16T00:30:13.31622Z","steps":["trace[1910299379] 'agreement among raft nodes before linearized reading' (duration: 209.331843ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:30:13.316246Z","caller":"traceutil/trace.go:171","msg":"trace[434828901] transaction","detail":"{read_only:false; response_revision:2619; number_of_response:1; }","duration":"364.50168ms","start":"2026-06-16T00:30:12.9517Z","end":"2026-06-16T00:30:13.316202Z","steps":["trace[434828901] 'process raft request' (duration: 364.288165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:13.316471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:30:12.951671Z","time spent":"364.661382ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:30:13.316451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.621083ms","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-16T00:30:13.316566Z","caller":"traceutil/trace.go:171","msg":"trace[45628658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2619; }","duration":"158.785905ms","start":"2026-06-16T00:30:13.157755Z","end":"2026-06-16T00:30:13.316541Z","steps":["trace[45628658] 'agreement among raft nodes before linearized reading' (duration: 158.51128ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:30:40.138571Z","caller":"traceutil/trace.go:171","msg":"trace[175013981] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"138.397294ms","start":"2026-06-16T00:30:40.000133Z","end":"2026-06-16T00:30:40.138531Z","steps":["trace[175013981] 'process raft request' (duration: 138.18393ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:30:45.64981Z","caller":"traceutil/trace.go:171","msg":"trace[1035198972] linearizableReadLoop","detail":"{readStateIndex:2893; appliedIndex:2892; }","duration":"154.292181ms","start":"2026-06-16T00:30:45.495495Z","end":"2026-06-16T00:30:45.649787Z","steps":["trace[1035198972] 'read index received' (duration: 154.246371ms)","trace[1035198972] 'applied index is now lower than readState.Index' (duration: 45.08µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:30:45.649971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.476425ms","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-16T00:30:45.649998Z","caller":"traceutil/trace.go:171","msg":"trace[1496901739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2796; }","duration":"154.516136ms","start":"2026-06-16T00:30:45.495475Z","end":"2026-06-16T00:30:45.649991Z","steps":["trace[1496901739] 'agreement among raft nodes before linearized reading' (duration: 154.386624ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:30:45.650197Z","caller":"traceutil/trace.go:171","msg":"trace[1565180913] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"214.068875ms","start":"2026-06-16T00:30:45.436092Z","end":"2026-06-16T00:30:45.650161Z","steps":["trace[1565180913] 'process raft request' (duration: 213.594847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:46.244278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.422671ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:30:46.244407Z","caller":"traceutil/trace.go:171","msg":"trace[1272752134] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2800; }","duration":"389.562604ms","start":"2026-06-16T00:30:45.854826Z","end":"2026-06-16T00:30:46.244389Z","steps":["trace[1272752134] 'range keys from in-memory index tree' (duration: 389.407321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:46.244499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.570006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:30:46.2446Z","caller":"traceutil/trace.go:171","msg":"trace[1432151669] linearizableReadLoop","detail":"{readStateIndex:2898; appliedIndex:2897; }","duration":"400.720458ms","start":"2026-06-16T00:30:45.843863Z","end":"2026-06-16T00:30:46.244583Z","steps":["trace[1432151669] 'read index received' (duration: 129.03688ms)","trace[1432151669] 'applied index is now lower than readState.Index' (duration: 271.682638ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:30:46.245304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.432541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-16T00:30:46.245348Z","caller":"traceutil/trace.go:171","msg":"trace[1604856584] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"476.630157ms","start":"2026-06-16T00:30:45.768701Z","end":"2026-06-16T00:30:46.245331Z","steps":["trace[1604856584] 'process raft request' (duration: 204.136784ms)","trace[1604856584] 'compare' (duration: 271.307961ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:30:46.245358Z","caller":"traceutil/trace.go:171","msg":"trace[66212844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2801; }","duration":"401.536933ms","start":"2026-06-16T00:30:45.843804Z","end":"2026-06-16T00:30:46.245341Z","steps":["trace[66212844] 'agreement among raft nodes before linearized reading' (duration: 401.3732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:46.245417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:30:45.843782Z","time spent":"401.626104ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T00:30:46.245417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:30:45.768679Z","time spent":"476.702379ms","remote":"127.0.0.1:37982","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-16T00:30:46.245629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.938022ms","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-16T00:30:46.245665Z","caller":"traceutil/trace.go:171","msg":"trace[910666961] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:2801; }","duration":"325.005675ms","start":"2026-06-16T00:30:45.920649Z","end":"2026-06-16T00:30:46.245654Z","steps":["trace[910666961] 'agreement among raft nodes before linearized reading' (duration: 324.889212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:46.245697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:30:45.92063Z","time spent":"325.057695ms","remote":"127.0.0.1:37728","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-16T00:30:46.245639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.068946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-16T00:30:46.245936Z","caller":"traceutil/trace.go:171","msg":"trace[210793361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2801; }","duration":"371.429643ms","start":"2026-06-16T00:30:45.874478Z","end":"2026-06-16T00:30:46.245907Z","steps":["trace[210793361] 'agreement among raft nodes before linearized reading' (duration: 370.803881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:46.246081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:30:45.874458Z","time spent":"371.605256ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T00:30:46.246345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.259233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b96851483e60f6\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-16T00:30:46.246391Z","caller":"traceutil/trace.go:171","msg":"trace[2125486614] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b96851483e60f6; range_end:; response_count:1; response_revision:2801; }","duration":"328.330314ms","start":"2026-06-16T00:30:45.918049Z","end":"2026-06-16T00:30:46.24638Z","steps":["trace[2125486614] 'agreement among raft nodes before linearized reading' (duration: 328.199911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:46.246558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:30:45.918037Z","time spent":"328.507978ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":801,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b96851483e60f6\" "} {"level":"warn","ts":"2026-06-16T00:30:46.24697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.701246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:30:46.247011Z","caller":"traceutil/trace.go:171","msg":"trace[918363955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2801; }","duration":"163.795918ms","start":"2026-06-16T00:30:46.083205Z","end":"2026-06-16T00:30:46.247001Z","steps":["trace[918363955] 'agreement among raft nodes before linearized reading' (duration: 163.734417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:46.247478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.799964ms","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-16T00:30:46.24752Z","caller":"traceutil/trace.go:171","msg":"trace[878942901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2801; }","duration":"368.884556ms","start":"2026-06-16T00:30:45.878625Z","end":"2026-06-16T00:30:46.24751Z","steps":["trace[878942901] 'agreement among raft nodes before linearized reading' (duration: 368.775644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:30:46.247554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:30:45.878599Z","time spent":"368.943477ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T00:30:46.415691Z","caller":"traceutil/trace.go:171","msg":"trace[562956260] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"161.835374ms","start":"2026-06-16T00:30:46.253832Z","end":"2026-06-16T00:30:46.415668Z","steps":["trace[562956260] 'process raft request' (duration: 161.795273ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:30:46.415855Z","caller":"traceutil/trace.go:171","msg":"trace[2126424747] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"165.705984ms","start":"2026-06-16T00:30:46.250138Z","end":"2026-06-16T00:30:46.415844Z","steps":["trace[2126424747] 'process raft request' (duration: 165.224865ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:30:46.416182Z","caller":"traceutil/trace.go:171","msg":"trace[1733175676] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"163.242009ms","start":"2026-06-16T00:30:46.252908Z","end":"2026-06-16T00:30:46.41615Z","steps":["trace[1733175676] 'process raft request' (duration: 162.671419ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:33:31.906292Z","caller":"traceutil/trace.go:171","msg":"trace[1932437898] transaction","detail":"{read_only:false; response_revision:3815; number_of_response:1; }","duration":"111.45347ms","start":"2026-06-16T00:33:31.794799Z","end":"2026-06-16T00:33:31.906253Z","steps":["trace[1932437898] 'process raft request' (duration: 62.820165ms)","trace[1932437898] 'compare' (duration: 48.27051ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:33:57.671489Z","caller":"traceutil/trace.go:171","msg":"trace[1639274278] transaction","detail":"{read_only:false; response_revision:3981; number_of_response:1; }","duration":"111.998336ms","start":"2026-06-16T00:33:57.559467Z","end":"2026-06-16T00:33:57.671465Z","steps":["trace[1639274278] 'process raft request' (duration: 111.885035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:18.004242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.660327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:34:18.004366Z","caller":"traceutil/trace.go:171","msg":"trace[1028395978] transaction","detail":"{read_only:false; response_revision:4472; number_of_response:1; }","duration":"195.418522ms","start":"2026-06-16T00:34:17.808925Z","end":"2026-06-16T00:34:18.004343Z","steps":["trace[1028395978] 'process raft request' (duration: 62.567193ms)","trace[1028395978] 'compare' (duration: 132.500924ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:19.908125Z","caller":"traceutil/trace.go:171","msg":"trace[1704259735] linearizableReadLoop","detail":"{readStateIndex:4650; appliedIndex:4648; }","duration":"100.495277ms","start":"2026-06-16T00:34:19.807602Z","end":"2026-06-16T00:34:19.908098Z","steps":["trace[1704259735] 'read index received' (duration: 27.658646ms)","trace[1704259735] 'applied index is now lower than readState.Index' (duration: 72.835321ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:19.908242Z","caller":"traceutil/trace.go:171","msg":"trace[340485494] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"110.545395ms","start":"2026-06-16T00:34:19.797665Z","end":"2026-06-16T00:34:19.90821Z","steps":["trace[340485494] 'process raft request' (duration: 110.262211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:19.908386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.74574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-16T00:34:19.908444Z","caller":"traceutil/trace.go:171","msg":"trace[1544111620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4484; }","duration":"100.864193ms","start":"2026-06-16T00:34:19.807564Z","end":"2026-06-16T00:34:19.908428Z","steps":["trace[1544111620] 'agreement among raft nodes before linearized reading' (duration: 100.63885ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:21.634383Z","caller":"traceutil/trace.go:171","msg":"trace[1723140424] linearizableReadLoop","detail":"{readStateIndex:4703; appliedIndex:4702; }","duration":"153.274543ms","start":"2026-06-16T00:34:21.481091Z","end":"2026-06-16T00:34:21.634366Z","steps":["trace[1723140424] 'read index received' (duration: 103.430726ms)","trace[1723140424] 'applied index is now lower than readState.Index' (duration: 49.843227ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:21.63447Z","caller":"traceutil/trace.go:171","msg":"trace[1366803033] transaction","detail":"{read_only:false; response_revision:4535; number_of_response:1; }","duration":"153.383334ms","start":"2026-06-16T00:34:21.481051Z","end":"2026-06-16T00:34:21.634434Z","steps":["trace[1366803033] 'process raft request' (duration: 103.536107ms)","trace[1366803033] 'compare' (duration: 49.698055ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:21.634581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.471976ms","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-16T00:34:21.634621Z","caller":"traceutil/trace.go:171","msg":"trace[1995175355] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4535; }","duration":"153.547647ms","start":"2026-06-16T00:34:21.481065Z","end":"2026-06-16T00:34:21.634612Z","steps":["trace[1995175355] 'agreement among raft nodes before linearized reading' (duration: 153.396375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:21.634767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.675944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:5 size:42175"} {"level":"info","ts":"2026-06-16T00:34:21.634832Z","caller":"traceutil/trace.go:171","msg":"trace[1648327779] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:5; response_revision:4535; }","duration":"149.778934ms","start":"2026-06-16T00:34:21.485037Z","end":"2026-06-16T00:34:21.634816Z","steps":["trace[1648327779] 'agreement among raft nodes before linearized reading' (duration: 149.589772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:21.63491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.648662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:11918"} {"level":"info","ts":"2026-06-16T00:34:21.634943Z","caller":"traceutil/trace.go:171","msg":"trace[1486192372] range","detail":"{range_begin:/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4535; }","duration":"146.704093ms","start":"2026-06-16T00:34:21.488231Z","end":"2026-06-16T00:34:21.634935Z","steps":["trace[1486192372] 'agreement among raft nodes before linearized reading' (duration: 146.555441ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:21.843954Z","caller":"traceutil/trace.go:171","msg":"trace[1060871244] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"162.344695ms","start":"2026-06-16T00:34:21.681584Z","end":"2026-06-16T00:34:21.843928Z","steps":["trace[1060871244] 'process raft request' (duration: 162.214064ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:21.844041Z","caller":"traceutil/trace.go:171","msg":"trace[1561552659] linearizableReadLoop","detail":"{readStateIndex:4707; appliedIndex:4707; }","duration":"160.183027ms","start":"2026-06-16T00:34:21.683826Z","end":"2026-06-16T00:34:21.844009Z","steps":["trace[1561552659] 'read index received' (duration: 160.173246ms)","trace[1561552659] 'applied index is now lower than readState.Index' (duration: 8.231µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:21.844271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.41219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:21.844308Z","caller":"traceutil/trace.go:171","msg":"trace[165819507] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:4539; }","duration":"160.4908ms","start":"2026-06-16T00:34:21.683805Z","end":"2026-06-16T00:34:21.844296Z","steps":["trace[165819507] 'agreement among raft nodes before linearized reading' (duration: 160.375189ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:21.906636Z","caller":"traceutil/trace.go:171","msg":"trace[856492279] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"220.471326ms","start":"2026-06-16T00:34:21.68615Z","end":"2026-06-16T00:34:21.906621Z","steps":["trace[856492279] 'process raft request' (duration: 220.197292ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:21.906849Z","caller":"traceutil/trace.go:171","msg":"trace[1662867638] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"220.490996ms","start":"2026-06-16T00:34:21.686352Z","end":"2026-06-16T00:34:21.906843Z","steps":["trace[1662867638] 'process raft request' (duration: 220.081561ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:21.906995Z","caller":"traceutil/trace.go:171","msg":"trace[702156696] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"193.077043ms","start":"2026-06-16T00:34:21.713913Z","end":"2026-06-16T00:34:21.90699Z","steps":["trace[702156696] 'process raft request' (duration: 192.608797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:21.90718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.809275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-rzh99\" ","response":"range_response_count:1 size:5041"} {"level":"info","ts":"2026-06-16T00:34:21.907201Z","caller":"traceutil/trace.go:171","msg":"trace[1174712646] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-rzh99; range_end:; response_count:1; response_revision:4542; }","duration":"113.880367ms","start":"2026-06-16T00:34:21.793315Z","end":"2026-06-16T00:34:21.907196Z","steps":["trace[1174712646] 'agreement among raft nodes before linearized reading' (duration: 113.818096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:21.907322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.104075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:5 size:42175"} {"level":"info","ts":"2026-06-16T00:34:21.907339Z","caller":"traceutil/trace.go:171","msg":"trace[2105631619] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:5; response_revision:4542; }","duration":"213.130495ms","start":"2026-06-16T00:34:21.694205Z","end":"2026-06-16T00:34:21.907335Z","steps":["trace[2105631619] 'agreement among raft nodes before linearized reading' (duration: 213.065205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:21.907503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.125812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-06-16T00:34:21.907538Z","caller":"traceutil/trace.go:171","msg":"trace[218267283] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4542; }","duration":"215.180753ms","start":"2026-06-16T00:34:21.692351Z","end":"2026-06-16T00:34:21.907531Z","steps":["trace[218267283] 'agreement among raft nodes before linearized reading' (duration: 215.114272ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:22.021428Z","caller":"traceutil/trace.go:171","msg":"trace[1916106518] linearizableReadLoop","detail":"{readStateIndex:4713; appliedIndex:4711; }","duration":"107.153445ms","start":"2026-06-16T00:34:21.914254Z","end":"2026-06-16T00:34:22.021407Z","steps":["trace[1916106518] 'read index received' (duration: 27.779938ms)","trace[1916106518] 'applied index is now lower than readState.Index' (duration: 79.372637ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:22.025068Z","caller":"traceutil/trace.go:171","msg":"trace[1555407428] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"111.802379ms","start":"2026-06-16T00:34:21.913251Z","end":"2026-06-16T00:34:22.025053Z","steps":["trace[1555407428] 'process raft request' (duration: 107.974697ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:22.025324Z","caller":"traceutil/trace.go:171","msg":"trace[2056974353] transaction","detail":"{read_only:false; response_revision:4545; number_of_response:1; }","duration":"111.093848ms","start":"2026-06-16T00:34:21.914219Z","end":"2026-06-16T00:34:22.025313Z","steps":["trace[2056974353] 'process raft request' (duration: 107.136615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:22.025487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.214621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:22.025519Z","caller":"traceutil/trace.go:171","msg":"trace[911382025] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4545; }","duration":"111.288742ms","start":"2026-06-16T00:34:21.914221Z","end":"2026-06-16T00:34:22.02551Z","steps":["trace[911382025] 'agreement among raft nodes before linearized reading' (duration: 111.223051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:22.025715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.881178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4736"} {"level":"info","ts":"2026-06-16T00:34:22.025778Z","caller":"traceutil/trace.go:171","msg":"trace[2095399939] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4545; }","duration":"105.959149ms","start":"2026-06-16T00:34:21.91981Z","end":"2026-06-16T00:34:22.025769Z","steps":["trace[2095399939] 'agreement among raft nodes before linearized reading' (duration: 105.821217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:22.599476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.265141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:34:22.599622Z","caller":"traceutil/trace.go:171","msg":"trace[1678649000] linearizableReadLoop","detail":"{readStateIndex:4723; appliedIndex:4722; }","duration":"345.38233ms","start":"2026-06-16T00:34:22.254204Z","end":"2026-06-16T00:34:22.599586Z","steps":["trace[1678649000] 'read index received' (duration: 68.978997ms)","trace[1678649000] 'applied index is now lower than readState.Index' (duration: 276.402303ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:22.599766Z","caller":"traceutil/trace.go:171","msg":"trace[1183548685] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"345.997029ms","start":"2026-06-16T00:34:22.253735Z","end":"2026-06-16T00:34:22.599732Z","steps":["trace[1183548685] 'process raft request' (duration: 69.398463ms)","trace[1183548685] 'compare' (duration: 276.121998ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:22.599923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:22.253722Z","time spent":"346.12506ms","remote":"127.0.0.1:37782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:34:22.60019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.959158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-06-16T00:34:22.600268Z","caller":"traceutil/trace.go:171","msg":"trace[2134829219] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4555; }","duration":"346.08091ms","start":"2026-06-16T00:34:22.25417Z","end":"2026-06-16T00:34:22.600251Z","steps":["trace[2134829219] 'agreement among raft nodes before linearized reading' (duration: 345.607063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:22.600439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:22.254151Z","time spent":"346.273342ms","remote":"127.0.0.1:38062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1146,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-06-16T00:34:22.600272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.939603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:5 size:42291"} {"level":"info","ts":"2026-06-16T00:34:22.601338Z","caller":"traceutil/trace.go:171","msg":"trace[1339696343] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:5; response_revision:4555; }","duration":"345.514571ms","start":"2026-06-16T00:34:22.255269Z","end":"2026-06-16T00:34:22.600784Z","steps":["trace[1339696343] 'agreement among raft nodes before linearized reading' (duration: 344.480247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:22.60144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:22.255248Z","time spent":"346.14171ms","remote":"127.0.0.1:37890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":42313,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"info","ts":"2026-06-16T00:34:22.879458Z","caller":"traceutil/trace.go:171","msg":"trace[262678421] linearizableReadLoop","detail":"{readStateIndex:4724; appliedIndex:4723; }","duration":"268.782149ms","start":"2026-06-16T00:34:22.61065Z","end":"2026-06-16T00:34:22.879432Z","steps":["trace[262678421] 'read index received' (duration: 220.298772ms)","trace[262678421] 'applied index is now lower than readState.Index' (duration: 48.482057ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:22.87951Z","caller":"traceutil/trace.go:171","msg":"trace[1211221478] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"269.231865ms","start":"2026-06-16T00:34:22.610247Z","end":"2026-06-16T00:34:22.879479Z","steps":["trace[1211221478] 'process raft request' (duration: 220.689757ms)","trace[1211221478] 'compare' (duration: 48.027171ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:22.879752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.077913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4736"} {"level":"info","ts":"2026-06-16T00:34:22.879851Z","caller":"traceutil/trace.go:171","msg":"trace[1835949595] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4556; }","duration":"269.175805ms","start":"2026-06-16T00:34:22.610634Z","end":"2026-06-16T00:34:22.87981Z","steps":["trace[1835949595] 'agreement among raft nodes before linearized reading' (duration: 268.88192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:22.88166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.956727ms","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-16T00:34:22.881724Z","caller":"traceutil/trace.go:171","msg":"trace[2024040401] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4559; }","duration":"268.037888ms","start":"2026-06-16T00:34:22.613671Z","end":"2026-06-16T00:34:22.881709Z","steps":["trace[2024040401] 'agreement among raft nodes before linearized reading' (duration: 267.849206ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:22.882025Z","caller":"traceutil/trace.go:171","msg":"trace[1742483511] transaction","detail":"{read_only:false; response_revision:4559; number_of_response:1; }","duration":"140.263924ms","start":"2026-06-16T00:34:22.741739Z","end":"2026-06-16T00:34:22.882003Z","steps":["trace[1742483511] 'process raft request' (duration: 139.737457ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:22.88206Z","caller":"traceutil/trace.go:171","msg":"trace[898100543] transaction","detail":"{read_only:false; response_revision:4557; number_of_response:1; }","duration":"258.323048ms","start":"2026-06-16T00:34:22.623696Z","end":"2026-06-16T00:34:22.882019Z","steps":["trace[898100543] 'process raft request' (duration: 257.640459ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:22.882235Z","caller":"traceutil/trace.go:171","msg":"trace[1753547141] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"158.020445ms","start":"2026-06-16T00:34:22.7242Z","end":"2026-06-16T00:34:22.882221Z","steps":["trace[1753547141] 'process raft request' (duration: 157.234135ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:23.003458Z","caller":"traceutil/trace.go:171","msg":"trace[1475667686] transaction","detail":"{read_only:false; response_revision:4561; number_of_response:1; }","duration":"118.51345ms","start":"2026-06-16T00:34:22.884905Z","end":"2026-06-16T00:34:23.003418Z","steps":["trace[1475667686] 'process raft request' (duration: 99.972788ms)","trace[1475667686] 'compare' (duration: 18.302839ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:23.003535Z","caller":"traceutil/trace.go:171","msg":"trace[372459087] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"116.979979ms","start":"2026-06-16T00:34:22.886532Z","end":"2026-06-16T00:34:23.003512Z","steps":["trace[372459087] 'process raft request' (duration: 116.804217ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:23.003818Z","caller":"traceutil/trace.go:171","msg":"trace[1726849487] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"113.873677ms","start":"2026-06-16T00:34:22.889912Z","end":"2026-06-16T00:34:23.003786Z","steps":["trace[1726849487] 'process raft request' (duration: 113.512142ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:23.003869Z","caller":"traceutil/trace.go:171","msg":"trace[733232057] linearizableReadLoop","detail":"{readStateIndex:4730; appliedIndex:4728; }","duration":"116.603904ms","start":"2026-06-16T00:34:22.887256Z","end":"2026-06-16T00:34:23.00386Z","steps":["trace[733232057] 'read index received' (duration: 12.727243ms)","trace[733232057] 'applied index is now lower than readState.Index' (duration: 103.875701ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:23.004024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.756125ms","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-16T00:34:23.004053Z","caller":"traceutil/trace.go:171","msg":"trace[703523080] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4564; }","duration":"116.839837ms","start":"2026-06-16T00:34:22.887206Z","end":"2026-06-16T00:34:23.004046Z","steps":["trace[703523080] 'agreement among raft nodes before linearized reading' (duration: 116.713905ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:28.493612Z","caller":"traceutil/trace.go:171","msg":"trace[253530123] linearizableReadLoop","detail":"{readStateIndex:4862; appliedIndex:4861; }","duration":"126.039465ms","start":"2026-06-16T00:34:28.367554Z","end":"2026-06-16T00:34:28.493594Z","steps":["trace[253530123] 'read index received' (duration: 125.861773ms)","trace[253530123] 'applied index is now lower than readState.Index' (duration: 177.162µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:28.493721Z","caller":"traceutil/trace.go:171","msg":"trace[1705580738] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"215.986383ms","start":"2026-06-16T00:34:28.277722Z","end":"2026-06-16T00:34:28.493708Z","steps":["trace[1705580738] 'process raft request' (duration: 215.751209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:28.494104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.545893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-06-16T00:34:28.494131Z","caller":"traceutil/trace.go:171","msg":"trace[2113086521] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4690; }","duration":"126.621824ms","start":"2026-06-16T00:34:28.367501Z","end":"2026-06-16T00:34:28.494123Z","steps":["trace[2113086521] 'agreement among raft nodes before linearized reading' (duration: 126.521573ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:32.023367Z","caller":"traceutil/trace.go:171","msg":"trace[1720595375] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"129.774023ms","start":"2026-06-16T00:34:31.893569Z","end":"2026-06-16T00:34:32.023343Z","steps":["trace[1720595375] 'process raft request' (duration: 129.52508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:32.661187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.499048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c6a10780-5e8c-4be2-a00f-81998e8ad044\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-06-16T00:34:32.661271Z","caller":"traceutil/trace.go:171","msg":"trace[1677674015] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c6a10780-5e8c-4be2-a00f-81998e8ad044; range_end:; response_count:1; response_revision:4771; }","duration":"172.63634ms","start":"2026-06-16T00:34:32.488616Z","end":"2026-06-16T00:34:32.661252Z","steps":["trace[1677674015] 'range keys from in-memory index tree' (duration: 172.336396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:32.661299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.462504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:34:32.661399Z","caller":"traceutil/trace.go:171","msg":"trace[162974919] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"132.796952ms","start":"2026-06-16T00:34:32.528582Z","end":"2026-06-16T00:34:32.661379Z","steps":["trace[162974919] 'process raft request' (duration: 10.182676ms)","trace[162974919] 'compare' (duration: 122.229941ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:34.101593Z","caller":"traceutil/trace.go:171","msg":"trace[210708964] linearizableReadLoop","detail":"{readStateIndex:4967; appliedIndex:4966; }","duration":"161.548738ms","start":"2026-06-16T00:34:33.940023Z","end":"2026-06-16T00:34:34.101572Z","steps":["trace[210708964] 'read index received' (duration: 160.847459ms)","trace[210708964] 'applied index is now lower than readState.Index' (duration: 700.229µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:34.101707Z","caller":"traceutil/trace.go:171","msg":"trace[386332093] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"178.77786ms","start":"2026-06-16T00:34:33.922917Z","end":"2026-06-16T00:34:34.101695Z","steps":["trace[386332093] 'process raft request' (duration: 178.088791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:34.101969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.932593ms","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-16T00:34:34.102008Z","caller":"traceutil/trace.go:171","msg":"trace[386978827] 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:4793; }","duration":"162.008225ms","start":"2026-06-16T00:34:33.939986Z","end":"2026-06-16T00:34:34.101994Z","steps":["trace[386978827] 'agreement among raft nodes before linearized reading' (duration: 161.874462ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:37.418583Z","caller":"traceutil/trace.go:171","msg":"trace[821813137] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"465.567348ms","start":"2026-06-16T00:34:36.952997Z","end":"2026-06-16T00:34:37.418564Z","steps":["trace[821813137] 'process raft request' (duration: 465.525667ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:37.418613Z","caller":"traceutil/trace.go:171","msg":"trace[337139572] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"473.525695ms","start":"2026-06-16T00:34:36.945045Z","end":"2026-06-16T00:34:37.41857Z","steps":["trace[337139572] 'process raft request' (duration: 470.032328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:37.418713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:36.952984Z","time spent":"465.659889ms","remote":"127.0.0.1:37982","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-16T00:34:37.418827Z","caller":"traceutil/trace.go:171","msg":"trace[2098219846] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"471.533169ms","start":"2026-06-16T00:34:36.947286Z","end":"2026-06-16T00:34:37.418819Z","steps":["trace[2098219846] 'process raft request' (duration: 471.137203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:37.418864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:36.947261Z","time spent":"471.580369ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:34:37.41987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:36.945016Z","time spent":"474.708841ms","remote":"127.0.0.1:37982","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-16T00:34:37.420371Z","caller":"traceutil/trace.go:171","msg":"trace[410315947] linearizableReadLoop","detail":"{readStateIndex:5023; appliedIndex:5019; }","duration":"336.237456ms","start":"2026-06-16T00:34:37.084112Z","end":"2026-06-16T00:34:37.42035Z","steps":["trace[410315947] 'read index received' (duration: 331.065616ms)","trace[410315947] 'applied index is now lower than readState.Index' (duration: 5.17053ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:37.420452Z","caller":"traceutil/trace.go:171","msg":"trace[2017135271] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"444.454703ms","start":"2026-06-16T00:34:36.975976Z","end":"2026-06-16T00:34:37.420431Z","steps":["trace[2017135271] 'process raft request' (duration: 443.602222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:37.420522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:36.975955Z","time spent":"444.540225ms","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3002,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:34:37.420459Z","caller":"traceutil/trace.go:171","msg":"trace[1060062860] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"283.78617ms","start":"2026-06-16T00:34:37.136657Z","end":"2026-06-16T00:34:37.420443Z","steps":["trace[1060062860] 'process raft request' (duration: 283.618388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:37.420818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.55458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:37.42085Z","caller":"traceutil/trace.go:171","msg":"trace[1130924164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4848; }","duration":"336.608171ms","start":"2026-06-16T00:34:37.084234Z","end":"2026-06-16T00:34:37.420842Z","steps":["trace[1130924164] 'agreement among raft nodes before linearized reading' (duration: 336.548351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:37.420871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:37.084229Z","time spent":"336.638592ms","remote":"127.0.0.1:37680","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-16T00:34:37.420985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.208593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:34:37.420989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.856184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T00:34:37.42103Z","caller":"traceutil/trace.go:171","msg":"trace[1346427143] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4848; }","duration":"139.257334ms","start":"2026-06-16T00:34:37.281758Z","end":"2026-06-16T00:34:37.421016Z","steps":["trace[1346427143] 'agreement among raft nodes before linearized reading' (duration: 139.174263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:37.421127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.237886ms","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-16T00:34:37.421163Z","caller":"traceutil/trace.go:171","msg":"trace[874604176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4848; }","duration":"336.289556ms","start":"2026-06-16T00:34:37.084865Z","end":"2026-06-16T00:34:37.421155Z","steps":["trace[874604176] 'agreement among raft nodes before linearized reading' (duration: 336.201975ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:37.421035Z","caller":"traceutil/trace.go:171","msg":"trace[1730794877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4848; }","duration":"336.952396ms","start":"2026-06-16T00:34:37.08407Z","end":"2026-06-16T00:34:37.421023Z","steps":["trace[1730794877] 'agreement among raft nodes before linearized reading' (duration: 336.50282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:37.421197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:37.084858Z","time spent":"336.331688ms","remote":"127.0.0.1:37982","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-16T00:34:37.421204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:37.08405Z","time spent":"337.142718ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T00:34:47.512871Z","caller":"traceutil/trace.go:171","msg":"trace[1209991156] linearizableReadLoop","detail":"{readStateIndex:5142; appliedIndex:5141; }","duration":"430.589327ms","start":"2026-06-16T00:34:47.082257Z","end":"2026-06-16T00:34:47.512846Z","steps":["trace[1209991156] 'read index received' (duration: 430.396524ms)","trace[1209991156] 'applied index is now lower than readState.Index' (duration: 191.703µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:47.512999Z","caller":"traceutil/trace.go:171","msg":"trace[554719889] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"453.001557ms","start":"2026-06-16T00:34:47.059946Z","end":"2026-06-16T00:34:47.512948Z","steps":["trace[554719889] 'process raft request' (duration: 452.725333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:47.513089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.814769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:34:47.513154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:47.059923Z","time spent":"453.148039ms","remote":"127.0.0.1:37890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17214,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:47.513248Z","caller":"traceutil/trace.go:171","msg":"trace[37147075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4961; }","duration":"430.950161ms","start":"2026-06-16T00:34:47.082244Z","end":"2026-06-16T00:34:47.513194Z","steps":["trace[37147075] 'agreement among raft nodes before linearized reading' (duration: 430.741568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:47.513338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:47.082195Z","time spent":"431.094983ms","remote":"127.0.0.1:37680","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-16T00:34:47.539812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.757262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-16T00:34:47.539884Z","caller":"traceutil/trace.go:171","msg":"trace[1311308096] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4965; }","duration":"434.871803ms","start":"2026-06-16T00:34:47.104995Z","end":"2026-06-16T00:34:47.539867Z","steps":["trace[1311308096] 'agreement among raft nodes before linearized reading' (duration: 434.64569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:47.539918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:47.104981Z","time spent":"434.930604ms","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-16T00:34:47.688686Z","caller":"traceutil/trace.go:171","msg":"trace[523224256] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"142.225605ms","start":"2026-06-16T00:34:47.546436Z","end":"2026-06-16T00:34:47.688662Z","steps":["trace[523224256] 'process raft request' (duration: 88.857141ms)","trace[523224256] 'compare' (duration: 52.880227ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:47.688882Z","caller":"traceutil/trace.go:171","msg":"trace[1786832884] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"142.089943ms","start":"2026-06-16T00:34:47.546771Z","end":"2026-06-16T00:34:47.688861Z","steps":["trace[1786832884] 'process raft request' (duration: 141.651576ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:47.689153Z","caller":"traceutil/trace.go:171","msg":"trace[1650972137] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"135.967171ms","start":"2026-06-16T00:34:47.553176Z","end":"2026-06-16T00:34:47.689143Z","steps":["trace[1650972137] 'process raft request' (duration: 135.782709ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:47.689248Z","caller":"traceutil/trace.go:171","msg":"trace[230453702] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5146; }","duration":"138.588156ms","start":"2026-06-16T00:34:47.550641Z","end":"2026-06-16T00:34:47.689229Z","steps":["trace[230453702] 'read index received' (duration: 84.625063ms)","trace[230453702] 'applied index is now lower than readState.Index' (duration: 53.961643ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:47.689577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.910291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-16T00:34:47.689663Z","caller":"traceutil/trace.go:171","msg":"trace[204683672] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4969; }","duration":"139.008882ms","start":"2026-06-16T00:34:47.550624Z","end":"2026-06-16T00:34:47.689633Z","steps":["trace[204683672] 'agreement among raft nodes before linearized reading' (duration: 138.711478ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:59.803295Z","caller":"traceutil/trace.go:171","msg":"trace[1798034574] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"163.672029ms","start":"2026-06-16T00:34:59.639592Z","end":"2026-06-16T00:34:59.803264Z","steps":["trace[1798034574] 'process raft request' (duration: 66.09423ms)","trace[1798034574] 'compare' (duration: 96.86747ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:59.803423Z","caller":"traceutil/trace.go:171","msg":"trace[2082096941] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"161.911815ms","start":"2026-06-16T00:34:59.641464Z","end":"2026-06-16T00:34:59.803376Z","steps":["trace[2082096941] 'process raft request' (duration: 161.396289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:35:20.200883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.457026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:35:20.201336Z","caller":"traceutil/trace.go:171","msg":"trace[974077123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5179; }","duration":"116.876151ms","start":"2026-06-16T00:35:20.084345Z","end":"2026-06-16T00:35:20.201222Z","steps":["trace[974077123] 'range keys from in-memory index tree' (duration: 116.02676ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:26.89215Z","caller":"traceutil/trace.go:171","msg":"trace[1717373306] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"160.040185ms","start":"2026-06-16T00:35:26.732064Z","end":"2026-06-16T00:35:26.892104Z","steps":["trace[1717373306] 'process raft request' (duration: 159.811972ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:03.149613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2891} {"level":"info","ts":"2026-06-16T00:36:03.241756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2891,"took":"90.431425ms","hash":2433781502,"current-db-size-bytes":54415360,"current-db-size":"54 MB","current-db-size-in-use-bytes":29347840,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-16T00:36:03.241812Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2433781502,"revision":2891,"compact-revision":-1} {"level":"info","ts":"2026-06-16T00:36:05.527122Z","caller":"traceutil/trace.go:171","msg":"trace[719362908] transaction","detail":"{read_only:false; response_revision:5595; number_of_response:1; }","duration":"461.023012ms","start":"2026-06-16T00:36:05.066074Z","end":"2026-06-16T00:36:05.527097Z","steps":["trace[719362908] 'process raft request' (duration: 460.90425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:36:05.527962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:36:05.066058Z","time spent":"461.845202ms","remote":"127.0.0.1:37982","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-16T00:36:05.527585Z","caller":"traceutil/trace.go:171","msg":"trace[1453956670] linearizableReadLoop","detail":"{readStateIndex:5794; appliedIndex:5794; }","duration":"444.660789ms","start":"2026-06-16T00:36:05.082909Z","end":"2026-06-16T00:36:05.52757Z","steps":["trace[1453956670] 'read index received' (duration: 444.654499ms)","trace[1453956670] 'applied index is now lower than readState.Index' (duration: 4.89µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:36:05.527685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.752911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:36:05.529416Z","caller":"traceutil/trace.go:171","msg":"trace[258630760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5595; }","duration":"446.517963ms","start":"2026-06-16T00:36:05.082886Z","end":"2026-06-16T00:36:05.529403Z","steps":["trace[258630760] 'agreement among raft nodes before linearized reading' (duration: 444.73045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:36:05.529482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:36:05.082875Z","time spent":"446.596684ms","remote":"127.0.0.1:37680","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-16T00:36:05.529354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.642036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-16T00:36:05.529765Z","caller":"traceutil/trace.go:171","msg":"trace[275898174] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5595; }","duration":"384.078312ms","start":"2026-06-16T00:36:05.145673Z","end":"2026-06-16T00:36:05.529751Z","steps":["trace[275898174] 'agreement among raft nodes before linearized reading' (duration: 383.570085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:36:05.529829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:36:05.145659Z","time spent":"384.157534ms","remote":"127.0.0.1:37890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-16T00:36:05.529363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.939463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:36:05.53012Z","caller":"traceutil/trace.go:171","msg":"trace[1168104818] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:5595; }","duration":"245.703124ms","start":"2026-06-16T00:36:05.284401Z","end":"2026-06-16T00:36:05.530105Z","steps":["trace[1168104818] 'agreement among raft nodes before linearized reading' (duration: 244.911783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:36:08.516388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.255772ms","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-16T00:36:08.516493Z","caller":"traceutil/trace.go:171","msg":"trace[93589130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5611; }","duration":"130.453814ms","start":"2026-06-16T00:36:08.386019Z","end":"2026-06-16T00:36:08.516473Z","steps":["trace[93589130] 'range keys from in-memory index tree' (duration: 130.07384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:36:08.516689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.176788ms","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-16T00:36:08.516841Z","caller":"traceutil/trace.go:171","msg":"trace[1905344539] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5611; }","duration":"102.34393ms","start":"2026-06-16T00:36:08.414459Z","end":"2026-06-16T00:36:08.516803Z","steps":["trace[1905344539] 'range keys from in-memory index tree' (duration: 101.58392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:38:41.625083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.529874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-16T00:38:41.625176Z","caller":"traceutil/trace.go:171","msg":"trace[965422726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6644; }","duration":"118.651808ms","start":"2026-06-16T00:38:41.506499Z","end":"2026-06-16T00:38:41.625151Z","steps":["trace[965422726] 'range keys from in-memory index tree' (duration: 118.366609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:38:41.625062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.628792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-06-16T00:38:41.625383Z","caller":"traceutil/trace.go:171","msg":"trace[1597818950] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6644; }","duration":"145.985773ms","start":"2026-06-16T00:38:41.47936Z","end":"2026-06-16T00:38:41.625346Z","steps":["trace[1597818950] 'range keys from in-memory index tree' (duration: 145.435066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:38:41.625407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.040343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-06-16T00:38:41.625497Z","caller":"traceutil/trace.go:171","msg":"trace[240454993] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6644; }","duration":"101.173698ms","start":"2026-06-16T00:38:41.524303Z","end":"2026-06-16T00:38:41.625477Z","steps":["trace[240454993] 'range keys from in-memory index tree' (duration: 100.247669ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:38:42.895945Z","caller":"traceutil/trace.go:171","msg":"trace[1594945486] transaction","detail":"{read_only:false; response_revision:6651; number_of_response:1; }","duration":"113.41252ms","start":"2026-06-16T00:38:42.782507Z","end":"2026-06-16T00:38:42.89592Z","steps":["trace[1594945486] 'process raft request' (duration: 71.318391ms)","trace[1594945486] 'compare' (duration: 41.970355ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:39:02.876433Z","caller":"traceutil/trace.go:171","msg":"trace[1654283115] transaction","detail":"{read_only:false; response_revision:6803; number_of_response:1; }","duration":"130.419803ms","start":"2026-06-16T00:39:02.745988Z","end":"2026-06-16T00:39:02.876408Z","steps":["trace[1654283115] 'process raft request' (duration: 130.253648ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:39:03.015539Z","caller":"traceutil/trace.go:171","msg":"trace[1210911307] transaction","detail":"{read_only:false; response_revision:6804; number_of_response:1; }","duration":"168.090772ms","start":"2026-06-16T00:39:02.847427Z","end":"2026-06-16T00:39:03.015518Z","steps":["trace[1210911307] 'process raft request' (duration: 167.939888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:22.323376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.232937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/percona-xtradb\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-06-16T00:39:22.323465Z","caller":"traceutil/trace.go:171","msg":"trace[1242000762] range","detail":"{range_begin:/registry/secrets/openstack/percona-xtradb; range_end:; response_count:1; response_revision:6909; }","duration":"229.339229ms","start":"2026-06-16T00:39:22.0941Z","end":"2026-06-16T00:39:22.32344Z","steps":["trace[1242000762] 'range keys from in-memory index tree' (duration: 229.096393ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:03.156789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5586} {"level":"info","ts":"2026-06-16T00:41:03.236948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5586,"took":"78.651088ms","hash":3934550932,"current-db-size-bytes":54415360,"current-db-size":"54 MB","current-db-size-in-use-bytes":18624512,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-16T00:41:03.237016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3934550932,"revision":5586,"compact-revision":2891} {"level":"info","ts":"2026-06-16T00:41:27.736663Z","caller":"traceutil/trace.go:171","msg":"trace[1314854882] transaction","detail":"{read_only:false; response_revision:7565; number_of_response:1; }","duration":"174.817905ms","start":"2026-06-16T00:41:27.561821Z","end":"2026-06-16T00:41:27.736639Z","steps":["trace[1314854882] 'process raft request' (duration: 174.630841ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:27.891403Z","caller":"traceutil/trace.go:171","msg":"trace[1816669757] linearizableReadLoop","detail":"{readStateIndex:7840; appliedIndex:7838; }","duration":"267.727969ms","start":"2026-06-16T00:41:27.623649Z","end":"2026-06-16T00:41:27.891377Z","steps":["trace[1816669757] 'read index received' (duration: 112.846903ms)","trace[1816669757] 'applied index is now lower than readState.Index' (duration: 154.880076ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:41:27.89155Z","caller":"traceutil/trace.go:171","msg":"trace[595785479] transaction","detail":"{read_only:false; response_revision:7566; number_of_response:1; }","duration":"325.705657ms","start":"2026-06-16T00:41:27.565814Z","end":"2026-06-16T00:41:27.891519Z","steps":["trace[595785479] 'process raft request' (duration: 226.002374ms)","trace[595785479] 'compare' (duration: 99.457297ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:41:27.891585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.921733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-06-16T00:41:27.891623Z","caller":"traceutil/trace.go:171","msg":"trace[576753416] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:7566; }","duration":"268.000605ms","start":"2026-06-16T00:41:27.623609Z","end":"2026-06-16T00:41:27.891609Z","steps":["trace[576753416] 'agreement among raft nodes before linearized reading' (duration: 267.914623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:27.891689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:41:27.565793Z","time spent":"325.821689ms","remote":"127.0.0.1:37982","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-16T00:41:27.892259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.021788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:41:27.892338Z","caller":"traceutil/trace.go:171","msg":"trace[221301269] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:7566; }","duration":"158.154192ms","start":"2026-06-16T00:41:27.734166Z","end":"2026-06-16T00:41:27.89232Z","steps":["trace[221301269] 'agreement among raft nodes before linearized reading' (duration: 157.974247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:27.892374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.829467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:41:27.89241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.875875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:41:27.892488Z","caller":"traceutil/trace.go:171","msg":"trace[1257311951] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:7566; }","duration":"154.996608ms","start":"2026-06-16T00:41:27.73747Z","end":"2026-06-16T00:41:27.892467Z","steps":["trace[1257311951] 'agreement among raft nodes before linearized reading' (duration: 154.825544ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:27.89245Z","caller":"traceutil/trace.go:171","msg":"trace[1359677718] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:7566; }","duration":"255.933049ms","start":"2026-06-16T00:41:27.636501Z","end":"2026-06-16T00:41:27.892434Z","steps":["trace[1359677718] 'agreement among raft nodes before linearized reading' (duration: 255.802386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:27.892826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.317114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:1 size:603"} {"level":"info","ts":"2026-06-16T00:41:27.892986Z","caller":"traceutil/trace.go:171","msg":"trace[486898234] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:1; response_revision:7566; }","duration":"260.503838ms","start":"2026-06-16T00:41:27.632463Z","end":"2026-06-16T00:41:27.892967Z","steps":["trace[486898234] 'agreement among raft nodes before linearized reading' (duration: 259.33237ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:28.108027Z","caller":"traceutil/trace.go:171","msg":"trace[70283396] linearizableReadLoop","detail":"{readStateIndex:7842; appliedIndex:7841; }","duration":"133.109038ms","start":"2026-06-16T00:41:27.974894Z","end":"2026-06-16T00:41:28.108003Z","steps":["trace[70283396] 'read index received' (duration: 660.256µs)","trace[70283396] 'applied index is now lower than readState.Index' (duration: 132.447712ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:41:28.108129Z","caller":"traceutil/trace.go:171","msg":"trace[1652511773] transaction","detail":"{read_only:false; response_revision:7568; number_of_response:1; }","duration":"207.310299ms","start":"2026-06-16T00:41:27.900791Z","end":"2026-06-16T00:41:28.108101Z","steps":["trace[1652511773] 'process raft request' (duration: 117.742918ms)","trace[1652511773] 'compare' (duration: 89.345836ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:41:28.10821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.296893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-06-16T00:41:28.108298Z","caller":"traceutil/trace.go:171","msg":"trace[1397617985] range","detail":"{range_begin:/registry/clusterrolebindings/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:7568; }","duration":"133.421925ms","start":"2026-06-16T00:41:27.974855Z","end":"2026-06-16T00:41:28.108277Z","steps":["trace[1397617985] 'agreement among raft nodes before linearized reading' (duration: 133.273982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:28.108299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.323306ms","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":"info","ts":"2026-06-16T00:41:28.108375Z","caller":"traceutil/trace.go:171","msg":"trace[265506911] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:7568; }","duration":"109.433139ms","start":"2026-06-16T00:41:27.998922Z","end":"2026-06-16T00:41:28.108355Z","steps":["trace[265506911] 'agreement among raft nodes before linearized reading' (duration: 109.314026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:28.108202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.028425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-06-16T00:41:28.108448Z","caller":"traceutil/trace.go:171","msg":"trace[640392872] range","detail":"{range_begin:/registry/clusterrolebindings/system:public-info-viewer; range_end:; response_count:1; response_revision:7568; }","duration":"130.316552ms","start":"2026-06-16T00:41:27.978118Z","end":"2026-06-16T00:41:28.108434Z","steps":["trace[640392872] 'agreement among raft nodes before linearized reading' (duration: 130.021055ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:28.815625Z","caller":"traceutil/trace.go:171","msg":"trace[1937024811] linearizableReadLoop","detail":"{readStateIndex:7845; appliedIndex:7844; }","duration":"137.594633ms","start":"2026-06-16T00:41:28.67801Z","end":"2026-06-16T00:41:28.815605Z","steps":["trace[1937024811] 'read index received' (duration: 137.44018ms)","trace[1937024811] 'applied index is now lower than readState.Index' (duration: 153.433µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:41:28.815798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.746377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/cert-manager/\" range_end:\"/registry/resourcequotas/cert-manager0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:41:28.815898Z","caller":"traceutil/trace.go:171","msg":"trace[380543337] range","detail":"{range_begin:/registry/resourcequotas/cert-manager/; range_end:/registry/resourcequotas/cert-manager0; response_count:0; response_revision:7571; }","duration":"137.885771ms","start":"2026-06-16T00:41:28.677992Z","end":"2026-06-16T00:41:28.815878Z","steps":["trace[380543337] 'agreement among raft nodes before linearized reading' (duration: 137.701326ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:28.816207Z","caller":"traceutil/trace.go:171","msg":"trace[1559441766] transaction","detail":"{read_only:false; response_revision:7571; number_of_response:1; }","duration":"140.531283ms","start":"2026-06-16T00:41:28.675155Z","end":"2026-06-16T00:41:28.815686Z","steps":["trace[1559441766] 'process raft request' (duration: 140.317598ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:30.097284Z","caller":"traceutil/trace.go:171","msg":"trace[973104987] linearizableReadLoop","detail":"{readStateIndex:7850; appliedIndex:7849; }","duration":"117.877661ms","start":"2026-06-16T00:41:29.979377Z","end":"2026-06-16T00:41:30.097255Z","steps":["trace[973104987] 'read index received' (duration: 117.666966ms)","trace[973104987] 'applied index is now lower than readState.Index' (duration: 209.555µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:41:30.097326Z","caller":"traceutil/trace.go:171","msg":"trace[401288196] transaction","detail":"{read_only:false; response_revision:7576; number_of_response:1; }","duration":"200.564798ms","start":"2026-06-16T00:41:29.896737Z","end":"2026-06-16T00:41:30.097302Z","steps":["trace[401288196] 'process raft request' (duration: 200.379584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:30.097482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.088816ms","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-16T00:41:30.097663Z","caller":"traceutil/trace.go:171","msg":"trace[1504008981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7576; }","duration":"118.320601ms","start":"2026-06-16T00:41:29.979327Z","end":"2026-06-16T00:41:30.097647Z","steps":["trace[1504008981] 'agreement among raft nodes before linearized reading' (duration: 118.053895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:30.402067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.015627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:41:30.40218Z","caller":"traceutil/trace.go:171","msg":"trace[97759773] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:7577; }","duration":"193.14766ms","start":"2026-06-16T00:41:30.208981Z","end":"2026-06-16T00:41:30.402129Z","steps":["trace[97759773] 'count revisions from in-memory index tree' (duration: 192.914255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:30.87942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.290418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:12 size:4389"} {"level":"info","ts":"2026-06-16T00:41:30.879508Z","caller":"traceutil/trace.go:171","msg":"trace[218742440] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:12; response_revision:7579; }","duration":"126.431781ms","start":"2026-06-16T00:41:30.753058Z","end":"2026-06-16T00:41:30.87949Z","steps":["trace[218742440] 'range keys from in-memory index tree' (duration: 126.041213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:31.204793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.482487ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-16T00:41:31.204964Z","caller":"traceutil/trace.go:171","msg":"trace[326516909] linearizableReadLoop","detail":"{readStateIndex:7855; appliedIndex:7854; }","duration":"217.616738ms","start":"2026-06-16T00:41:30.98732Z","end":"2026-06-16T00:41:31.204937Z","steps":["trace[326516909] 'read index received' (duration: 79.914056ms)","trace[326516909] 'applied index is now lower than readState.Index' (duration: 137.700502ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:41:31.205419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.635869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-16T00:41:31.205467Z","caller":"traceutil/trace.go:171","msg":"trace[715214271] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7580; }","duration":"168.728332ms","start":"2026-06-16T00:41:31.036723Z","end":"2026-06-16T00:41:31.205451Z","steps":["trace[715214271] 'agreement among raft nodes before linearized reading' (duration: 168.441545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:31.205747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.395277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/cert-manager/\" range_end:\"/registry/configmaps/cert-manager0\" limit:500 ","response":"range_response_count:2 size:2664"} {"level":"info","ts":"2026-06-16T00:41:31.205953Z","caller":"traceutil/trace.go:171","msg":"trace[843089229] range","detail":"{range_begin:/registry/configmaps/cert-manager/; range_end:/registry/configmaps/cert-manager0; response_count:2; response_revision:7580; }","duration":"218.647023ms","start":"2026-06-16T00:41:30.987283Z","end":"2026-06-16T00:41:31.20593Z","steps":["trace[843089229] 'agreement among raft nodes before linearized reading' (duration: 217.736392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:31.974051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220542198811764,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T00:41:32.02253Z","caller":"traceutil/trace.go:171","msg":"trace[435617739] transaction","detail":"{read_only:false; response_revision:7582; number_of_response:1; }","duration":"558.697434ms","start":"2026-06-16T00:41:31.463752Z","end":"2026-06-16T00:41:32.02245Z","steps":["trace[435617739] 'process raft request' (duration: 558.463798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.02287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:41:31.463731Z","time spent":"558.97431ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:41:32.248039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.605006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:41:32.248363Z","caller":"traceutil/trace.go:171","msg":"trace[744339139] transaction","detail":"{read_only:false; response_revision:7585; number_of_response:1; }","duration":"145.624274ms","start":"2026-06-16T00:41:32.102717Z","end":"2026-06-16T00:41:32.248341Z","steps":["trace[744339139] 'process raft request' (duration: 145.576092ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:32.248537Z","caller":"traceutil/trace.go:171","msg":"trace[1202622084] transaction","detail":"{read_only:false; response_revision:7584; number_of_response:1; }","duration":"461.034745ms","start":"2026-06-16T00:41:31.787469Z","end":"2026-06-16T00:41:32.248504Z","steps":["trace[1202622084] 'process raft request' (duration: 460.664747ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:32.248539Z","caller":"traceutil/trace.go:171","msg":"trace[674788019] linearizableReadLoop","detail":"{readStateIndex:7859; appliedIndex:7857; }","duration":"774.702838ms","start":"2026-06-16T00:41:31.4738Z","end":"2026-06-16T00:41:32.248502Z","steps":["trace[674788019] 'read index received' (duration: 548.806562ms)","trace[674788019] 'applied index is now lower than readState.Index' (duration: 225.893626ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:41:32.24864Z","caller":"traceutil/trace.go:171","msg":"trace[1363348394] transaction","detail":"{read_only:false; response_revision:7583; number_of_response:1; }","duration":"745.944352ms","start":"2026-06-16T00:41:31.502632Z","end":"2026-06-16T00:41:32.248576Z","steps":["trace[1363348394] 'process raft request' (duration: 575.713981ms)","trace[1363348394] 'compare' (duration: 169.492374ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:41:32.248723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:41:31.787442Z","time spent":"461.180818ms","remote":"127.0.0.1:37982","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-16T00:41:32.248721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.912883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/auth-system/\" range_end:\"/registry/deployments/auth-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:41:32.248816Z","caller":"traceutil/trace.go:171","msg":"trace[1754706688] range","detail":"{range_begin:/registry/deployments/auth-system/; range_end:/registry/deployments/auth-system0; response_count:0; response_revision:7585; }","duration":"774.984665ms","start":"2026-06-16T00:41:31.473792Z","end":"2026-06-16T00:41:32.248777Z","steps":["trace[1754706688] 'agreement among raft nodes before linearized reading' (duration: 774.887132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.248864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:41:31.473736Z","time spent":"775.114577ms","remote":"127.0.0.1:38156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/auth-system/\" range_end:\"/registry/deployments/auth-system0\" limit:500 "} {"level":"warn","ts":"2026-06-16T00:41:32.248905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:41:31.502611Z","time spent":"746.143307ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:41:32.249033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.573096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/\" range_end:\"/registry/deployments/cert-manager0\" limit:500 ","response":"range_response_count:3 size:13639"} {"level":"warn","ts":"2026-06-16T00:41:32.249035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.849028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:923"} {"level":"info","ts":"2026-06-16T00:41:32.249077Z","caller":"traceutil/trace.go:171","msg":"trace[765272938] range","detail":"{range_begin:/registry/deployments/cert-manager/; range_end:/registry/deployments/cert-manager0; response_count:3; response_revision:7585; }","duration":"743.633898ms","start":"2026-06-16T00:41:31.505431Z","end":"2026-06-16T00:41:32.249064Z","steps":["trace[765272938] 'agreement among raft nodes before linearized reading' (duration: 743.457034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.249109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:41:31.505423Z","time spent":"743.678359ms","remote":"127.0.0.1:38156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":3,"response size":13661,"request content":"key:\"/registry/deployments/cert-manager/\" range_end:\"/registry/deployments/cert-manager0\" limit:500 "} {"level":"info","ts":"2026-06-16T00:41:32.249102Z","caller":"traceutil/trace.go:171","msg":"trace[685285092] range","detail":"{range_begin:/registry/configmaps/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:7585; }","duration":"773.94416ms","start":"2026-06-16T00:41:31.475145Z","end":"2026-06-16T00:41:32.249089Z","steps":["trace[685285092] 'agreement among raft nodes before linearized reading' (duration: 773.627752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.24917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:41:31.475139Z","time spent":"774.018731ms","remote":"127.0.0.1:37792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":945,"request content":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-06-16T00:41:32.249203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.144457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:41:32.249252Z","caller":"traceutil/trace.go:171","msg":"trace[978632082] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:7585; }","duration":"346.226659ms","start":"2026-06-16T00:41:31.903009Z","end":"2026-06-16T00:41:32.249235Z","steps":["trace[978632082] 'agreement among raft nodes before linearized reading' (duration: 346.122297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.249304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:41:31.902994Z","time spent":"346.299431ms","remote":"127.0.0.1:42994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:41:32.249553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.067309ms","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-16T00:41:32.249645Z","caller":"traceutil/trace.go:171","msg":"trace[864832488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7585; }","duration":"101.231463ms","start":"2026-06-16T00:41:32.148395Z","end":"2026-06-16T00:41:32.249626Z","steps":["trace[864832488] 'agreement among raft nodes before linearized reading' (duration: 101.007678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.249585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.198011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/cert-manager/kube-root-ca.crt\" ","response":"range_response_count:1 size:1748"} {"level":"warn","ts":"2026-06-16T00:41:32.250234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.335646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:41:32.250301Z","caller":"traceutil/trace.go:171","msg":"trace[650743023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7585; }","duration":"165.992182ms","start":"2026-06-16T00:41:32.084294Z","end":"2026-06-16T00:41:32.250286Z","steps":["trace[650743023] 'agreement among raft nodes before linearized reading' (duration: 164.519157ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:32.249761Z","caller":"traceutil/trace.go:171","msg":"trace[1249783426] range","detail":"{range_begin:/registry/configmaps/cert-manager/kube-root-ca.crt; range_end:; response_count:1; response_revision:7585; }","duration":"747.402706ms","start":"2026-06-16T00:41:31.502346Z","end":"2026-06-16T00:41:32.249749Z","steps":["trace[1249783426] 'agreement among raft nodes before linearized reading' (duration: 747.15774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.251326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:41:31.502329Z","time spent":"748.954423ms","remote":"127.0.0.1:37792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":1770,"request content":"key:\"/registry/configmaps/cert-manager/kube-root-ca.crt\" "} {"level":"info","ts":"2026-06-16T00:41:32.681887Z","caller":"traceutil/trace.go:171","msg":"trace[830603302] linearizableReadLoop","detail":"{readStateIndex:7862; appliedIndex:7861; }","duration":"147.732534ms","start":"2026-06-16T00:41:32.534126Z","end":"2026-06-16T00:41:32.681859Z","steps":["trace[830603302] 'read index received' (duration: 147.55264ms)","trace[830603302] 'applied index is now lower than readState.Index' (duration: 178.304µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:41:32.682079Z","caller":"traceutil/trace.go:171","msg":"trace[2081757906] transaction","detail":"{read_only:false; response_revision:7587; number_of_response:1; }","duration":"221.134754ms","start":"2026-06-16T00:41:32.460817Z","end":"2026-06-16T00:41:32.681952Z","steps":["trace[2081757906] 'process raft request' (duration: 220.836927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.682262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.064112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4749"} {"level":"warn","ts":"2026-06-16T00:41:32.682278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.885393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/cert-manager/\" range_end:\"/registry/jobs/cert-manager0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:41:32.682338Z","caller":"traceutil/trace.go:171","msg":"trace[1998067466] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:7587; }","duration":"148.182735ms","start":"2026-06-16T00:41:32.534139Z","end":"2026-06-16T00:41:32.682321Z","steps":["trace[1998067466] 'agreement among raft nodes before linearized reading' (duration: 147.961739ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:41:32.682371Z","caller":"traceutil/trace.go:171","msg":"trace[1582959807] range","detail":"{range_begin:/registry/jobs/cert-manager/; range_end:/registry/jobs/cert-manager0; response_count:0; response_revision:7587; }","duration":"141.010206ms","start":"2026-06-16T00:41:32.54132Z","end":"2026-06-16T00:41:32.68233Z","steps":["trace[1582959807] 'agreement among raft nodes before linearized reading' (duration: 140.863132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.682359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.052421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/auth-system/\" range_end:\"/registry/jobs/auth-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:41:32.682534Z","caller":"traceutil/trace.go:171","msg":"trace[1068540321] range","detail":"{range_begin:/registry/jobs/auth-system/; range_end:/registry/jobs/auth-system0; response_count:0; response_revision:7587; }","duration":"142.255405ms","start":"2026-06-16T00:41:32.540254Z","end":"2026-06-16T00:41:32.682509Z","steps":["trace[1068540321] 'agreement among raft nodes before linearized reading' (duration: 141.959578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:32.683006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.8598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:3890"} {"level":"info","ts":"2026-06-16T00:41:32.683122Z","caller":"traceutil/trace.go:171","msg":"trace[864717111] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:7587; }","duration":"149.015934ms","start":"2026-06-16T00:41:32.534092Z","end":"2026-06-16T00:41:32.683108Z","steps":["trace[864717111] 'agreement among raft nodes before linearized reading' (duration: 147.98444ms)"],"step_count":1}