{"level":"warn","ts":"2026-04-16T16:22:42.066034Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:22:42.066126Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-04-16T16:22:42.066194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:22:42.066204Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-16T16:22:42.066221Z","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-04-16T16:22:42.066978Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-04-16T16:22:42.06705Z","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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-04-16T16:22:42.070917Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.642321ms"} {"level":"info","ts":"2026-04-16T16:22:42.078375Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-04-16T16:22:42.078473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:22:42.078496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-04-16T16:22:42.078512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:22:42.078538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-04-16T16:22:42.078591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-04-16T16:22:42.087202Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:22:42.089108Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:22:42.090747Z","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-04-16T16:22:42.093275Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:22:42.093439Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:22:42.093709Z","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-04-16T16:22:42.093967Z","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-04-16T16:22:42.09401Z","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-04-16T16:22:42.094193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-04-16T16:22:42.096843Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-16T16:22:42.099341Z","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-04-16T16:22:42.099551Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:22:42.099595Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:22:42.099663Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-16T16:22:42.099686Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-16T16:22:42.679838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:22:42.679895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:22:42.679922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-04-16T16:22:42.679938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:22:42.679943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-16T16:22:42.679951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-04-16T16:22:42.679959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-16T16:22:42.6809Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:22:42.680967Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:22:42.681037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:22:42.681017Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:22:42.681471Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:22:42.681519Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T16:22:42.681921Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:22:42.682043Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:22:42.682132Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:22:42.684311Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-04-16T16:22:42.685229Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T16:22:48.883138Z","caller":"traceutil/trace.go:171","msg":"trace[580283245] linearizableReadLoop","detail":"{readStateIndex:275; appliedIndex:272; }","duration":"364.07375ms","start":"2026-04-16T16:22:48.519046Z","end":"2026-04-16T16:22:48.88312Z","steps":["trace[580283245] 'read index received' (duration: 297.850306ms)","trace[580283245] 'applied index is now lower than readState.Index' (duration: 66.222874ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:22:48.883235Z","caller":"traceutil/trace.go:171","msg":"trace[1446709194] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"367.635227ms","start":"2026-04-16T16:22:48.515592Z","end":"2026-04-16T16:22:48.883228Z","steps":["trace[1446709194] 'process raft request' (duration: 301.295101ms)","trace[1446709194] 'compare' (duration: 66.139944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:22:48.883647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:48.515561Z","time spent":"367.691237ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:22:48.883797Z","caller":"traceutil/trace.go:171","msg":"trace[1090021310] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"368.064221ms","start":"2026-04-16T16:22:48.515727Z","end":"2026-04-16T16:22:48.883791Z","steps":["trace[1090021310] 'process raft request' (duration: 367.343313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:48.883852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:48.515712Z","time spent":"368.109201ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:22:48.883939Z","caller":"traceutil/trace.go:171","msg":"trace[93893280] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"368.172343ms","start":"2026-04-16T16:22:48.51576Z","end":"2026-04-16T16:22:48.883933Z","steps":["trace[93893280] 'process raft request' (duration: 367.337893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:48.883983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:48.515748Z","time spent":"368.213363ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:22:48.884093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.044311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-16T16:22:48.884131Z","caller":"traceutil/trace.go:171","msg":"trace[794770380] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:261; }","duration":"365.118371ms","start":"2026-04-16T16:22:48.519005Z","end":"2026-04-16T16:22:48.884124Z","steps":["trace[794770380] 'agreement among raft nodes before linearized reading' (duration: 365.052211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:48.884151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:48.518993Z","time spent":"365.152942ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2944,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-16T16:22:49.091648Z","caller":"traceutil/trace.go:171","msg":"trace[1991481907] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"208.425276ms","start":"2026-04-16T16:22:48.883202Z","end":"2026-04-16T16:22:49.091627Z","steps":["trace[1991481907] 'read index received' (duration: 208.242474ms)","trace[1991481907] 'applied index is now lower than readState.Index' (duration: 182.192µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:22:49.092128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.104747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:22:49.092175Z","caller":"traceutil/trace.go:171","msg":"trace[302804540] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:262; }","duration":"545.194107ms","start":"2026-04-16T16:22:48.546966Z","end":"2026-04-16T16:22:49.09216Z","steps":["trace[302804540] 'agreement among raft nodes before linearized reading' (duration: 545.090466ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:49.092164Z","caller":"traceutil/trace.go:171","msg":"trace[300928630] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"554.086718ms","start":"2026-04-16T16:22:48.53805Z","end":"2026-04-16T16:22:49.092137Z","steps":["trace[300928630] 'process raft request' (duration: 553.468592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:49.092208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:48.546948Z","time spent":"545.252718ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-16T16:22:49.092283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.335295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:22:49.092333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.202003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"warn","ts":"2026-04-16T16:22:49.092333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.316843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-04-16T16:22:49.092417Z","caller":"traceutil/trace.go:171","msg":"trace[576533191] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:262; }","duration":"207.302673ms","start":"2026-04-16T16:22:48.885096Z","end":"2026-04-16T16:22:49.092399Z","steps":["trace[576533191] 'agreement among raft nodes before linearized reading' (duration: 207.106792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:49.092448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:48.538025Z","time spent":"554.210529ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:22:49.092468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.427585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5890"} {"level":"info","ts":"2026-04-16T16:22:49.092501Z","caller":"traceutil/trace.go:171","msg":"trace[1515745669] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:262; }","duration":"207.496575ms","start":"2026-04-16T16:22:48.884998Z","end":"2026-04-16T16:22:49.092494Z","steps":["trace[1515745669] 'agreement among raft nodes before linearized reading' (duration: 207.432725ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:49.092482Z","caller":"traceutil/trace.go:171","msg":"trace[1359652466] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:262; }","duration":"207.511326ms","start":"2026-04-16T16:22:48.884931Z","end":"2026-04-16T16:22:49.092442Z","steps":["trace[1359652466] 'agreement among raft nodes before linearized reading' (duration: 207.220083ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:49.092383Z","caller":"traceutil/trace.go:171","msg":"trace[1641483725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:262; }","duration":"476.492787ms","start":"2026-04-16T16:22:48.615869Z","end":"2026-04-16T16:22:49.092362Z","steps":["trace[1641483725] 'agreement among raft nodes before linearized reading' (duration: 476.305625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:49.092645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:48.615847Z","time spent":"476.78986ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:22:51.146072Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.007069972s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:22:51.146818Z","caller":"traceutil/trace.go:171","msg":"trace[1193602338] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"2.040815362s","start":"2026-04-16T16:22:49.105974Z","end":"2026-04-16T16:22:51.14679Z","steps":["trace[1193602338] 'read index received' (duration: 33.051073ms)","trace[1193602338] 'applied index is now lower than readState.Index' (duration: 2.007762509s)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:22:51.146888Z","caller":"traceutil/trace.go:171","msg":"trace[62674574] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"2.040771842s","start":"2026-04-16T16:22:49.106107Z","end":"2026-04-16T16:22:51.146879Z","steps":["trace[62674574] 'process raft request' (duration: 2.040453799s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:51.146812Z","caller":"traceutil/trace.go:171","msg":"trace[1428341183] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"2.042035065s","start":"2026-04-16T16:22:49.104738Z","end":"2026-04-16T16:22:51.146773Z","steps":["trace[1428341183] 'process raft request' (duration: 2.041632231s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:51.147054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:49.1061Z","time spent":"2.040856133s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:22:51.147084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:49.104728Z","time spent":"2.042257847s","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:22:51.14711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041122735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T16:22:51.147107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.005412725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:22:51.147164Z","caller":"traceutil/trace.go:171","msg":"trace[2075806545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:266; }","duration":"2.041193006s","start":"2026-04-16T16:22:49.105955Z","end":"2026-04-16T16:22:51.147148Z","steps":["trace[2075806545] 'agreement among raft nodes before linearized reading' (duration: 2.040986604s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:51.147192Z","caller":"traceutil/trace.go:171","msg":"trace[943699659] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:266; }","duration":"2.005546077s","start":"2026-04-16T16:22:49.141621Z","end":"2026-04-16T16:22:51.147167Z","steps":["trace[943699659] 'agreement among raft nodes before linearized reading' (duration: 2.005386735s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:51.14722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:49.105948Z","time spent":"2.041256928s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:22:51.147241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:49.1416Z","time spent":"2.005632268s","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" "} {"level":"warn","ts":"2026-04-16T16:22:51.14723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305031241s","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-04-16T16:22:51.147341Z","caller":"traceutil/trace.go:171","msg":"trace[1177733132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:266; }","duration":"1.305208693s","start":"2026-04-16T16:22:49.842109Z","end":"2026-04-16T16:22:51.147318Z","steps":["trace[1177733132] 'agreement among raft nodes before linearized reading' (duration: 1.30495515s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:51.14739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:49.842082Z","time spent":"1.305298815s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:22:51.147471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049084331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T16:22:51.147516Z","caller":"traceutil/trace.go:171","msg":"trace[340387951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:266; }","duration":"1.049175971s","start":"2026-04-16T16:22:50.098328Z","end":"2026-04-16T16:22:51.147504Z","steps":["trace[340387951] 'agreement among raft nodes before linearized reading' (duration: 1.04905315s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:51.147547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:50.098305Z","time spent":"1.049233632s","remote":"127.0.0.1:34784","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-04-16T16:22:51.147534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758004141s","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-04-16T16:22:51.147592Z","caller":"traceutil/trace.go:171","msg":"trace[19358038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:266; }","duration":"1.758096722s","start":"2026-04-16T16:22:49.389476Z","end":"2026-04-16T16:22:51.147573Z","steps":["trace[19358038] 'agreement among raft nodes before linearized reading' (duration: 1.757995651s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:51.147635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:22:49.389457Z","time spent":"1.758167522s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T16:22:52.215241Z","caller":"traceutil/trace.go:171","msg":"trace[526480596] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"211.743874ms","start":"2026-04-16T16:22:52.003465Z","end":"2026-04-16T16:22:52.215209Z","steps":["trace[526480596] 'process raft request' (duration: 211.580762ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:17.616762Z","caller":"traceutil/trace.go:171","msg":"trace[1360096594] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"116.581241ms","start":"2026-04-16T16:23:17.500153Z","end":"2026-04-16T16:23:17.616734Z","steps":["trace[1360096594] 'process raft request' (duration: 116.072777ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:19.458126Z","caller":"traceutil/trace.go:171","msg":"trace[1447362660] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"260.789727ms","start":"2026-04-16T16:23:19.197316Z","end":"2026-04-16T16:23:19.458105Z","steps":["trace[1447362660] 'process raft request' (duration: 260.368003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:19.837053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.354214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:23:19.837244Z","caller":"traceutil/trace.go:171","msg":"trace[172506723] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"363.83877ms","start":"2026-04-16T16:23:19.473387Z","end":"2026-04-16T16:23:19.837226Z","steps":["trace[172506723] 'read index received' (duration: 41.103662ms)","trace[172506723] 'applied index is now lower than readState.Index' (duration: 322.733808ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:23:19.837321Z","caller":"traceutil/trace.go:171","msg":"trace[250244678] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"366.421381ms","start":"2026-04-16T16:23:19.470889Z","end":"2026-04-16T16:23:19.83731Z","steps":["trace[250244678] 'process raft request' (duration: 43.559423ms)","trace[250244678] 'compare' (duration: 322.227713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:23:19.837397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:19.470868Z","time spent":"366.488252ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:23:19.837486Z","caller":"traceutil/trace.go:171","msg":"trace[689791688] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"366.440081ms","start":"2026-04-16T16:23:19.470987Z","end":"2026-04-16T16:23:19.837427Z","steps":["trace[689791688] 'process raft request' (duration: 366.183439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:19.837738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:19.470871Z","time spent":"366.731024ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:23:19.837757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.350774ms","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-04-16T16:23:19.838473Z","caller":"traceutil/trace.go:171","msg":"trace[103198193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"365.08593ms","start":"2026-04-16T16:23:19.473345Z","end":"2026-04-16T16:23:19.838431Z","steps":["trace[103198193] 'agreement among raft nodes before linearized reading' (duration: 364.172913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:19.838534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:19.47333Z","time spent":"365.185672ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T16:23:19.8405Z","caller":"traceutil/trace.go:171","msg":"trace[1304245442] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"315.398638ms","start":"2026-04-16T16:23:19.525068Z","end":"2026-04-16T16:23:19.840467Z","steps":["trace[1304245442] 'process raft request' (duration: 315.207226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:19.840639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:19.52504Z","time spent":"315.524429ms","remote":"127.0.0.1:34860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:23:19.840712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.473891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s6hkk\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-16T16:23:19.840702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.388584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T16:23:19.840759Z","caller":"traceutil/trace.go:171","msg":"trace[1517140740] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s6hkk; range_end:; response_count:1; response_revision:576; }","duration":"241.563762ms","start":"2026-04-16T16:23:19.599181Z","end":"2026-04-16T16:23:19.840745Z","steps":["trace[1517140740] 'agreement among raft nodes before linearized reading' (duration: 241.386351ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:19.840806Z","caller":"traceutil/trace.go:171","msg":"trace[120258875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"364.529555ms","start":"2026-04-16T16:23:19.476253Z","end":"2026-04-16T16:23:19.840782Z","steps":["trace[120258875] 'agreement among raft nodes before linearized reading' (duration: 364.179373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:19.840858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:19.476242Z","time spent":"364.606266ms","remote":"127.0.0.1:34784","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-04-16T16:23:20.35078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303123132064708,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:23:21.006496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.728503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:23:21.006758Z","caller":"traceutil/trace.go:171","msg":"trace[1167121977] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:596; }","duration":"1.156773269s","start":"2026-04-16T16:23:19.849969Z","end":"2026-04-16T16:23:21.006742Z","steps":["trace[1167121977] 'read index received' (duration: 832.727692ms)","trace[1167121977] 'applied index is now lower than readState.Index' (duration: 324.044657ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:23:21.006848Z","caller":"traceutil/trace.go:171","msg":"trace[1652333666] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.154315678s","start":"2026-04-16T16:23:19.852523Z","end":"2026-04-16T16:23:21.006839Z","steps":["trace[1652333666] 'process raft request' (duration: 1.154168277s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:21.006924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:19.852491Z","time spent":"1.154399759s","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:23:21.007017Z","caller":"traceutil/trace.go:171","msg":"trace[1046711243] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.161334646s","start":"2026-04-16T16:23:19.845655Z","end":"2026-04-16T16:23:21.006989Z","steps":["trace[1046711243] 'process raft request' (duration: 1.160957622s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:21.007054Z","caller":"traceutil/trace.go:171","msg":"trace[1931644990] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.163483654s","start":"2026-04-16T16:23:19.843518Z","end":"2026-04-16T16:23:21.007001Z","steps":["trace[1931644990] 'process raft request' (duration: 839.167885ms)","trace[1931644990] 'compare' (duration: 323.108548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:23:21.007164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:19.845632Z","time spent":"1.161456736s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:23:21.007182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.090857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:21.007213Z","caller":"traceutil/trace.go:171","msg":"trace[623453660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"819.160268ms","start":"2026-04-16T16:23:20.188041Z","end":"2026-04-16T16:23:21.007202Z","steps":["trace[623453660] 'agreement among raft nodes before linearized reading' (duration: 819.124978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:21.007248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:20.188016Z","time spent":"819.225868ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:23:21.007399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:19.843442Z","time spent":"1.163762265s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:23:21.007135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157162412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:21.007515Z","caller":"traceutil/trace.go:171","msg":"trace[1196432048] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:579; }","duration":"1.157560174s","start":"2026-04-16T16:23:19.84994Z","end":"2026-04-16T16:23:21.0075Z","steps":["trace[1196432048] 'agreement among raft nodes before linearized reading' (duration: 1.157164771s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:21.00757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:19.849932Z","time spent":"1.157625545s","remote":"127.0.0.1:34968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-04-16T16:23:22.342029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303123132064714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:23:22.485242Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.351259305s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:23:22.51127Z","caller":"traceutil/trace.go:171","msg":"trace[1231713029] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.099188658s","start":"2026-04-16T16:23:21.412039Z","end":"2026-04-16T16:23:22.511227Z","steps":["trace[1231713029] 'process raft request' (duration: 1.09826331s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:22.511329Z","caller":"traceutil/trace.go:171","msg":"trace[563773982] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"669.895133ms","start":"2026-04-16T16:23:21.841404Z","end":"2026-04-16T16:23:22.5113Z","steps":["trace[563773982] 'read index received' (duration: 644.072857ms)","trace[563773982] 'applied index is now lower than readState.Index' (duration: 25.819376ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:23:22.511435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:21.412018Z","time spent":"1.099334509s","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:23:22.51157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.148335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T16:23:22.51157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.012684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:22.511629Z","caller":"traceutil/trace.go:171","msg":"trace[125310470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"670.252186ms","start":"2026-04-16T16:23:21.841362Z","end":"2026-04-16T16:23:22.511614Z","steps":["trace[125310470] 'agreement among raft nodes before linearized reading' (duration: 670.043104ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:22.51165Z","caller":"traceutil/trace.go:171","msg":"trace[1163532637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"323.125745ms","start":"2026-04-16T16:23:22.188501Z","end":"2026-04-16T16:23:22.511627Z","steps":["trace[1163532637] 'agreement among raft nodes before linearized reading' (duration: 322.961753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:22.511673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.801601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T16:23:22.511684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:21.841343Z","time spent":"670.332506ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:23:22.511704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:22.188484Z","time spent":"323.210775ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:23:22.511721Z","caller":"traceutil/trace.go:171","msg":"trace[1503752898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"669.930592ms","start":"2026-04-16T16:23:21.841778Z","end":"2026-04-16T16:23:22.511709Z","steps":["trace[1503752898] 'agreement among raft nodes before linearized reading' (duration: 669.65963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:22.511753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:21.841756Z","time spent":"669.989373ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T16:23:24.230281Z","caller":"traceutil/trace.go:171","msg":"trace[300980143] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"473.84828ms","start":"2026-04-16T16:23:23.756407Z","end":"2026-04-16T16:23:24.230255Z","steps":["trace[300980143] 'read index received' (duration: 473.661499ms)","trace[300980143] 'applied index is now lower than readState.Index' (duration: 185.661µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:23:24.230362Z","caller":"traceutil/trace.go:171","msg":"trace[1464422136] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"474.002361ms","start":"2026-04-16T16:23:23.756326Z","end":"2026-04-16T16:23:24.230328Z","steps":["trace[1464422136] 'process raft request' (duration: 473.787989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.230454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.023111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.230513Z","caller":"traceutil/trace.go:171","msg":"trace[421860136] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"474.121132ms","start":"2026-04-16T16:23:23.756374Z","end":"2026-04-16T16:23:24.230495Z","steps":["trace[421860136] 'agreement among raft nodes before linearized reading' (duration: 474.023621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.230547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.756312Z","time spent":"474.109951ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:23:24.230566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.756359Z","time spent":"474.192682ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-04-16T16:23:24.230649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.341226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.230748Z","caller":"traceutil/trace.go:171","msg":"trace[621166115] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"473.456157ms","start":"2026-04-16T16:23:23.757278Z","end":"2026-04-16T16:23:24.230734Z","steps":["trace[621166115] 'agreement among raft nodes before linearized reading' (duration: 473.335526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.230792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.757268Z","time spent":"473.511227ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-16T16:23:24.231117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.342623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:23:24.231148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.568485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.231206Z","caller":"traceutil/trace.go:171","msg":"trace[286772729] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:605; }","duration":"397.644186ms","start":"2026-04-16T16:23:23.833546Z","end":"2026-04-16T16:23:24.231191Z","steps":["trace[286772729] 'agreement among raft nodes before linearized reading' (duration: 397.540325ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:24.231188Z","caller":"traceutil/trace.go:171","msg":"trace[754136340] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:605; }","duration":"397.435804ms","start":"2026-04-16T16:23:23.833735Z","end":"2026-04-16T16:23:24.231171Z","steps":["trace[754136340] 'agreement among raft nodes before linearized reading' (duration: 397.327873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.231262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.833532Z","time spent":"397.714966ms","remote":"127.0.0.1:47538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:23:24.231444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.815402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:23:24.231471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.833728Z","time spent":"397.722946ms","remote":"127.0.0.1:47538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"info","ts":"2026-04-16T16:23:24.231512Z","caller":"traceutil/trace.go:171","msg":"trace[510310466] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"472.892982ms","start":"2026-04-16T16:23:23.758599Z","end":"2026-04-16T16:23:24.231492Z","steps":["trace[510310466] 'agreement among raft nodes before linearized reading' (duration: 472.63704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.231565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.758593Z","time spent":"472.957743ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-04-16T16:23:24.232028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.493947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:23:24.232214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.578461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:23:24.232813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.076543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.232904Z","caller":"traceutil/trace.go:171","msg":"trace[2140643210] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"470.835386ms","start":"2026-04-16T16:23:23.762059Z","end":"2026-04-16T16:23:24.232895Z","steps":["trace[2140643210] 'agreement among raft nodes before linearized reading' (duration: 469.060863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.232998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.762032Z","time spent":"470.954857ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-04-16T16:23:24.232826Z","caller":"traceutil/trace.go:171","msg":"trace[754454124] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"342.216395ms","start":"2026-04-16T16:23:23.890598Z","end":"2026-04-16T16:23:24.232814Z","steps":["trace[754454124] 'agreement among raft nodes before linearized reading' (duration: 341.45403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.234271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.890594Z","time spent":"343.660357ms","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-16T16:23:24.232385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.538741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.234428Z","caller":"traceutil/trace.go:171","msg":"trace[313024341] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"473.602878ms","start":"2026-04-16T16:23:23.760815Z","end":"2026-04-16T16:23:24.234418Z","steps":["trace[313024341] 'agreement among raft nodes before linearized reading' (duration: 470.911536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.234461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.760809Z","time spent":"473.644338ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-04-16T16:23:24.232468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.152715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.234974Z","caller":"traceutil/trace.go:171","msg":"trace[2137357936] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"475.679765ms","start":"2026-04-16T16:23:23.759281Z","end":"2026-04-16T16:23:24.234961Z","steps":["trace[2137357936] 'agreement among raft nodes before linearized reading' (duration: 473.150675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.235015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.759273Z","time spent":"475.731615ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-16T16:23:24.232391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.317147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.235183Z","caller":"traceutil/trace.go:171","msg":"trace[1701947940] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:605; }","duration":"253.124379ms","start":"2026-04-16T16:23:23.98205Z","end":"2026-04-16T16:23:24.235174Z","steps":["trace[1701947940] 'agreement among raft nodes before linearized reading' (duration: 250.255687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.23248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.533287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.235351Z","caller":"traceutil/trace.go:171","msg":"trace[137413273] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"278.44043ms","start":"2026-04-16T16:23:23.956902Z","end":"2026-04-16T16:23:24.235343Z","steps":["trace[137413273] 'agreement among raft nodes before linearized reading' (duration: 275.494916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.232473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.343457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.237563Z","caller":"traceutil/trace.go:171","msg":"trace[1517703001] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:605; }","duration":"255.487288ms","start":"2026-04-16T16:23:23.982062Z","end":"2026-04-16T16:23:24.237549Z","steps":["trace[1517703001] 'agreement among raft nodes before linearized reading' (duration: 250.219116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.232547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.748194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.237843Z","caller":"traceutil/trace.go:171","msg":"trace[1834056067] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:605; }","duration":"404.069846ms","start":"2026-04-16T16:23:23.833763Z","end":"2026-04-16T16:23:24.237833Z","steps":["trace[1834056067] 'agreement among raft nodes before linearized reading' (duration: 398.756734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.237925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.833594Z","time spent":"404.313938ms","remote":"127.0.0.1:47538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:23:24.232594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.849395ms","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":"info","ts":"2026-04-16T16:23:24.238147Z","caller":"traceutil/trace.go:171","msg":"trace[1908766066] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"375.48064ms","start":"2026-04-16T16:23:23.862655Z","end":"2026-04-16T16:23:24.238135Z","steps":["trace[1908766066] 'agreement among raft nodes before linearized reading' (duration: 369.853835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.238226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.862636Z","time spent":"375.5757ms","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:23:24.232599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.827183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.238678Z","caller":"traceutil/trace.go:171","msg":"trace[1073148891] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:605; }","duration":"255.89301ms","start":"2026-04-16T16:23:23.982746Z","end":"2026-04-16T16:23:24.238639Z","steps":["trace[1073148891] 'agreement among raft nodes before linearized reading' (duration: 249.834963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.232629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.647399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.238825Z","caller":"traceutil/trace.go:171","msg":"trace[1860225061] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"348.842248ms","start":"2026-04-16T16:23:23.889957Z","end":"2026-04-16T16:23:24.2388Z","steps":["trace[1860225061] 'agreement among raft nodes before linearized reading' (duration: 342.654729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.238899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.889945Z","time spent":"348.926498ms","remote":"127.0.0.1:47554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:23:24.232641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.000849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:23:24.232636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.859605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:23:24.232667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.416263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.239252Z","caller":"traceutil/trace.go:171","msg":"trace[2116470585] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"376.417436ms","start":"2026-04-16T16:23:23.862759Z","end":"2026-04-16T16:23:24.239176Z","steps":["trace[2116470585] 'agreement among raft nodes before linearized reading' (duration: 369.860435ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:24.239264Z","caller":"traceutil/trace.go:171","msg":"trace[20379461] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:605; }","duration":"428.025246ms","start":"2026-04-16T16:23:23.811226Z","end":"2026-04-16T16:23:24.239251Z","steps":["trace[20379461] 'agreement among raft nodes before linearized reading' (duration: 421.428934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.2393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.811212Z","time spent":"428.075076ms","remote":"127.0.0.1:47522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-16T16:23:24.232668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.192096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:23:24.239333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.862754Z","time spent":"376.526717ms","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-16T16:23:24.239373Z","caller":"traceutil/trace.go:171","msg":"trace[576007230] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"449.91807ms","start":"2026-04-16T16:23:23.789441Z","end":"2026-04-16T16:23:24.239359Z","steps":["trace[576007230] 'agreement among raft nodes before linearized reading' (duration: 443.211056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.239418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.789427Z","time spent":"449.97926ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-16T16:23:24.232688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.940938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.239525Z","caller":"traceutil/trace.go:171","msg":"trace[703124511] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:605; }","duration":"428.789603ms","start":"2026-04-16T16:23:23.810725Z","end":"2026-04-16T16:23:24.239515Z","steps":["trace[703124511] 'agreement among raft nodes before linearized reading' (duration: 421.950628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.239566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.810717Z","time spent":"428.836833ms","remote":"127.0.0.1:47522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:23:24.232705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.738652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.239648Z","caller":"traceutil/trace.go:171","msg":"trace[225540414] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"474.691407ms","start":"2026-04-16T16:23:23.764944Z","end":"2026-04-16T16:23:24.239636Z","steps":["trace[225540414] 'agreement among raft nodes before linearized reading' (duration: 467.744731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.239691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.764936Z","time spent":"474.742837ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-04-16T16:23:24.232766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.394594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:24.239876Z","caller":"traceutil/trace.go:171","msg":"trace[1502928977] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"481.521481ms","start":"2026-04-16T16:23:23.758344Z","end":"2026-04-16T16:23:24.239865Z","steps":["trace[1502928977] 'agreement among raft nodes before linearized reading' (duration: 474.395594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.239917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.758335Z","time spent":"481.570791ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-04-16T16:23:24.232259Z","caller":"traceutil/trace.go:171","msg":"trace[1284699600] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:605; }","duration":"473.745129ms","start":"2026-04-16T16:23:23.758486Z","end":"2026-04-16T16:23:24.232231Z","steps":["trace[1284699600] 'agreement among raft nodes before linearized reading' (duration: 473.462467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.239989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.75848Z","time spent":"481.49496ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-04-16T16:23:24.232854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.849951ms","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":"info","ts":"2026-04-16T16:23:24.240092Z","caller":"traceutil/trace.go:171","msg":"trace[393571052] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"350.095118ms","start":"2026-04-16T16:23:23.889985Z","end":"2026-04-16T16:23:24.24008Z","steps":["trace[393571052] 'agreement among raft nodes before linearized reading' (duration: 342.317457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.240139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.889983Z","time spent":"350.142069ms","remote":"127.0.0.1:47554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:23:24.240842Z","caller":"traceutil/trace.go:171","msg":"trace[2059747215] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:605; }","duration":"428.416759ms","start":"2026-04-16T16:23:23.8106Z","end":"2026-04-16T16:23:24.239017Z","steps":["trace[2059747215] 'agreement among raft nodes before linearized reading' (duration: 422.020669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:24.240986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:23.810582Z","time spent":"430.379805ms","remote":"127.0.0.1:47522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-16T16:23:34.400243Z","caller":"traceutil/trace.go:171","msg":"trace[59309969] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"105.51252ms","start":"2026-04-16T16:23:34.294695Z","end":"2026-04-16T16:23:34.400207Z","steps":["trace[59309969] 'process raft request' (duration: 105.211976ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:38.473652Z","caller":"traceutil/trace.go:171","msg":"trace[1566094178] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"285.838001ms","start":"2026-04-16T16:23:38.187793Z","end":"2026-04-16T16:23:38.473631Z","steps":["trace[1566094178] 'read index received' (duration: 285.645443ms)","trace[1566094178] 'applied index is now lower than readState.Index' (duration: 191.668µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:23:38.473772Z","caller":"traceutil/trace.go:171","msg":"trace[1985948508] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"754.7204ms","start":"2026-04-16T16:23:37.719042Z","end":"2026-04-16T16:23:38.473763Z","steps":["trace[1985948508] 'process raft request' (duration: 754.46341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:38.473894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:37.719009Z","time spent":"754.798454ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:23:38.474332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.511159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:23:38.474437Z","caller":"traceutil/trace.go:171","msg":"trace[27567911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"286.672465ms","start":"2026-04-16T16:23:38.187743Z","end":"2026-04-16T16:23:38.474415Z","steps":["trace[27567911] 'agreement among raft nodes before linearized reading' (duration: 286.481807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:09.687805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303123132065816,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:24:10.18873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303123132065816,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:24:10.65156Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.902048926s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:24:10.652136Z","caller":"traceutil/trace.go:171","msg":"trace[457647410] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"1.902706265s","start":"2026-04-16T16:24:08.749397Z","end":"2026-04-16T16:24:10.652103Z","steps":["trace[457647410] 'process raft request' (duration: 1.902388386s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:10.652462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:08.74938Z","time spent":"1.902920451s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:24:10.68915Z","caller":"traceutil/trace.go:171","msg":"trace[1719733945] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1183; }","duration":"1.502126506s","start":"2026-04-16T16:24:09.187Z","end":"2026-04-16T16:24:10.689127Z","steps":["trace[1719733945] 'read index received' (duration: 1.464786062s)","trace[1719733945] 'applied index is now lower than readState.Index' (duration: 37.339404ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:24:10.689312Z","caller":"traceutil/trace.go:171","msg":"trace[13941992] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"1.939506684s","start":"2026-04-16T16:24:08.749786Z","end":"2026-04-16T16:24:10.689293Z","steps":["trace[13941992] 'process raft request' (duration: 1.939088382s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:10.689392Z","caller":"traceutil/trace.go:171","msg":"trace[17162321] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"1.723591125s","start":"2026-04-16T16:24:08.96578Z","end":"2026-04-16T16:24:10.689371Z","steps":["trace[17162321] 'process raft request' (duration: 1.723286837s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:10.689425Z","caller":"traceutil/trace.go:171","msg":"trace[247609933] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"1.936742734s","start":"2026-04-16T16:24:08.752661Z","end":"2026-04-16T16:24:10.689403Z","steps":["trace[247609933] 'process raft request' (duration: 1.936341252s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:10.689449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:08.749775Z","time spent":"1.939595746s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:24:10.689497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:08.96575Z","time spent":"1.723685649s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:24:10.689528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:08.752621Z","time spent":"1.936860567s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:24:10.689593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39869407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T16:24:10.689654Z","caller":"traceutil/trace.go:171","msg":"trace[2010535458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1154; }","duration":"1.398797633s","start":"2026-04-16T16:24:09.290841Z","end":"2026-04-16T16:24:10.689638Z","steps":["trace[2010535458] 'agreement among raft nodes before linearized reading' (duration: 1.398639258s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:10.689684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068861961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:24:10.689702Z","caller":"traceutil/trace.go:171","msg":"trace[1160908086] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1154; }","duration":"1.068920693s","start":"2026-04-16T16:24:09.620776Z","end":"2026-04-16T16:24:10.689696Z","steps":["trace[1160908086] 'agreement among raft nodes before linearized reading' (duration: 1.068856901s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:10.689698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502708493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:24:10.68973Z","caller":"traceutil/trace.go:171","msg":"trace[2021116134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1154; }","duration":"1.502767625s","start":"2026-04-16T16:24:09.186953Z","end":"2026-04-16T16:24:10.68972Z","steps":["trace[2021116134] 'agreement among raft nodes before linearized reading' (duration: 1.502727734s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:10.689739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:09.620754Z","time spent":"1.068979045s","remote":"127.0.0.1:34932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":7,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:24:10.689761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:09.290819Z","time spent":"1.398872215s","remote":"127.0.0.1:34784","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-04-16T16:24:10.689765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:09.186931Z","time spent":"1.502829287s","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:24:10.689612Z","caller":"traceutil/trace.go:171","msg":"trace[1251105456] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"1.774265373s","start":"2026-04-16T16:24:08.915334Z","end":"2026-04-16T16:24:10.6896Z","steps":["trace[1251105456] 'process raft request' (duration: 1.773700646s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:10.689964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39253213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-16T16:24:10.689658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.991428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:24:10.690005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:08.915305Z","time spent":"1.774668194s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:24:10.690008Z","caller":"traceutil/trace.go:171","msg":"trace[815314447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1154; }","duration":"1.392635523s","start":"2026-04-16T16:24:09.29736Z","end":"2026-04-16T16:24:10.689995Z","steps":["trace[815314447] 'agreement among raft nodes before linearized reading' (duration: 1.39252622s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:10.690053Z","caller":"traceutil/trace.go:171","msg":"trace[972111519] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1154; }","duration":"968.433021ms","start":"2026-04-16T16:24:09.721605Z","end":"2026-04-16T16:24:10.690038Z","steps":["trace[972111519] 'agreement among raft nodes before linearized reading' (duration: 968.018469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:10.690065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:09.297326Z","time spent":"1.392731586s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:24:10.690119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:09.721585Z","time spent":"968.514013ms","remote":"127.0.0.1:34828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:24:29.018299Z","caller":"traceutil/trace.go:171","msg":"trace[1707650237] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"141.914488ms","start":"2026-04-16T16:24:28.876349Z","end":"2026-04-16T16:24:29.018264Z","steps":["trace[1707650237] 'process raft request' (duration: 141.54881ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:29.126879Z","caller":"traceutil/trace.go:171","msg":"trace[2123247555] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"101.271279ms","start":"2026-04-16T16:24:29.02557Z","end":"2026-04-16T16:24:29.126841Z","steps":["trace[2123247555] 'process raft request' (duration: 74.4863ms)","trace[2123247555] 'compare' (duration: 26.596055ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:24:30.490205Z","caller":"traceutil/trace.go:171","msg":"trace[1854819825] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1311; }","duration":"141.414902ms","start":"2026-04-16T16:24:30.348744Z","end":"2026-04-16T16:24:30.490159Z","steps":["trace[1854819825] 'process raft request' (duration: 115.853239ms)","trace[1854819825] 'compare' (duration: 25.392079ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:24:32.924709Z","caller":"traceutil/trace.go:171","msg":"trace[1980020026] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"108.58366ms","start":"2026-04-16T16:24:32.8161Z","end":"2026-04-16T16:24:32.924683Z","steps":["trace[1980020026] 'process raft request' (duration: 108.423876ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:43.248126Z","caller":"traceutil/trace.go:171","msg":"trace[111635908] linearizableReadLoop","detail":"{readStateIndex:1588; appliedIndex:1587; }","duration":"153.210666ms","start":"2026-04-16T16:24:43.094886Z","end":"2026-04-16T16:24:43.248097Z","steps":["trace[111635908] 'read index received' (duration: 145.840237ms)","trace[111635908] 'applied index is now lower than readState.Index' (duration: 7.368739ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:24:43.248272Z","caller":"traceutil/trace.go:171","msg":"trace[1244019236] transaction","detail":"{read_only:false; response_revision:1527; number_of_response:1; }","duration":"204.611955ms","start":"2026-04-16T16:24:43.04361Z","end":"2026-04-16T16:24:43.248222Z","steps":["trace[1244019236] 'process raft request' (duration: 197.159554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:43.248395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.495903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T16:24:43.248476Z","caller":"traceutil/trace.go:171","msg":"trace[792644166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1527; }","duration":"153.624105ms","start":"2026-04-16T16:24:43.094833Z","end":"2026-04-16T16:24:43.248457Z","steps":["trace[792644166] 'agreement among raft nodes before linearized reading' (duration: 153.39878ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:59.224122Z","caller":"traceutil/trace.go:171","msg":"trace[615494209] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"159.796992ms","start":"2026-04-16T16:24:59.064295Z","end":"2026-04-16T16:24:59.224092Z","steps":["trace[615494209] 'read index received' (duration: 159.654339ms)","trace[615494209] 'applied index is now lower than readState.Index' (duration: 141.723µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:24:59.224185Z","caller":"traceutil/trace.go:171","msg":"trace[1075167159] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"187.427703ms","start":"2026-04-16T16:24:59.036711Z","end":"2026-04-16T16:24:59.224139Z","steps":["trace[1075167159] 'process raft request' (duration: 187.206389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:59.224483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.162729ms","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-04-16T16:24:59.224585Z","caller":"traceutil/trace.go:171","msg":"trace[55735630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1811; }","duration":"160.311071ms","start":"2026-04-16T16:24:59.064254Z","end":"2026-04-16T16:24:59.224566Z","steps":["trace[55735630] 'agreement among raft nodes before linearized reading' (duration: 159.980445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:59.22461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.751104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-16T16:24:59.224672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.773216ms","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-04-16T16:24:59.224717Z","caller":"traceutil/trace.go:171","msg":"trace[1018896857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1811; }","duration":"114.854038ms","start":"2026-04-16T16:24:59.109851Z","end":"2026-04-16T16:24:59.224705Z","steps":["trace[1018896857] 'agreement among raft nodes before linearized reading' (duration: 114.732105ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:59.224796Z","caller":"traceutil/trace.go:171","msg":"trace[315387300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1811; }","duration":"154.854877ms","start":"2026-04-16T16:24:59.069797Z","end":"2026-04-16T16:24:59.224652Z","steps":["trace[315387300] 'agreement among raft nodes before linearized reading' (duration: 154.50499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:59.225399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.372947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-50cc4570-2787-44e5-9d8b-258f2c2e64e7\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-16T16:24:59.225542Z","caller":"traceutil/trace.go:171","msg":"trace[2140584632] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-50cc4570-2787-44e5-9d8b-258f2c2e64e7; range_end:; response_count:1; response_revision:1811; }","duration":"102.54361ms","start":"2026-04-16T16:24:59.122974Z","end":"2026-04-16T16:24:59.225518Z","steps":["trace[2140584632] 'agreement among raft nodes before linearized reading' (duration: 101.543571ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:59.516132Z","caller":"traceutil/trace.go:171","msg":"trace[688011693] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1885; }","duration":"188.911691ms","start":"2026-04-16T16:24:59.327207Z","end":"2026-04-16T16:24:59.516118Z","steps":["trace[688011693] 'read index received' (duration: 188.402611ms)","trace[688011693] 'applied index is now lower than readState.Index' (duration: 508.09µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:24:59.516296Z","caller":"traceutil/trace.go:171","msg":"trace[1971885649] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"283.264685ms","start":"2026-04-16T16:24:59.232966Z","end":"2026-04-16T16:24:59.516231Z","steps":["trace[1971885649] 'process raft request' (duration: 283.076231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:59.516547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.317318ms","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-04-16T16:24:59.516627Z","caller":"traceutil/trace.go:171","msg":"trace[742327569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1815; }","duration":"189.460681ms","start":"2026-04-16T16:24:59.327152Z","end":"2026-04-16T16:24:59.516612Z","steps":["trace[742327569] 'agreement among raft nodes before linearized reading' (duration: 189.092754ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:59.516556Z","caller":"traceutil/trace.go:171","msg":"trace[2070934922] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"285.390485ms","start":"2026-04-16T16:24:59.231134Z","end":"2026-04-16T16:24:59.516524Z","steps":["trace[2070934922] 'process raft request' (duration: 284.690282ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:59.51597Z","caller":"traceutil/trace.go:171","msg":"trace[732584802] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"285.58397ms","start":"2026-04-16T16:24:59.230328Z","end":"2026-04-16T16:24:59.515912Z","steps":["trace[732584802] 'process raft request' (duration: 285.326505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:24:59.521693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.084144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T16:24:59.521752Z","caller":"traceutil/trace.go:171","msg":"trace[1200687109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1816; }","duration":"188.179537ms","start":"2026-04-16T16:24:59.333558Z","end":"2026-04-16T16:24:59.521738Z","steps":["trace[1200687109] 'agreement among raft nodes before linearized reading' (duration: 187.982013ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:59.521963Z","caller":"traceutil/trace.go:171","msg":"trace[1949539170] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"182.381115ms","start":"2026-04-16T16:24:59.339532Z","end":"2026-04-16T16:24:59.521913Z","steps":["trace[1949539170] 'process raft request' (duration: 181.907306ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:00.399416Z","caller":"traceutil/trace.go:171","msg":"trace[188329180] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"873.09025ms","start":"2026-04-16T16:24:59.526296Z","end":"2026-04-16T16:25:00.399386Z","steps":["trace[188329180] 'process raft request' (duration: 873.028189ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:00.399431Z","caller":"traceutil/trace.go:171","msg":"trace[1219638654] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"873.272513ms","start":"2026-04-16T16:24:59.526116Z","end":"2026-04-16T16:25:00.399388Z","steps":["trace[1219638654] 'process raft request' (duration: 873.013948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:00.399621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:59.526276Z","time spent":"873.246623ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:00.399785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:59.5261Z","time spent":"873.539369ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:00.768794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.803158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:25:00.770082Z","caller":"traceutil/trace.go:171","msg":"trace[1013890400] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1890; }","duration":"851.667461ms","start":"2026-04-16T16:24:59.918399Z","end":"2026-04-16T16:25:00.770067Z","steps":["trace[1013890400] 'read index received' (duration: 480.681257ms)","trace[1013890400] 'applied index is now lower than readState.Index' (duration: 370.985354ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:25:00.770157Z","caller":"traceutil/trace.go:171","msg":"trace[1672261418] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"439.149335ms","start":"2026-04-16T16:25:00.330998Z","end":"2026-04-16T16:25:00.770147Z","steps":["trace[1672261418] 'process raft request' (duration: 438.977181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:00.770312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:00.330959Z","time spent":"439.240907ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:00.770327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.929777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-50cc4570-2787-44e5-9d8b-258f2c2e64e7\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-16T16:25:00.770462Z","caller":"traceutil/trace.go:171","msg":"trace[1749388684] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-50cc4570-2787-44e5-9d8b-258f2c2e64e7; range_end:; response_count:1; response_revision:1821; }","duration":"852.094959ms","start":"2026-04-16T16:24:59.918357Z","end":"2026-04-16T16:25:00.770451Z","steps":["trace[1749388684] 'agreement among raft nodes before linearized reading' (duration: 851.859095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:00.770454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.340119ms","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-04-16T16:25:00.770573Z","caller":"traceutil/trace.go:171","msg":"trace[691745488] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1821; }","duration":"846.516532ms","start":"2026-04-16T16:24:59.924025Z","end":"2026-04-16T16:25:00.770542Z","steps":["trace[691745488] 'agreement among raft nodes before linearized reading' (duration: 846.231187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:00.770646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:59.923999Z","time spent":"846.625225ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-16T16:25:00.770064Z","caller":"traceutil/trace.go:171","msg":"trace[260667688] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"952.971599ms","start":"2026-04-16T16:24:59.81705Z","end":"2026-04-16T16:25:00.770022Z","steps":["trace[260667688] 'process raft request' (duration: 722.833015ms)","trace[260667688] 'compare' (duration: 228.439572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:25:00.770991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:59.817029Z","time spent":"953.845696ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:00.770522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:24:59.918343Z","time spent":"852.169481ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-50cc4570-2787-44e5-9d8b-258f2c2e64e7\" "} {"level":"warn","ts":"2026-04-16T16:25:00.771814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.549984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:25:00.771903Z","caller":"traceutil/trace.go:171","msg":"trace[1209100699] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1821; }","duration":"690.686546ms","start":"2026-04-16T16:25:00.081197Z","end":"2026-04-16T16:25:00.771884Z","steps":["trace[1209100699] 'agreement among raft nodes before linearized reading' (duration: 689.430833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:00.772144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:00.081184Z","time spent":"690.947232ms","remote":"127.0.0.1:47654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":13,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:25:00.772154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.539299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T16:25:00.772208Z","caller":"traceutil/trace.go:171","msg":"trace[937354111] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1821; }","duration":"729.59384ms","start":"2026-04-16T16:25:00.042599Z","end":"2026-04-16T16:25:00.772192Z","steps":["trace[937354111] 'agreement among raft nodes before linearized reading' (duration: 729.238803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:00.77227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:00.042549Z","time spent":"729.704262ms","remote":"127.0.0.1:50580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-16T16:25:02.031462Z","caller":"traceutil/trace.go:171","msg":"trace[598150250] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"251.337564ms","start":"2026-04-16T16:25:01.780089Z","end":"2026-04-16T16:25:02.031427Z","steps":["trace[598150250] 'read index received' (duration: 250.952847ms)","trace[598150250] 'applied index is now lower than readState.Index' (duration: 383.537µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:25:02.031736Z","caller":"traceutil/trace.go:171","msg":"trace[1125492091] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"293.262098ms","start":"2026-04-16T16:25:01.738359Z","end":"2026-04-16T16:25:02.031621Z","steps":["trace[1125492091] 'process raft request' (duration: 292.858101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:02.03179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.67644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-50cc4570-2787-44e5-9d8b-258f2c2e64e7\" ","response":"range_response_count:1 size:3932"} {"level":"info","ts":"2026-04-16T16:25:02.031961Z","caller":"traceutil/trace.go:171","msg":"trace[505211015] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-50cc4570-2787-44e5-9d8b-258f2c2e64e7; range_end:; response_count:1; response_revision:1839; }","duration":"251.910574ms","start":"2026-04-16T16:25:01.78003Z","end":"2026-04-16T16:25:02.03194Z","steps":["trace[505211015] 'agreement among raft nodes before linearized reading' (duration: 251.576888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:02.031954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.793853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T16:25:02.032032Z","caller":"traceutil/trace.go:171","msg":"trace[1674203276] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1839; }","duration":"250.937055ms","start":"2026-04-16T16:25:01.78106Z","end":"2026-04-16T16:25:02.031997Z","steps":["trace[1674203276] 'agreement among raft nodes before linearized reading' (duration: 250.65479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:02.513185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.26865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T16:25:02.513287Z","caller":"traceutil/trace.go:171","msg":"trace[1732002524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1839; }","duration":"108.430934ms","start":"2026-04-16T16:25:02.404833Z","end":"2026-04-16T16:25:02.513264Z","steps":["trace[1732002524] 'range keys from in-memory index tree' (duration: 108.038736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:02.513362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.054457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-16T16:25:02.513455Z","caller":"traceutil/trace.go:171","msg":"trace[553334811] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1839; }","duration":"108.21395ms","start":"2026-04-16T16:25:02.405219Z","end":"2026-04-16T16:25:02.513433Z","steps":["trace[553334811] 'range keys from in-memory index tree' (duration: 107.826302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:02.513556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.35535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:25:02.513589Z","caller":"traceutil/trace.go:171","msg":"trace[1165850629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1839; }","duration":"325.437381ms","start":"2026-04-16T16:25:02.188142Z","end":"2026-04-16T16:25:02.513579Z","steps":["trace[1165850629] 'range keys from in-memory index tree' (duration: 325.223088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:02.51362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:02.188092Z","time spent":"325.519233ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:25:02.513992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.425901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-04-16T16:25:02.514019Z","caller":"traceutil/trace.go:171","msg":"trace[1511755264] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1839; }","duration":"257.481991ms","start":"2026-04-16T16:25:02.256528Z","end":"2026-04-16T16:25:02.51401Z","steps":["trace[1511755264] 'range keys from in-memory index tree' (duration: 257.310168ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:09.137972Z","caller":"traceutil/trace.go:171","msg":"trace[1032736074] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"582.165714ms","start":"2026-04-16T16:25:08.555775Z","end":"2026-04-16T16:25:09.13794Z","steps":["trace[1032736074] 'process raft request' (duration: 581.980441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:09.138169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:08.555748Z","time spent":"582.315216ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:09.196418Z","caller":"traceutil/trace.go:171","msg":"trace[112476725] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"640.545866ms","start":"2026-04-16T16:25:08.555827Z","end":"2026-04-16T16:25:09.196373Z","steps":["trace[112476725] 'process raft request' (duration: 615.298161ms)","trace[112476725] 'compare' (duration: 24.95707ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:25:09.19645Z","caller":"traceutil/trace.go:171","msg":"trace[1586323374] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1975; }","duration":"111.882887ms","start":"2026-04-16T16:25:09.084539Z","end":"2026-04-16T16:25:09.196422Z","steps":["trace[1586323374] 'read index received' (duration: 53.25108ms)","trace[1586323374] 'applied index is now lower than readState.Index' (duration: 58.630567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:25:09.196628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:08.555798Z","time spent":"640.7186ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:09.196921Z","caller":"traceutil/trace.go:171","msg":"trace[1781456388] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"399.585296ms","start":"2026-04-16T16:25:08.797292Z","end":"2026-04-16T16:25:09.196877Z","steps":["trace[1781456388] 'process raft request' (duration: 398.976435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:09.197227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.681702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T16:25:09.197274Z","caller":"traceutil/trace.go:171","msg":"trace[1148401208] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1903; }","duration":"112.822244ms","start":"2026-04-16T16:25:09.084438Z","end":"2026-04-16T16:25:09.19726Z","steps":["trace[1148401208] 'agreement among raft nodes before linearized reading' (duration: 112.662241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:09.197787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:08.797228Z","time spent":"399.772469ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:14.980266Z","caller":"traceutil/trace.go:171","msg":"trace[1011234592] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"615.73764ms","start":"2026-04-16T16:25:14.364503Z","end":"2026-04-16T16:25:14.98024Z","steps":["trace[1011234592] 'process raft request' (duration: 607.955884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:14.980425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:14.364492Z","time spent":"615.851411ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:14.980496Z","caller":"traceutil/trace.go:171","msg":"trace[356518241] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"615.427974ms","start":"2026-04-16T16:25:14.365039Z","end":"2026-04-16T16:25:14.980467Z","steps":["trace[356518241] 'process raft request' (duration: 615.127718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:14.980661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:14.36503Z","time spent":"615.535245ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5881,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:15.667601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303123132067428,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:25:16.168555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303123132067428,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:25:16.384068Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.394559532s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-16T16:25:16.39773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.575558ms","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-04-16T16:25:16.397866Z","caller":"traceutil/trace.go:171","msg":"trace[368783882] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1941; }","duration":"277.721992ms","start":"2026-04-16T16:25:16.12012Z","end":"2026-04-16T16:25:16.397842Z","steps":["trace[368783882] 'range keys from in-memory index tree' (duration: 277.558138ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:16.398278Z","caller":"traceutil/trace.go:171","msg":"trace[112866125] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"1.408977851s","start":"2026-04-16T16:25:14.989266Z","end":"2026-04-16T16:25:16.398244Z","steps":["trace[112866125] 'process raft request' (duration: 1.395116913s)","trace[112866125] 'compare' (duration: 13.464882ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:25:16.398423Z","caller":"traceutil/trace.go:171","msg":"trace[737597850] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"1.177486226s","start":"2026-04-16T16:25:15.220921Z","end":"2026-04-16T16:25:16.398407Z","steps":["trace[737597850] 'process raft request' (duration: 1.17717973s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.398478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:14.989232Z","time spent":"1.409145356s","remote":"127.0.0.1:34932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6113,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:16.398546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.220896Z","time spent":"1.177575567s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:16.399008Z","caller":"traceutil/trace.go:171","msg":"trace[1800707511] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"1.110676774s","start":"2026-04-16T16:25:15.288318Z","end":"2026-04-16T16:25:16.398995Z","steps":["trace[1800707511] 'process raft request' (duration: 1.110013023s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.399084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.28829Z","time spent":"1.110755675s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:16.39926Z","caller":"traceutil/trace.go:171","msg":"trace[1285822389] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"809.336204ms","start":"2026-04-16T16:25:15.589912Z","end":"2026-04-16T16:25:16.399248Z","steps":["trace[1285822389] 'process raft request' (duration: 809.034109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.399334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.58988Z","time spent":"809.418566ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:16.399484Z","caller":"traceutil/trace.go:171","msg":"trace[270322611] linearizableReadLoop","detail":"{readStateIndex:2022; appliedIndex:2017; }","duration":"1.231962332s","start":"2026-04-16T16:25:15.167511Z","end":"2026-04-16T16:25:16.399473Z","steps":["trace[270322611] 'read index received' (duration: 1.216890082s)","trace[270322611] 'applied index is now lower than readState.Index' (duration: 15.07135ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:25:16.399615Z","caller":"traceutil/trace.go:171","msg":"trace[835298884] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"576.596547ms","start":"2026-04-16T16:25:15.823009Z","end":"2026-04-16T16:25:16.399605Z","steps":["trace[835298884] 'process raft request' (duration: 576.189971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.399668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.822964Z","time spent":"576.676079ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:16.399724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211165171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:25:16.399811Z","caller":"traceutil/trace.go:171","msg":"trace[1339703235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1946; }","duration":"1.211278073s","start":"2026-04-16T16:25:15.188514Z","end":"2026-04-16T16:25:16.399792Z","steps":["trace[1339703235] 'agreement among raft nodes before linearized reading' (duration: 1.21114039s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.399856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.188504Z","time spent":"1.211344464s","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:25:16.399875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.391401ms","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-04-16T16:25:16.399937Z","caller":"traceutil/trace.go:171","msg":"trace[1351336728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1946; }","duration":"967.493593ms","start":"2026-04-16T16:25:15.432406Z","end":"2026-04-16T16:25:16.3999Z","steps":["trace[1351336728] 'agreement among raft nodes before linearized reading' (duration: 967.373501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.39999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.432373Z","time spent":"967.597225ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:25:16.400011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.453437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-16T16:25:16.400083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194442939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:25:16.400116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.786305ms","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-04-16T16:25:16.400115Z","caller":"traceutil/trace.go:171","msg":"trace[1708240194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1946; }","duration":"801.578789ms","start":"2026-04-16T16:25:15.598516Z","end":"2026-04-16T16:25:16.400095Z","steps":["trace[1708240194] 'agreement among raft nodes before linearized reading' (duration: 801.240233ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:16.400156Z","caller":"traceutil/trace.go:171","msg":"trace[549714794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1946; }","duration":"804.883827ms","start":"2026-04-16T16:25:15.595263Z","end":"2026-04-16T16:25:16.400147Z","steps":["trace[549714794] 'agreement among raft nodes before linearized reading' (duration: 804.795255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.400186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.598501Z","time spent":"801.673631ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T16:25:16.400201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175884648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-16T16:25:16.400239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.777962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:25:16.400288Z","caller":"traceutil/trace.go:171","msg":"trace[1873611525] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1946; }","duration":"843.882754ms","start":"2026-04-16T16:25:15.556394Z","end":"2026-04-16T16:25:16.400277Z","steps":["trace[1873611525] 'agreement among raft nodes before linearized reading' (duration: 843.816733ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:16.400304Z","caller":"traceutil/trace.go:171","msg":"trace[941467796] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1946; }","duration":"1.17600979s","start":"2026-04-16T16:25:15.224262Z","end":"2026-04-16T16:25:16.400272Z","steps":["trace[941467796] 'agreement among raft nodes before linearized reading' (duration: 1.175730265s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.400371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.224251Z","time spent":"1.176108651s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-16T16:25:16.400191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.595249Z","time spent":"804.935417ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:25:16.400496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23303391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-16T16:25:16.400506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163166916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T16:25:16.400537Z","caller":"traceutil/trace.go:171","msg":"trace[742875893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1946; }","duration":"1.233131452s","start":"2026-04-16T16:25:15.167396Z","end":"2026-04-16T16:25:16.400528Z","steps":["trace[742875893] 'agreement among raft nodes before linearized reading' (duration: 1.233041051s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:16.400541Z","caller":"traceutil/trace.go:171","msg":"trace[1501516070] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1946; }","duration":"1.163239397s","start":"2026-04-16T16:25:15.237292Z","end":"2026-04-16T16:25:16.400531Z","steps":["trace[1501516070] 'agreement among raft nodes before linearized reading' (duration: 1.163147866s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.400537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.817806ms","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-04-16T16:25:16.400119Z","caller":"traceutil/trace.go:171","msg":"trace[609076497] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1946; }","duration":"1.194528241s","start":"2026-04-16T16:25:15.205578Z","end":"2026-04-16T16:25:16.400106Z","steps":["trace[609076497] 'agreement among raft nodes before linearized reading' (duration: 1.19447241s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.400574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.16736Z","time spent":"1.233207464s","remote":"127.0.0.1:34784","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-04-16T16:25:16.400576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.237267Z","time spent":"1.163301868s","remote":"127.0.0.1:50580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-16T16:25:16.400587Z","caller":"traceutil/trace.go:171","msg":"trace[1691309745] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1946; }","duration":"616.920068ms","start":"2026-04-16T16:25:15.783654Z","end":"2026-04-16T16:25:16.400574Z","steps":["trace[1691309745] 'agreement among raft nodes before linearized reading' (duration: 616.769615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.400624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.783627Z","time spent":"616.98871ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-16T16:25:16.400646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.205511Z","time spent":"1.19508618s","remote":"127.0.0.1:47370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:25:16.400326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.556355Z","time spent":"843.962876ms","remote":"127.0.0.1:47490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:25:16.40072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.685037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-04-16T16:25:16.400412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.316884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-16T16:25:16.400751Z","caller":"traceutil/trace.go:171","msg":"trace[1485984111] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1946; }","duration":"352.743709ms","start":"2026-04-16T16:25:16.048Z","end":"2026-04-16T16:25:16.400743Z","steps":["trace[1485984111] 'agreement among raft nodes before linearized reading' (duration: 352.653477ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:16.400764Z","caller":"traceutil/trace.go:171","msg":"trace[620097371] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:1946; }","duration":"411.70172ms","start":"2026-04-16T16:25:15.989049Z","end":"2026-04-16T16:25:16.400751Z","steps":["trace[620097371] 'agreement among raft nodes before linearized reading' (duration: 411.251712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.400405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.659426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-16T16:25:16.400789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:16.047986Z","time spent":"352.794129ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-16T16:25:16.400798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.988996Z","time spent":"411.792571ms","remote":"127.0.0.1:34538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"info","ts":"2026-04-16T16:25:16.400823Z","caller":"traceutil/trace.go:171","msg":"trace[1769288624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1946; }","duration":"704.120074ms","start":"2026-04-16T16:25:15.696693Z","end":"2026-04-16T16:25:16.400813Z","steps":["trace[1769288624] 'agreement among raft nodes before linearized reading' (duration: 703.655646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:16.400859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:15.696636Z","time spent":"704.213706ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:25:36.341004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.551774ms","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-04-16T16:25:36.341214Z","caller":"traceutil/trace.go:171","msg":"trace[646521921] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2061; }","duration":"221.780388ms","start":"2026-04-16T16:25:36.119417Z","end":"2026-04-16T16:25:36.341197Z","steps":["trace[646521921] 'range keys from in-memory index tree' (duration: 221.509313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:36.341524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.590565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:25:36.341596Z","caller":"traceutil/trace.go:171","msg":"trace[772235499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2061; }","duration":"153.666986ms","start":"2026-04-16T16:25:36.187919Z","end":"2026-04-16T16:25:36.341586Z","steps":["trace[772235499] 'range keys from in-memory index tree' (duration: 153.510244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:37.374733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.191086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:25:37.374839Z","caller":"traceutil/trace.go:171","msg":"trace[364759567] linearizableReadLoop","detail":"{readStateIndex:2146; appliedIndex:2145; }","duration":"844.946715ms","start":"2026-04-16T16:25:36.529875Z","end":"2026-04-16T16:25:37.374822Z","steps":["trace[364759567] 'read index received' (duration: 21.468045ms)","trace[364759567] 'applied index is now lower than readState.Index' (duration: 823.4773ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:25:37.374903Z","caller":"traceutil/trace.go:171","msg":"trace[1833717066] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"857.39965ms","start":"2026-04-16T16:25:36.517471Z","end":"2026-04-16T16:25:37.374871Z","steps":["trace[1833717066] 'process raft request' (duration: 33.95279ms)","trace[1833717066] 'compare' (duration: 823.082525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:25:37.375038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.158679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T16:25:37.375043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:36.517459Z","time spent":"857.508843ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:37.375072Z","caller":"traceutil/trace.go:171","msg":"trace[179077109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2065; }","duration":"845.21496ms","start":"2026-04-16T16:25:36.529847Z","end":"2026-04-16T16:25:37.375061Z","steps":["trace[179077109] 'agreement among raft nodes before linearized reading' (duration: 845.086838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:37.375108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:36.529835Z","time spent":"845.264962ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:25:37.375314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.290257ms","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-04-16T16:25:37.375348Z","caller":"traceutil/trace.go:171","msg":"trace[635569486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2065; }","duration":"844.338387ms","start":"2026-04-16T16:25:36.531Z","end":"2026-04-16T16:25:37.375338Z","steps":["trace[635569486] 'agreement among raft nodes before linearized reading' (duration: 844.216785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:37.375382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:36.530993Z","time spent":"844.382308ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T16:25:37.37568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.878347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T16:25:37.375708Z","caller":"traceutil/trace.go:171","msg":"trace[332746381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2065; }","duration":"789.906238ms","start":"2026-04-16T16:25:36.585793Z","end":"2026-04-16T16:25:37.375699Z","steps":["trace[332746381] 'agreement among raft nodes before linearized reading' (duration: 789.826506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:37.375733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:36.58573Z","time spent":"789.997829ms","remote":"127.0.0.1:34784","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-04-16T16:25:37.375729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.813623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-16T16:25:37.375776Z","caller":"traceutil/trace.go:171","msg":"trace[1544617600] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2065; }","duration":"842.901285ms","start":"2026-04-16T16:25:36.532858Z","end":"2026-04-16T16:25:37.375759Z","steps":["trace[1544617600] 'agreement among raft nodes before linearized reading' (duration: 842.777163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:37.375755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.82605ms","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-04-16T16:25:37.375877Z","caller":"traceutil/trace.go:171","msg":"trace[1336668841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2065; }","duration":"786.977502ms","start":"2026-04-16T16:25:36.588868Z","end":"2026-04-16T16:25:37.375845Z","steps":["trace[1336668841] 'agreement among raft nodes before linearized reading' (duration: 786.647427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:37.375934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:36.588857Z","time spent":"787.062243ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:25:37.376121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.88395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:25:37.376155Z","caller":"traceutil/trace.go:171","msg":"trace[224614329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2065; }","duration":"188.953771ms","start":"2026-04-16T16:25:37.18719Z","end":"2026-04-16T16:25:37.376144Z","steps":["trace[224614329] 'agreement among raft nodes before linearized reading' (duration: 188.889641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:37.375809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:36.532834Z","time spent":"842.967915ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-16T16:25:37.37651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.7582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T16:25:37.376556Z","caller":"traceutil/trace.go:171","msg":"trace[1012469138] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2065; }","duration":"824.871823ms","start":"2026-04-16T16:25:36.551674Z","end":"2026-04-16T16:25:37.376545Z","steps":["trace[1012469138] 'agreement among raft nodes before linearized reading' (duration: 824.157812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:37.376585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:36.551655Z","time spent":"824.923213ms","remote":"127.0.0.1:50580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-16T16:25:37.880437Z","caller":"traceutil/trace.go:171","msg":"trace[726461758] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"499.812227ms","start":"2026-04-16T16:25:37.380581Z","end":"2026-04-16T16:25:37.880393Z","steps":["trace[726461758] 'process raft request' (duration: 461.41571ms)","trace[726461758] 'compare' (duration: 38.103152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:25:37.880629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:37.380556Z","time spent":"499.969078ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:38.266114Z","caller":"traceutil/trace.go:171","msg":"trace[343146602] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"884.706291ms","start":"2026-04-16T16:25:37.381366Z","end":"2026-04-16T16:25:38.266072Z","steps":["trace[343146602] 'process raft request' (duration: 794.550358ms)","trace[343146602] 'compare' (duration: 89.889599ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:25:38.266269Z","caller":"traceutil/trace.go:171","msg":"trace[83496141] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"884.61572ms","start":"2026-04-16T16:25:37.381636Z","end":"2026-04-16T16:25:38.266252Z","steps":["trace[83496141] 'process raft request' (duration: 884.399037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:38.266284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:37.38134Z","time spent":"884.854874ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:38.266514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:37.381631Z","time spent":"884.715752ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:38.266112Z","caller":"traceutil/trace.go:171","msg":"trace[1897210271] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"884.55303ms","start":"2026-04-16T16:25:37.381531Z","end":"2026-04-16T16:25:38.266084Z","steps":["trace[1897210271] 'process raft request' (duration: 884.446208ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:38.266599Z","caller":"traceutil/trace.go:171","msg":"trace[1187062725] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2146; }","duration":"508.088047ms","start":"2026-04-16T16:25:37.758497Z","end":"2026-04-16T16:25:38.266585Z","steps":["trace[1187062725] 'read index received' (duration: 83.338884ms)","trace[1187062725] 'applied index is now lower than readState.Index' (duration: 424.748293ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:25:38.266625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:37.381525Z","time spent":"885.051397ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:38.266714Z","caller":"traceutil/trace.go:171","msg":"trace[1330907974] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"883.459193ms","start":"2026-04-16T16:25:37.383244Z","end":"2026-04-16T16:25:38.266703Z","steps":["trace[1330907974] 'process raft request' (duration: 882.965015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:38.266794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:37.383235Z","time spent":"883.517574ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:38.266815Z","caller":"traceutil/trace.go:171","msg":"trace[1190920743] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"883.490682ms","start":"2026-04-16T16:25:37.383299Z","end":"2026-04-16T16:25:38.26679Z","steps":["trace[1190920743] 'process raft request' (duration: 883.171947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:38.266896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:37.383293Z","time spent":"883.557953ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:38.266961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.470123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-04-16T16:25:38.267043Z","caller":"traceutil/trace.go:171","msg":"trace[1056999488] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2071; }","duration":"508.552824ms","start":"2026-04-16T16:25:37.758475Z","end":"2026-04-16T16:25:38.267028Z","steps":["trace[1056999488] 'agreement among raft nodes before linearized reading' (duration: 508.33046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:38.267117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:37.758402Z","time spent":"508.704186ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-16T16:25:38.283009Z","caller":"traceutil/trace.go:171","msg":"trace[1937129167] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"149.269526ms","start":"2026-04-16T16:25:38.133699Z","end":"2026-04-16T16:25:38.282968Z","steps":["trace[1937129167] 'process raft request' (duration: 149.092564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:38.964445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.364418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T16:25:38.964547Z","caller":"traceutil/trace.go:171","msg":"trace[1248959358] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2072; }","duration":"577.51428ms","start":"2026-04-16T16:25:38.387008Z","end":"2026-04-16T16:25:38.964522Z","steps":["trace[1248959358] 'range keys from in-memory index tree' (duration: 577.206516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:38.964594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:38.386996Z","time spent":"577.582961ms","remote":"127.0.0.1:50580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-16T16:25:38.965334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.346953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:25:38.965699Z","caller":"traceutil/trace.go:171","msg":"trace[1999044797] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2153; }","duration":"370.850329ms","start":"2026-04-16T16:25:38.594829Z","end":"2026-04-16T16:25:38.96568Z","steps":["trace[1999044797] 'read index received' (duration: 117.082109ms)","trace[1999044797] 'applied index is now lower than readState.Index' (duration: 253.76729ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:25:38.965863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.024971ms","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-04-16T16:25:38.96596Z","caller":"traceutil/trace.go:171","msg":"trace[581189897] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2075; }","duration":"371.155983ms","start":"2026-04-16T16:25:38.594784Z","end":"2026-04-16T16:25:38.96594Z","steps":["trace[581189897] 'agreement among raft nodes before linearized reading' (duration: 370.99909ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:38.966056Z","caller":"traceutil/trace.go:171","msg":"trace[616306058] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"470.587418ms","start":"2026-04-16T16:25:38.495453Z","end":"2026-04-16T16:25:38.96604Z","steps":["trace[616306058] 'process raft request' (duration: 216.421353ms)","trace[616306058] 'compare' (duration: 252.206056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:25:38.966164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:38.495419Z","time spent":"470.67267ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:38.96611Z","caller":"traceutil/trace.go:171","msg":"trace[345740275] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"466.589358ms","start":"2026-04-16T16:25:38.499492Z","end":"2026-04-16T16:25:38.966081Z","steps":["trace[345740275] 'process raft request' (duration: 466.023978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:38.966062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:38.594762Z","time spent":"371.282915ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:25:38.966407Z","caller":"traceutil/trace.go:171","msg":"trace[1168071434] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"458.243609ms","start":"2026-04-16T16:25:38.508152Z","end":"2026-04-16T16:25:38.966396Z","steps":["trace[1168071434] 'process raft request' (duration: 457.475146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:25:38.966615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:38.508133Z","time spent":"458.300439ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:25:38.966592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:25:38.49947Z","time spent":"466.860042ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:25:44.427438Z","caller":"traceutil/trace.go:171","msg":"trace[16041964] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"103.368665ms","start":"2026-04-16T16:25:44.324037Z","end":"2026-04-16T16:25:44.427405Z","steps":["trace[16041964] 'process raft request' (duration: 103.185393ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:44.432835Z","caller":"traceutil/trace.go:171","msg":"trace[696099552] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"106.781287ms","start":"2026-04-16T16:25:44.32603Z","end":"2026-04-16T16:25:44.432811Z","steps":["trace[696099552] 'process raft request' (duration: 106.702156ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:44.432865Z","caller":"traceutil/trace.go:171","msg":"trace[812503629] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"107.445226ms","start":"2026-04-16T16:25:44.325396Z","end":"2026-04-16T16:25:44.432841Z","steps":["trace[812503629] 'process raft request' (duration: 107.211013ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:44.433358Z","caller":"traceutil/trace.go:171","msg":"trace[1075439788] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"107.095382ms","start":"2026-04-16T16:25:44.326244Z","end":"2026-04-16T16:25:44.43334Z","steps":["trace[1075439788] 'process raft request' (duration: 106.527593ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:25:44.4338Z","caller":"traceutil/trace.go:171","msg":"trace[817171568] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"106.791827ms","start":"2026-04-16T16:25:44.326971Z","end":"2026-04-16T16:25:44.433763Z","steps":["trace[817171568] 'process raft request' (duration: 106.595694ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:09.732084Z","caller":"traceutil/trace.go:171","msg":"trace[484671822] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"175.314347ms","start":"2026-04-16T16:26:09.556743Z","end":"2026-04-16T16:26:09.732057Z","steps":["trace[484671822] 'process raft request' (duration: 175.154795ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:10.761424Z","caller":"traceutil/trace.go:171","msg":"trace[2062826008] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"145.142046ms","start":"2026-04-16T16:26:10.616252Z","end":"2026-04-16T16:26:10.761394Z","steps":["trace[2062826008] 'process raft request' (duration: 100.88228ms)","trace[2062826008] 'compare' (duration: 44.072252ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:26:10.761451Z","caller":"traceutil/trace.go:171","msg":"trace[1868360589] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"145.085205ms","start":"2026-04-16T16:26:10.616341Z","end":"2026-04-16T16:26:10.761426Z","steps":["trace[1868360589] 'process raft request' (duration: 144.996303ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:10.76162Z","caller":"traceutil/trace.go:171","msg":"trace[980137930] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"144.501017ms","start":"2026-04-16T16:26:10.61709Z","end":"2026-04-16T16:26:10.761591Z","steps":["trace[980137930] 'process raft request' (duration: 144.294085ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:35.304248Z","caller":"traceutil/trace.go:171","msg":"trace[1838383119] transaction","detail":"{read_only:false; response_revision:2430; number_of_response:1; }","duration":"108.088135ms","start":"2026-04-16T16:26:35.196121Z","end":"2026-04-16T16:26:35.304209Z","steps":["trace[1838383119] 'process raft request' (duration: 107.806641ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:42.240043Z","caller":"traceutil/trace.go:171","msg":"trace[1257773711] linearizableReadLoop","detail":"{readStateIndex:2612; appliedIndex:2611; }","duration":"104.895173ms","start":"2026-04-16T16:26:42.135117Z","end":"2026-04-16T16:26:42.240012Z","steps":["trace[1257773711] 'read index received' (duration: 104.66834ms)","trace[1257773711] 'applied index is now lower than readState.Index' (duration: 225.633µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:26:42.240106Z","caller":"traceutil/trace.go:171","msg":"trace[440879808] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"318.019605ms","start":"2026-04-16T16:26:41.922036Z","end":"2026-04-16T16:26:42.240056Z","steps":["trace[440879808] 'process raft request' (duration: 317.781762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:42.240301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.164518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-16T16:26:42.240363Z","caller":"traceutil/trace.go:171","msg":"trace[660896544] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2516; }","duration":"105.297319ms","start":"2026-04-16T16:26:42.135048Z","end":"2026-04-16T16:26:42.240345Z","steps":["trace[660896544] 'agreement among raft nodes before linearized reading' (duration: 105.091137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:42.240575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:41.922016Z","time spent":"318.203638ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:26:42.93241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.590547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:42.932509Z","caller":"traceutil/trace.go:171","msg":"trace[1679834724] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2516; }","duration":"450.756059ms","start":"2026-04-16T16:26:42.481728Z","end":"2026-04-16T16:26:42.932484Z","steps":["trace[1679834724] 'count revisions from in-memory index tree' (duration: 450.486666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:42.932487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.025699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T16:26:42.932556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:42.481698Z","time spent":"450.84688ms","remote":"127.0.0.1:34754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:26:42.932601Z","caller":"traceutil/trace.go:171","msg":"trace[2116081938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2516; }","duration":"114.219281ms","start":"2026-04-16T16:26:42.818355Z","end":"2026-04-16T16:26:42.932574Z","steps":["trace[2116081938] 'range keys from in-memory index tree' (duration: 113.788236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:42.932618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.202445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7341"} {"level":"info","ts":"2026-04-16T16:26:42.932724Z","caller":"traceutil/trace.go:171","msg":"trace[752731757] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2516; }","duration":"680.319997ms","start":"2026-04-16T16:26:42.252369Z","end":"2026-04-16T16:26:42.932689Z","steps":["trace[752731757] 'range keys from in-memory index tree' (duration: 680.055103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:42.932777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:42.252358Z","time spent":"680.399268ms","remote":"127.0.0.1:34936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7365,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"info","ts":"2026-04-16T16:26:45.070149Z","caller":"traceutil/trace.go:171","msg":"trace[2066528006] linearizableReadLoop","detail":"{readStateIndex:2623; appliedIndex:2622; }","duration":"121.04656ms","start":"2026-04-16T16:26:44.949076Z","end":"2026-04-16T16:26:45.070123Z","steps":["trace[2066528006] 'read index received' (duration: 120.113398ms)","trace[2066528006] 'applied index is now lower than readState.Index' (duration: 931.752µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:26:45.070223Z","caller":"traceutil/trace.go:171","msg":"trace[32605089] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"123.715474ms","start":"2026-04-16T16:26:44.94647Z","end":"2026-04-16T16:26:45.070185Z","steps":["trace[32605089] 'process raft request' (duration: 122.739071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:45.070369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.570678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:45.070412Z","caller":"traceutil/trace.go:171","msg":"trace[712993808] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2527; }","duration":"106.694809ms","start":"2026-04-16T16:26:44.963707Z","end":"2026-04-16T16:26:45.070402Z","steps":["trace[712993808] 'agreement among raft nodes before linearized reading' (duration: 106.600858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:45.070482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.377114ms","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-04-16T16:26:45.070571Z","caller":"traceutil/trace.go:171","msg":"trace[1960653932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2527; }","duration":"121.530225ms","start":"2026-04-16T16:26:44.94902Z","end":"2026-04-16T16:26:45.07055Z","steps":["trace[1960653932] 'agreement among raft nodes before linearized reading' (duration: 121.253782ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:46.830689Z","caller":"traceutil/trace.go:171","msg":"trace[379227833] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"348.709186ms","start":"2026-04-16T16:26:46.481915Z","end":"2026-04-16T16:26:46.830624Z","steps":["trace[379227833] 'process raft request' (duration: 348.518304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:46.831005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:46.481892Z","time spent":"349.01995ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:26:46.831938Z","caller":"traceutil/trace.go:171","msg":"trace[1041599873] linearizableReadLoop","detail":"{readStateIndex:2634; appliedIndex:2634; }","duration":"349.926372ms","start":"2026-04-16T16:26:46.482Z","end":"2026-04-16T16:26:46.831926Z","steps":["trace[1041599873] 'read index received' (duration: 349.919462ms)","trace[1041599873] 'applied index is now lower than readState.Index' (duration: 5.21µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:26:46.832055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.046033ms","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-04-16T16:26:46.832082Z","caller":"traceutil/trace.go:171","msg":"trace[39444401] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2537; }","duration":"350.089114ms","start":"2026-04-16T16:26:46.481983Z","end":"2026-04-16T16:26:46.832072Z","steps":["trace[39444401] 'agreement among raft nodes before linearized reading' (duration: 349.991803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:46.832121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:46.481975Z","time spent":"350.141134ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-16T16:26:46.833093Z","caller":"traceutil/trace.go:171","msg":"trace[1918949111] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"297.566536ms","start":"2026-04-16T16:26:46.535517Z","end":"2026-04-16T16:26:46.833083Z","steps":["trace[1918949111] 'process raft request' (duration: 297.301573ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:09.511016Z","caller":"traceutil/trace.go:171","msg":"trace[812739968] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"130.786647ms","start":"2026-04-16T16:27:09.380208Z","end":"2026-04-16T16:27:09.510995Z","steps":["trace[812739968] 'process raft request' (duration: 130.664685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:09.770336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.174932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:27:09.770605Z","caller":"traceutil/trace.go:171","msg":"trace[2076515565] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"193.698805ms","start":"2026-04-16T16:27:09.576885Z","end":"2026-04-16T16:27:09.770584Z","steps":["trace[2076515565] 'process raft request' (duration: 193.641274ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:09.770796Z","caller":"traceutil/trace.go:171","msg":"trace[179748180] transaction","detail":"{read_only:false; response_revision:2670; number_of_response:1; }","duration":"195.839121ms","start":"2026-04-16T16:27:09.574943Z","end":"2026-04-16T16:27:09.770782Z","steps":["trace[179748180] 'process raft request' (duration: 195.508367ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:09.771013Z","caller":"traceutil/trace.go:171","msg":"trace[276102913] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"197.705873ms","start":"2026-04-16T16:27:09.573199Z","end":"2026-04-16T16:27:09.770905Z","steps":["trace[276102913] 'process raft request' (duration: 94.859183ms)","trace[276102913] 'compare' (duration: 101.830468ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:27:15.459494Z","caller":"traceutil/trace.go:171","msg":"trace[689240532] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2796; }","duration":"182.985768ms","start":"2026-04-16T16:27:15.276488Z","end":"2026-04-16T16:27:15.459474Z","steps":["trace[689240532] 'read index received' (duration: 182.770996ms)","trace[689240532] 'applied index is now lower than readState.Index' (duration: 213.602µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:27:15.459763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.771895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-16T16:27:15.459803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.970495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T16:27:15.459837Z","caller":"traceutil/trace.go:171","msg":"trace[2061066292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2694; }","duration":"176.905946ms","start":"2026-04-16T16:27:15.282891Z","end":"2026-04-16T16:27:15.459797Z","steps":["trace[2061066292] 'agreement among raft nodes before linearized reading' (duration: 176.710104ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:15.459865Z","caller":"traceutil/trace.go:171","msg":"trace[1669899609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2694; }","duration":"171.056717ms","start":"2026-04-16T16:27:15.288795Z","end":"2026-04-16T16:27:15.459852Z","steps":["trace[1669899609] 'agreement among raft nodes before linearized reading' (duration: 170.922915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:15.459931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.598872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T16:27:15.460021Z","caller":"traceutil/trace.go:171","msg":"trace[1837840374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2694; }","duration":"171.754185ms","start":"2026-04-16T16:27:15.288251Z","end":"2026-04-16T16:27:15.460005Z","steps":["trace[1837840374] 'agreement among raft nodes before linearized reading' (duration: 171.485221ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:15.460329Z","caller":"traceutil/trace.go:171","msg":"trace[1892952584] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"187.780626ms","start":"2026-04-16T16:27:15.271905Z","end":"2026-04-16T16:27:15.459685Z","steps":["trace[1892952584] 'process raft request' (duration: 187.434492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:15.46058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.077751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-16T16:27:15.460655Z","caller":"traceutil/trace.go:171","msg":"trace[61089644] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2694; }","duration":"184.185823ms","start":"2026-04-16T16:27:15.27645Z","end":"2026-04-16T16:27:15.460636Z","steps":["trace[61089644] 'agreement among raft nodes before linearized reading' (duration: 183.125501ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:15.920616Z","caller":"traceutil/trace.go:171","msg":"trace[469258327] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"456.690013ms","start":"2026-04-16T16:27:15.463896Z","end":"2026-04-16T16:27:15.920586Z","steps":["trace[469258327] 'process raft request' (duration: 456.513401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:15.920819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:15.463835Z","time spent":"456.876046ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:27:15.943952Z","caller":"traceutil/trace.go:171","msg":"trace[1427475766] transaction","detail":"{read_only:false; response_revision:2696; number_of_response:1; }","duration":"479.85933ms","start":"2026-04-16T16:27:15.464064Z","end":"2026-04-16T16:27:15.943923Z","steps":["trace[1427475766] 'process raft request' (duration: 479.504945ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:15.944065Z","caller":"traceutil/trace.go:171","msg":"trace[1784311938] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"147.149241ms","start":"2026-04-16T16:27:15.796886Z","end":"2026-04-16T16:27:15.944035Z","steps":["trace[1784311938] 'process raft request' (duration: 147.10656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:15.944108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:15.463833Z","time spent":"480.190795ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:27:15.944437Z","caller":"traceutil/trace.go:171","msg":"trace[857349388] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"480.213904ms","start":"2026-04-16T16:27:15.464207Z","end":"2026-04-16T16:27:15.944421Z","steps":["trace[857349388] 'process raft request' (duration: 479.591587ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:15.944108Z","caller":"traceutil/trace.go:171","msg":"trace[1721409204] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"479.502535ms","start":"2026-04-16T16:27:15.464584Z","end":"2026-04-16T16:27:15.944086Z","steps":["trace[1721409204] 'process raft request' (duration: 479.275993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:15.94452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:15.464189Z","time spent":"480.288945ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:27:15.944602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:15.464576Z","time spent":"479.969251ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:27:15.944662Z","caller":"traceutil/trace.go:171","msg":"trace[1191971654] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"478.951779ms","start":"2026-04-16T16:27:15.465682Z","end":"2026-04-16T16:27:15.944634Z","steps":["trace[1191971654] 'process raft request' (duration: 478.21138ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:15.94473Z","caller":"traceutil/trace.go:171","msg":"trace[2028225306] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"149.490658ms","start":"2026-04-16T16:27:15.795227Z","end":"2026-04-16T16:27:15.944718Z","steps":["trace[2028225306] 'process raft request' (duration: 148.707399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:15.945303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:15.465664Z","time spent":"479.448225ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:27:15.945482Z","caller":"traceutil/trace.go:171","msg":"trace[293129549] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"147.95262ms","start":"2026-04-16T16:27:15.796706Z","end":"2026-04-16T16:27:15.944659Z","steps":["trace[293129549] 'process raft request' (duration: 147.259492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:16.940926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.067862ms","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-04-16T16:27:16.941011Z","caller":"traceutil/trace.go:171","msg":"trace[967717312] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2702; }","duration":"822.162013ms","start":"2026-04-16T16:27:16.118837Z","end":"2026-04-16T16:27:16.940999Z","steps":["trace[967717312] 'range keys from in-memory index tree' (duration: 822.055271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:16.941027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.387582ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-16T16:27:16.94131Z","caller":"traceutil/trace.go:171","msg":"trace[591881180] linearizableReadLoop","detail":"{readStateIndex:2806; appliedIndex:2805; }","duration":"879.826481ms","start":"2026-04-16T16:27:16.06147Z","end":"2026-04-16T16:27:16.941297Z","steps":["trace[591881180] 'read index received' (duration: 341.171506ms)","trace[591881180] 'applied index is now lower than readState.Index' (duration: 538.653385ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:27:16.941431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:15.995427Z","time spent":"946.001913ms","remote":"127.0.0.1:34538","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-16T16:27:16.941643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.173615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:27:16.941674Z","caller":"traceutil/trace.go:171","msg":"trace[103569587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2702; }","duration":"880.245256ms","start":"2026-04-16T16:27:16.061421Z","end":"2026-04-16T16:27:16.941666Z","steps":["trace[103569587] 'agreement among raft nodes before linearized reading' (duration: 880.185416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:16.941787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:16.061402Z","time spent":"880.376748ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:27:16.942166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.407495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-16T16:27:16.942211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.50695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:27:16.942253Z","caller":"traceutil/trace.go:171","msg":"trace[996202508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2702; }","duration":"752.57351ms","start":"2026-04-16T16:27:16.189666Z","end":"2026-04-16T16:27:16.942239Z","steps":["trace[996202508] 'agreement among raft nodes before linearized reading' (duration: 752.52536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:16.942293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:16.189644Z","time spent":"752.641302ms","remote":"127.0.0.1:34488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:27:16.942151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.23992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-16T16:27:16.942795Z","caller":"traceutil/trace.go:171","msg":"trace[1964405404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2702; }","duration":"524.510416ms","start":"2026-04-16T16:27:16.41769Z","end":"2026-04-16T16:27:16.9422Z","steps":["trace[1964405404] 'agreement among raft nodes before linearized reading' (duration: 524.355544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:16.942828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:16.417667Z","time spent":"525.153094ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T16:27:16.942796Z","caller":"traceutil/trace.go:171","msg":"trace[99864326] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2702; }","duration":"805.904997ms","start":"2026-04-16T16:27:16.136858Z","end":"2026-04-16T16:27:16.942763Z","steps":["trace[99864326] 'agreement among raft nodes before linearized reading' (duration: 805.102998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:16.942911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:27:16.136841Z","time spent":"806.03247ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-16T16:27:17.158645Z","caller":"traceutil/trace.go:171","msg":"trace[618978437] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"215.763365ms","start":"2026-04-16T16:27:16.942847Z","end":"2026-04-16T16:27:17.15861Z","steps":["trace[618978437] 'process raft request' (duration: 127.854647ms)","trace[618978437] 'compare' (duration: 87.683726ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:27:17.158812Z","caller":"traceutil/trace.go:171","msg":"trace[67369114] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"138.996998ms","start":"2026-04-16T16:27:17.019793Z","end":"2026-04-16T16:27:17.15879Z","steps":["trace[67369114] 'process raft request' (duration: 138.960877ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:17.159156Z","caller":"traceutil/trace.go:171","msg":"trace[1051411090] linearizableReadLoop","detail":"{readStateIndex:2807; appliedIndex:2806; }","duration":"213.280967ms","start":"2026-04-16T16:27:16.945858Z","end":"2026-04-16T16:27:17.159139Z","steps":["trace[1051411090] 'read index received' (duration: 124.837151ms)","trace[1051411090] 'applied index is now lower than readState.Index' (duration: 88.442156ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:27:17.159339Z","caller":"traceutil/trace.go:171","msg":"trace[820291085] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"211.796129ms","start":"2026-04-16T16:27:16.947512Z","end":"2026-04-16T16:27:17.159308Z","steps":["trace[820291085] 'process raft request' (duration: 211.17415ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:17.159371Z","caller":"traceutil/trace.go:171","msg":"trace[1174138018] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"213.287846ms","start":"2026-04-16T16:27:16.946062Z","end":"2026-04-16T16:27:17.15935Z","steps":["trace[1174138018] 'process raft request' (duration: 212.543966ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:17.159436Z","caller":"traceutil/trace.go:171","msg":"trace[419953895] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"211.607086ms","start":"2026-04-16T16:27:16.947744Z","end":"2026-04-16T16:27:17.159351Z","steps":["trace[419953895] 'process raft request' (duration: 210.979528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:27:17.159747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.510669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-04-16T16:27:17.159817Z","caller":"traceutil/trace.go:171","msg":"trace[396977964] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2707; }","duration":"213.955984ms","start":"2026-04-16T16:27:16.945827Z","end":"2026-04-16T16:27:17.159783Z","steps":["trace[396977964] 'agreement among raft nodes before linearized reading' (duration: 213.377757ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:09.142298Z","caller":"traceutil/trace.go:171","msg":"trace[1584891190] linearizableReadLoop","detail":"{readStateIndex:3848; appliedIndex:3847; }","duration":"318.380512ms","start":"2026-04-16T16:30:08.823886Z","end":"2026-04-16T16:30:09.142266Z","steps":["trace[1584891190] 'read index received' (duration: 318.136846ms)","trace[1584891190] 'applied index is now lower than readState.Index' (duration: 242.196µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:30:09.142488Z","caller":"traceutil/trace.go:171","msg":"trace[2004890023] transaction","detail":"{read_only:false; response_revision:3706; number_of_response:1; }","duration":"396.41235ms","start":"2026-04-16T16:30:08.74606Z","end":"2026-04-16T16:30:09.142473Z","steps":["trace[2004890023] 'process raft request' (duration: 395.98773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:09.142636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:08.746032Z","time spent":"396.503882ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:30:09.142777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.314972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T16:30:09.142846Z","caller":"traceutil/trace.go:171","msg":"trace[1674535347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3706; }","duration":"316.426715ms","start":"2026-04-16T16:30:08.826404Z","end":"2026-04-16T16:30:09.142831Z","steps":["trace[1674535347] 'agreement among raft nodes before linearized reading' (duration: 316.23247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:09.142882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:08.826383Z","time spent":"316.491376ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T16:30:09.143029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.637584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:30:09.143089Z","caller":"traceutil/trace.go:171","msg":"trace[1253707304] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:3706; }","duration":"288.729127ms","start":"2026-04-16T16:30:08.854343Z","end":"2026-04-16T16:30:09.143072Z","steps":["trace[1253707304] 'agreement among raft nodes before linearized reading' (duration: 288.638574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:09.143156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.713452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-16T16:30:09.143187Z","caller":"traceutil/trace.go:171","msg":"trace[1539255303] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3706; }","duration":"316.781633ms","start":"2026-04-16T16:30:08.826396Z","end":"2026-04-16T16:30:09.143178Z","steps":["trace[1539255303] 'agreement among raft nodes before linearized reading' (duration: 316.719682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:09.143211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:08.826374Z","time spent":"316.832964ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-16T16:30:09.143387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.50697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T16:30:09.143431Z","caller":"traceutil/trace.go:171","msg":"trace[289960256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3706; }","duration":"319.580961ms","start":"2026-04-16T16:30:08.823839Z","end":"2026-04-16T16:30:09.14342Z","steps":["trace[289960256] 'agreement among raft nodes before linearized reading' (duration: 319.443718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:09.143463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:08.823817Z","time spent":"319.640273ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:30:09.143097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.478481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T16:30:09.14355Z","caller":"traceutil/trace.go:171","msg":"trace[102112837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3706; }","duration":"315.950843ms","start":"2026-04-16T16:30:08.827592Z","end":"2026-04-16T16:30:09.143543Z","steps":["trace[102112837] 'agreement among raft nodes before linearized reading' (duration: 315.434911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:09.143582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:08.827582Z","time spent":"315.992444ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T16:31:04.605562Z","caller":"traceutil/trace.go:171","msg":"trace[520904457] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"120.198632ms","start":"2026-04-16T16:31:04.485332Z","end":"2026-04-16T16:31:04.605531Z","steps":["trace[520904457] 'process raft request' (duration: 119.365365ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:06.437338Z","caller":"traceutil/trace.go:171","msg":"trace[832379493] linearizableReadLoop","detail":"{readStateIndex:4746; appliedIndex:4745; }","duration":"274.483923ms","start":"2026-04-16T16:31:06.162819Z","end":"2026-04-16T16:31:06.437303Z","steps":["trace[832379493] 'read index received' (duration: 208.891893ms)","trace[832379493] 'applied index is now lower than readState.Index' (duration: 65.59066ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:06.437494Z","caller":"traceutil/trace.go:171","msg":"trace[421575630] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"282.371568ms","start":"2026-04-16T16:31:06.15511Z","end":"2026-04-16T16:31:06.437481Z","steps":["trace[421575630] 'process raft request' (duration: 216.467831ms)","trace[421575630] 'compare' (duration: 65.237793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:06.437774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.936482ms","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-04-16T16:31:06.437811Z","caller":"traceutil/trace.go:171","msg":"trace[949178155] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4567; }","duration":"275.022744ms","start":"2026-04-16T16:31:06.162776Z","end":"2026-04-16T16:31:06.437799Z","steps":["trace[949178155] 'agreement among raft nodes before linearized reading' (duration: 274.858681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:06.437996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.894819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:06.43802Z","caller":"traceutil/trace.go:171","msg":"trace[1152191779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4567; }","duration":"250.952561ms","start":"2026-04-16T16:31:06.187062Z","end":"2026-04-16T16:31:06.438014Z","steps":["trace[1152191779] 'agreement among raft nodes before linearized reading' (duration: 250.91323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:06.439235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.201085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-16T16:31:06.43927Z","caller":"traceutil/trace.go:171","msg":"trace[275689865] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4567; }","duration":"214.239395ms","start":"2026-04-16T16:31:06.225023Z","end":"2026-04-16T16:31:06.439263Z","steps":["trace[275689865] 'agreement among raft nodes before linearized reading' (duration: 213.277406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.952921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.496351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:07.953063Z","caller":"traceutil/trace.go:171","msg":"trace[812226132] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"291.33005ms","start":"2026-04-16T16:31:07.661707Z","end":"2026-04-16T16:31:07.953037Z","steps":["trace[812226132] 'process raft request' (duration: 145.587404ms)","trace[812226132] 'compare' (duration: 145.255346ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:07.954516Z","caller":"traceutil/trace.go:171","msg":"trace[566611579] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"288.326338ms","start":"2026-04-16T16:31:07.666149Z","end":"2026-04-16T16:31:07.954475Z","steps":["trace[566611579] 'process raft request' (duration: 287.595242ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:07.954493Z","caller":"traceutil/trace.go:171","msg":"trace[1031529642] transaction","detail":"{read_only:false; response_revision:4595; number_of_response:1; }","duration":"288.328868ms","start":"2026-04-16T16:31:07.666113Z","end":"2026-04-16T16:31:07.954442Z","steps":["trace[1031529642] 'process raft request' (duration: 287.437679ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:07.95461Z","caller":"traceutil/trace.go:171","msg":"trace[2031067792] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"288.323207ms","start":"2026-04-16T16:31:07.666276Z","end":"2026-04-16T16:31:07.954599Z","steps":["trace[2031067792] 'process raft request' (duration: 288.090172ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:14.300191Z","caller":"traceutil/trace.go:171","msg":"trace[1498979266] linearizableReadLoop","detail":"{readStateIndex:4899; appliedIndex:4898; }","duration":"109.73665ms","start":"2026-04-16T16:31:14.190411Z","end":"2026-04-16T16:31:14.300148Z","steps":["trace[1498979266] 'read index received' (duration: 109.388123ms)","trace[1498979266] 'applied index is now lower than readState.Index' (duration: 347.087µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:14.300426Z","caller":"traceutil/trace.go:171","msg":"trace[1731168029] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"136.742584ms","start":"2026-04-16T16:31:14.163666Z","end":"2026-04-16T16:31:14.300409Z","steps":["trace[1731168029] 'process raft request' (duration: 136.232554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:14.300981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.791537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-71cf5e69-0a17-4f34-bded-b15e547dfad4\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-16T16:31:14.301087Z","caller":"traceutil/trace.go:171","msg":"trace[834226044] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-71cf5e69-0a17-4f34-bded-b15e547dfad4; range_end:; response_count:1; response_revision:4716; }","duration":"102.995253ms","start":"2026-04-16T16:31:14.198066Z","end":"2026-04-16T16:31:14.301061Z","steps":["trace[834226044] 'agreement among raft nodes before linearized reading' (duration: 102.623595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:14.301147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.692059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:14.301239Z","caller":"traceutil/trace.go:171","msg":"trace[353030471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4716; }","duration":"110.858763ms","start":"2026-04-16T16:31:14.190357Z","end":"2026-04-16T16:31:14.301215Z","steps":["trace[353030471] 'agreement among raft nodes before linearized reading' (duration: 110.664409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:15.222475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.060244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:15.222873Z","caller":"traceutil/trace.go:171","msg":"trace[1242667551] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"314.419028ms","start":"2026-04-16T16:31:14.908429Z","end":"2026-04-16T16:31:15.222848Z","steps":["trace[1242667551] 'process raft request' (duration: 314.370477ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:15.222892Z","caller":"traceutil/trace.go:171","msg":"trace[1795001183] linearizableReadLoop","detail":"{readStateIndex:4903; appliedIndex:4902; }","duration":"562.20828ms","start":"2026-04-16T16:31:14.660638Z","end":"2026-04-16T16:31:15.222846Z","steps":["trace[1795001183] 'read index received' (duration: 260.600075ms)","trace[1795001183] 'applied index is now lower than readState.Index' (duration: 301.605445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:15.222961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:14.908417Z","time spent":"314.50715ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:15.223221Z","caller":"traceutil/trace.go:171","msg":"trace[483500343] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"610.775057ms","start":"2026-04-16T16:31:14.612427Z","end":"2026-04-16T16:31:15.223202Z","steps":["trace[483500343] 'process raft request' (duration: 308.864306ms)","trace[483500343] 'compare' (duration: 300.35683ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:15.223251Z","caller":"traceutil/trace.go:171","msg":"trace[1061624023] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"351.852505ms","start":"2026-04-16T16:31:14.871386Z","end":"2026-04-16T16:31:15.223239Z","steps":["trace[1061624023] 'process raft request' (duration: 351.225092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:15.223308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:14.871348Z","time spent":"351.929397ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:15.223368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:14.612392Z","time spent":"610.865228ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:15.223455Z","caller":"traceutil/trace.go:171","msg":"trace[1822412655] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"318.88928ms","start":"2026-04-16T16:31:14.904556Z","end":"2026-04-16T16:31:15.223445Z","steps":["trace[1822412655] 'process raft request' (duration: 318.200205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:15.223522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:14.904511Z","time spent":"318.967942ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:15.22366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.031947ms","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-04-16T16:31:15.223689Z","caller":"traceutil/trace.go:171","msg":"trace[2112937214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4723; }","duration":"563.11613ms","start":"2026-04-16T16:31:14.660565Z","end":"2026-04-16T16:31:15.223681Z","steps":["trace[2112937214] 'agreement among raft nodes before linearized reading' (duration: 562.986567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:15.223711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:14.660532Z","time spent":"563.17457ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:31:15.22387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.392498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-16T16:31:15.22389Z","caller":"traceutil/trace.go:171","msg":"trace[1752226255] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4723; }","duration":"452.47054ms","start":"2026-04-16T16:31:14.771412Z","end":"2026-04-16T16:31:15.223883Z","steps":["trace[1752226255] 'agreement among raft nodes before linearized reading' (duration: 452.401418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:15.223911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:14.771377Z","time spent":"452.526571ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3268,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-16T16:31:15.224201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.234507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-71cf5e69-0a17-4f34-bded-b15e547dfad4\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-04-16T16:31:15.224242Z","caller":"traceutil/trace.go:171","msg":"trace[1651331131] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-71cf5e69-0a17-4f34-bded-b15e547dfad4; range_end:; response_count:1; response_revision:4723; }","duration":"416.325649ms","start":"2026-04-16T16:31:14.807904Z","end":"2026-04-16T16:31:15.224229Z","steps":["trace[1651331131] 'agreement among raft nodes before linearized reading' (duration: 416.215107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:15.224273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:14.807869Z","time spent":"416.39408ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3789,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-71cf5e69-0a17-4f34-bded-b15e547dfad4\" "} {"level":"warn","ts":"2026-04-16T16:31:15.626724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.660487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:15.627197Z","caller":"traceutil/trace.go:171","msg":"trace[881567912] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"292.274522ms","start":"2026-04-16T16:31:15.334905Z","end":"2026-04-16T16:31:15.627179Z","steps":["trace[881567912] 'process raft request' (duration: 292.165749ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:15.62719Z","caller":"traceutil/trace.go:171","msg":"trace[1786248962] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"390.012732ms","start":"2026-04-16T16:31:15.237127Z","end":"2026-04-16T16:31:15.627139Z","steps":["trace[1786248962] 'process raft request' (duration: 122.806383ms)","trace[1786248962] 'compare' (duration: 266.162877ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:15.627275Z","caller":"traceutil/trace.go:171","msg":"trace[1195974126] linearizableReadLoop","detail":"{readStateIndex:4908; appliedIndex:4907; }","duration":"325.062181ms","start":"2026-04-16T16:31:15.302156Z","end":"2026-04-16T16:31:15.627218Z","steps":["trace[1195974126] 'read index received' (duration: 57.966015ms)","trace[1195974126] 'applied index is now lower than readState.Index' (duration: 267.090036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:15.627346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:15.237107Z","time spent":"390.153824ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:15.627611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.614249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:15.627669Z","caller":"traceutil/trace.go:171","msg":"trace[362363105] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:4726; }","duration":"338.754672ms","start":"2026-04-16T16:31:15.288895Z","end":"2026-04-16T16:31:15.62765Z","steps":["trace[362363105] 'agreement among raft nodes before linearized reading' (duration: 338.539678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:15.627712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:15.288869Z","time spent":"338.831944ms","remote":"127.0.0.1:47538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:31:15.627759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.913836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-16T16:31:15.627813Z","caller":"traceutil/trace.go:171","msg":"trace[781446549] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4726; }","duration":"320.023418ms","start":"2026-04-16T16:31:15.307777Z","end":"2026-04-16T16:31:15.6278Z","steps":["trace[781446549] 'agreement among raft nodes before linearized reading' (duration: 319.810394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:15.627856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:15.307755Z","time spent":"320.09388ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1289,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" "} {"level":"info","ts":"2026-04-16T16:31:17.457861Z","caller":"traceutil/trace.go:171","msg":"trace[1861193047] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"143.873771ms","start":"2026-04-16T16:31:17.313945Z","end":"2026-04-16T16:31:17.457819Z","steps":["trace[1861193047] 'process raft request' (duration: 142.79823ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:17.458174Z","caller":"traceutil/trace.go:171","msg":"trace[1215240128] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4937; }","duration":"112.093834ms","start":"2026-04-16T16:31:17.345386Z","end":"2026-04-16T16:31:17.457479Z","steps":["trace[1215240128] 'read index received' (duration: 111.375629ms)","trace[1215240128] 'applied index is now lower than readState.Index' (duration: 717.015µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:17.458501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.093394ms","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-04-16T16:31:17.458587Z","caller":"traceutil/trace.go:171","msg":"trace[579000432] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4754; }","duration":"113.243117ms","start":"2026-04-16T16:31:17.345322Z","end":"2026-04-16T16:31:17.458566Z","steps":["trace[579000432] 'agreement among raft nodes before linearized reading' (duration: 112.972942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:17.459501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.609504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-16T16:31:17.459601Z","caller":"traceutil/trace.go:171","msg":"trace[256812978] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4755; }","duration":"112.749637ms","start":"2026-04-16T16:31:17.346836Z","end":"2026-04-16T16:31:17.459585Z","steps":["trace[256812978] 'agreement among raft nodes before linearized reading' (duration: 112.304298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:28.881209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.886674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T16:31:28.881216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.231814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"info","ts":"2026-04-16T16:31:28.881302Z","caller":"traceutil/trace.go:171","msg":"trace[2032233993] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4900; }","duration":"537.361257ms","start":"2026-04-16T16:31:28.343926Z","end":"2026-04-16T16:31:28.881288Z","steps":["trace[2032233993] 'range keys from in-memory index tree' (duration: 536.904468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:28.881342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.692199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:28.88137Z","caller":"traceutil/trace.go:171","msg":"trace[250374108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4900; }","duration":"692.757319ms","start":"2026-04-16T16:31:28.188605Z","end":"2026-04-16T16:31:28.881362Z","steps":["trace[250374108] 'range keys from in-memory index tree' (duration: 692.613367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:28.881371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:28.343907Z","time spent":"537.453698ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10418,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-16T16:31:28.881399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:28.188577Z","time spent":"692.814851ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:31:28.881523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.073808ms","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":"warn","ts":"2026-04-16T16:31:28.881509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.087096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-16T16:31:28.881576Z","caller":"traceutil/trace.go:171","msg":"trace[1936796239] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4900; }","duration":"461.16979ms","start":"2026-04-16T16:31:28.420389Z","end":"2026-04-16T16:31:28.881559Z","steps":["trace[1936796239] 'range keys from in-memory index tree' (duration: 460.932635ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:28.88158Z","caller":"traceutil/trace.go:171","msg":"trace[312727249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4900; }","duration":"275.208768ms","start":"2026-04-16T16:31:28.606357Z","end":"2026-04-16T16:31:28.881566Z","steps":["trace[312727249] 'range keys from in-memory index tree' (duration: 274.920412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:28.881607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:28.420364Z","time spent":"461.236072ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-16T16:31:28.881302Z","caller":"traceutil/trace.go:171","msg":"trace[747974842] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4900; }","duration":"680.126739ms","start":"2026-04-16T16:31:28.201154Z","end":"2026-04-16T16:31:28.88128Z","steps":["trace[747974842] 'count revisions from in-memory index tree' (duration: 679.64891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:28.881657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:28.201028Z","time spent":"680.616549ms","remote":"127.0.0.1:34922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:31:28.989508Z","caller":"traceutil/trace.go:171","msg":"trace[655090016] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"102.610033ms","start":"2026-04-16T16:31:28.886872Z","end":"2026-04-16T16:31:28.989482Z","steps":["trace[655090016] 'process raft request' (duration: 102.47513ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:29.0262Z","caller":"traceutil/trace.go:171","msg":"trace[1325158316] linearizableReadLoop","detail":"{readStateIndex:5092; appliedIndex:5091; }","duration":"118.855206ms","start":"2026-04-16T16:31:28.907322Z","end":"2026-04-16T16:31:29.026178Z","steps":["trace[1325158316] 'read index received' (duration: 82.635075ms)","trace[1325158316] 'applied index is now lower than readState.Index' (duration: 36.219111ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:29.026548Z","caller":"traceutil/trace.go:171","msg":"trace[762219351] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"133.812273ms","start":"2026-04-16T16:31:28.892724Z","end":"2026-04-16T16:31:29.026537Z","steps":["trace[762219351] 'process raft request' (duration: 133.342563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:29.02686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.530629ms","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-04-16T16:31:29.026891Z","caller":"traceutil/trace.go:171","msg":"trace[1019292520] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4902; }","duration":"119.6201ms","start":"2026-04-16T16:31:28.907264Z","end":"2026-04-16T16:31:29.026884Z","steps":["trace[1019292520] 'agreement among raft nodes before linearized reading' (duration: 119.477418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:29.512359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.565822ms","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-04-16T16:31:29.512443Z","caller":"traceutil/trace.go:171","msg":"trace[1511031172] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4910; }","duration":"142.714345ms","start":"2026-04-16T16:31:29.369708Z","end":"2026-04-16T16:31:29.512422Z","steps":["trace[1511031172] 'range keys from in-memory index tree' (duration: 142.348067ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:53.452424Z","caller":"traceutil/trace.go:171","msg":"trace[1504233417] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"118.190713ms","start":"2026-04-16T16:31:53.334208Z","end":"2026-04-16T16:31:53.452399Z","steps":["trace[1504233417] 'process raft request' (duration: 84.523174ms)","trace[1504233417] 'compare' (duration: 33.217621ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:53.45256Z","caller":"traceutil/trace.go:171","msg":"trace[864419757] linearizableReadLoop","detail":"{readStateIndex:5267; appliedIndex:5266; }","duration":"118.283225ms","start":"2026-04-16T16:31:53.334265Z","end":"2026-04-16T16:31:53.452549Z","steps":["trace[864419757] 'read index received' (duration: 84.375141ms)","trace[864419757] 'applied index is now lower than readState.Index' (duration: 33.906914ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:53.452801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.522369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-16T16:31:53.452846Z","caller":"traceutil/trace.go:171","msg":"trace[89000416] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5074; }","duration":"118.629671ms","start":"2026-04-16T16:31:53.334205Z","end":"2026-04-16T16:31:53.452835Z","steps":["trace[89000416] 'agreement among raft nodes before linearized reading' (duration: 118.490319ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:42.714445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2842} {"level":"info","ts":"2026-04-16T16:32:42.784479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2842,"took":"68.840482ms","hash":1141932607,"current-db-size-bytes":52416512,"current-db-size":"52 MB","current-db-size-in-use-bytes":28712960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-16T16:32:42.784566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1141932607,"revision":2842,"compact-revision":-1} {"level":"info","ts":"2026-04-16T16:33:26.498558Z","caller":"traceutil/trace.go:171","msg":"trace[301297592] transaction","detail":"{read_only:false; response_revision:5783; number_of_response:1; }","duration":"144.631516ms","start":"2026-04-16T16:33:26.353886Z","end":"2026-04-16T16:33:26.498517Z","steps":["trace[301297592] 'process raft request' (duration: 140.14515ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:37:42.719283Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5510} {"level":"info","ts":"2026-04-16T16:37:42.802255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5510,"took":"81.533362ms","hash":4137279350,"current-db-size-bytes":52416512,"current-db-size":"52 MB","current-db-size-in-use-bytes":18194432,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-16T16:37:42.802322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4137279350,"revision":5510,"compact-revision":2842}