{"level":"warn","ts":"2026-04-14T04:06:43.458811Z","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-14T04:06:43.458901Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-14T04:06:43.459191Z","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-14T04:06:43.459222Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-14T04:06:43.459306Z","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-14T04:06:43.460053Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-04-14T04:06:43.460213Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-14T04:06:43.464971Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.456445ms"} {"level":"info","ts":"2026-04-14T04:06:43.474386Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-04-14T04:06:43.47455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T04:06:43.474664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-04-14T04:06:43.474742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T04:06:43.474787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-04-14T04:06:43.474896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-04-14T04:06:43.489438Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T04:06:43.492982Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T04:06:43.496181Z","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-14T04:06:43.498536Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T04:06:43.504613Z","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-14T04:06:43.504702Z","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-14T04:06:43.504723Z","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-14T04:06:43.504829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T04:06:43.505278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-04-14T04:06:43.505394Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-14T04:06:43.505616Z","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-14T04:06:43.505904Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-14T04:06:43.505962Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-14T04:06:43.506009Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T04:06:43.506077Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T04:06:43.57589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T04:06:43.576059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T04:06:43.576253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-04-14T04:06:43.576349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-04-14T04:06:43.576418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-14T04:06:43.576485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-04-14T04:06:43.576543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-14T04:06:43.577693Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:06:43.578851Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T04:06:43.578855Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T04:06:43.579371Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:06:43.579573Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T04:06:43.580141Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:06:43.580251Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:06:43.581716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-04-14T04:06:43.593624Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T04:06:43.593722Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T04:06:43.593745Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-14T04:06:45.799604Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878395521897333,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T04:06:45.912643Z","caller":"traceutil/trace.go:171","msg":"trace[1956201935] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"619.619929ms","start":"2026-04-14T04:06:45.292996Z","end":"2026-04-14T04:06:45.912616Z","steps":["trace[1956201935] 'process raft request' (duration: 619.330764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.913264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.292985Z","time spent":"619.746311ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:06:45.971413Z","caller":"traceutil/trace.go:171","msg":"trace[531610147] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"678.097074ms","start":"2026-04-14T04:06:45.293264Z","end":"2026-04-14T04:06:45.971362Z","steps":["trace[531610147] 'process raft request' (duration: 677.828799ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:45.971553Z","caller":"traceutil/trace.go:171","msg":"trace[1494138905] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"666.353763ms","start":"2026-04-14T04:06:45.305177Z","end":"2026-04-14T04:06:45.971531Z","steps":["trace[1494138905] 'process raft request' (duration: 666.079647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.971667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.305169Z","time spent":"666.451944ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:06:45.971678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.293257Z","time spent":"678.265257ms","remote":"127.0.0.1:43992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:06:45.971679Z","caller":"traceutil/trace.go:171","msg":"trace[1757270920] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:4; }","duration":"672.925976ms","start":"2026-04-14T04:06:45.298699Z","end":"2026-04-14T04:06:45.971625Z","steps":["trace[1757270920] 'read index received' (duration: 613.717567ms)","trace[1757270920] 'applied index is now lower than readState.Index' (duration: 59.207309ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:06:45.971798Z","caller":"traceutil/trace.go:171","msg":"trace[1696331039] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"616.243405ms","start":"2026-04-14T04:06:45.355545Z","end":"2026-04-14T04:06:45.971788Z","steps":["trace[1696331039] 'process raft request' (duration: 615.911949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.971865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.355517Z","time spent":"616.313816ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:06:45.971879Z","caller":"traceutil/trace.go:171","msg":"trace[524934974] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"616.203424ms","start":"2026-04-14T04:06:45.355586Z","end":"2026-04-14T04:06:45.97179Z","steps":["trace[524934974] 'process raft request' (duration: 615.913259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.972068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.355575Z","time spent":"616.406158ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:06:45.972056Z","caller":"traceutil/trace.go:171","msg":"trace[1737764027] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"607.207014ms","start":"2026-04-14T04:06:45.364797Z","end":"2026-04-14T04:06:45.972004Z","steps":["trace[1737764027] 'process raft request' (duration: 606.736966ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:45.972253Z","caller":"traceutil/trace.go:171","msg":"trace[677078621] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"617.280945ms","start":"2026-04-14T04:06:45.354962Z","end":"2026-04-14T04:06:45.972243Z","steps":["trace[677078621] 'process raft request' (duration: 616.386658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.972208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.542671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-14T04:06:45.972181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.443556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-14T04:06:45.9723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.354935Z","time spent":"617.344816ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:06:45.972294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.935213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:06:45.972312Z","caller":"traceutil/trace.go:171","msg":"trace[1137422894] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"625.703514ms","start":"2026-04-14T04:06:45.346596Z","end":"2026-04-14T04:06:45.972299Z","steps":["trace[1137422894] 'agreement among raft nodes before linearized reading' (duration: 625.50813ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:45.972343Z","caller":"traceutil/trace.go:171","msg":"trace[720096122] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"673.621019ms","start":"2026-04-14T04:06:45.298689Z","end":"2026-04-14T04:06:45.97231Z","steps":["trace[720096122] 'agreement among raft nodes before linearized reading' (duration: 673.269443ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:45.972359Z","caller":"traceutil/trace.go:171","msg":"trace[693069133] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"568.030334ms","start":"2026-04-14T04:06:45.404318Z","end":"2026-04-14T04:06:45.972348Z","steps":["trace[693069133] 'agreement among raft nodes before linearized reading' (duration: 567.936932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.972382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.346569Z","time spent":"625.803035ms","remote":"127.0.0.1:44040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-14T04:06:45.97239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.298684Z","time spent":"673.69317ms","remote":"127.0.0.1:44358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-14T04:06:45.972407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.404307Z","time spent":"568.085315ms","remote":"127.0.0.1:44040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-14T04:06:45.972487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.897094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:06:45.972534Z","caller":"traceutil/trace.go:171","msg":"trace[1497713502] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"619.974575ms","start":"2026-04-14T04:06:45.352548Z","end":"2026-04-14T04:06:45.972523Z","steps":["trace[1497713502] 'agreement among raft nodes before linearized reading' (duration: 619.874113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.972558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.352538Z","time spent":"620.014887ms","remote":"127.0.0.1:44040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-14T04:06:45.972636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.830595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-14T04:06:45.972704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.331521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:06:45.972713Z","caller":"traceutil/trace.go:171","msg":"trace[111818975] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"617.899346ms","start":"2026-04-14T04:06:45.354784Z","end":"2026-04-14T04:06:45.972683Z","steps":["trace[111818975] 'agreement among raft nodes before linearized reading' (duration: 617.812705ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:45.972734Z","caller":"traceutil/trace.go:171","msg":"trace[1659613158] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:0; response_revision:11; }","duration":"622.373671ms","start":"2026-04-14T04:06:45.350354Z","end":"2026-04-14T04:06:45.972727Z","steps":["trace[1659613158] 'agreement among raft nodes before linearized reading' (duration: 622.3277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.972771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.350347Z","time spent":"622.418342ms","remote":"127.0.0.1:43974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.132\" "} {"level":"warn","ts":"2026-04-14T04:06:45.972779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.354779Z","time spent":"617.971968ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-14T04:06:45.972271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.364788Z","time spent":"607.421448ms","remote":"127.0.0.1:44128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:06:45.97207Z","caller":"traceutil/trace.go:171","msg":"trace[1822114159] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"618.181302ms","start":"2026-04-14T04:06:45.353858Z","end":"2026-04-14T04:06:45.972039Z","steps":["trace[1822114159] 'process raft request' (duration: 617.439578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.973159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.353845Z","time spent":"619.273092ms","remote":"127.0.0.1:44426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:06:45.972657Z","caller":"traceutil/trace.go:171","msg":"trace[120867948] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"617.257755ms","start":"2026-04-14T04:06:45.35539Z","end":"2026-04-14T04:06:45.972648Z","steps":["trace[120867948] 'process raft request' (duration: 615.986611ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:45.973401Z","caller":"traceutil/trace.go:171","msg":"trace[580040812] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"617.807284ms","start":"2026-04-14T04:06:45.355411Z","end":"2026-04-14T04:06:45.973219Z","steps":["trace[580040812] 'process raft request' (duration: 616.018281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:45.973508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.355384Z","time spent":"617.921607ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:06:45.973847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:06:45.355391Z","time spent":"618.266744ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:06:46.143099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.70081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-14T04:06:46.143389Z","caller":"traceutil/trace.go:171","msg":"trace[648843808] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"162.861061ms","start":"2026-04-14T04:06:45.980505Z","end":"2026-04-14T04:06:46.143366Z","steps":["trace[648843808] 'process raft request' (duration: 60.620321ms)","trace[648843808] 'compare' (duration: 101.447995ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:06:46.143391Z","caller":"traceutil/trace.go:171","msg":"trace[1799401452] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"159.905275ms","start":"2026-04-14T04:06:45.983453Z","end":"2026-04-14T04:06:46.143358Z","steps":["trace[1799401452] 'read index received' (duration: 57.717696ms)","trace[1799401452] 'applied index is now lower than readState.Index' (duration: 102.185639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:06:46.14365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.17092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-7wpb9\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-14T04:06:46.143766Z","caller":"traceutil/trace.go:171","msg":"trace[577161335] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-7wpb9; range_end:; response_count:1; response_revision:12; }","duration":"160.298712ms","start":"2026-04-14T04:06:45.98345Z","end":"2026-04-14T04:06:46.143748Z","steps":["trace[577161335] 'agreement among raft nodes before linearized reading' (duration: 160.030167ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.193675Z","caller":"traceutil/trace.go:171","msg":"trace[2044987840] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"209.881843ms","start":"2026-04-14T04:06:45.983768Z","end":"2026-04-14T04:06:46.19365Z","steps":["trace[2044987840] 'process raft request' (duration: 209.396054ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.193884Z","caller":"traceutil/trace.go:171","msg":"trace[1105601639] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"209.984055ms","start":"2026-04-14T04:06:45.983872Z","end":"2026-04-14T04:06:46.193856Z","steps":["trace[1105601639] 'process raft request' (duration: 209.520256ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.194971Z","caller":"traceutil/trace.go:171","msg":"trace[839814243] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"210.882492ms","start":"2026-04-14T04:06:45.984037Z","end":"2026-04-14T04:06:46.19492Z","steps":["trace[839814243] 'process raft request' (duration: 210.534505ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.19504Z","caller":"traceutil/trace.go:171","msg":"trace[228895565] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"211.117367ms","start":"2026-04-14T04:06:45.983904Z","end":"2026-04-14T04:06:46.195021Z","steps":["trace[228895565] 'process raft request' (duration: 210.068797ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.19519Z","caller":"traceutil/trace.go:171","msg":"trace[804987168] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"211.223678ms","start":"2026-04-14T04:06:45.983931Z","end":"2026-04-14T04:06:46.195155Z","steps":["trace[804987168] 'process raft request' (duration: 210.492105ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.195175Z","caller":"traceutil/trace.go:171","msg":"trace[707260738] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"212.997192ms","start":"2026-04-14T04:06:45.982125Z","end":"2026-04-14T04:06:46.195122Z","steps":["trace[707260738] 'process raft request' (duration: 212.38428ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.195286Z","caller":"traceutil/trace.go:171","msg":"trace[284796847] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"210.299211ms","start":"2026-04-14T04:06:45.984976Z","end":"2026-04-14T04:06:46.195275Z","steps":["trace[284796847] 'process raft request' (duration: 209.701439ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.194967Z","caller":"traceutil/trace.go:171","msg":"trace[1126315249] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"210.989114ms","start":"2026-04-14T04:06:45.983963Z","end":"2026-04-14T04:06:46.194952Z","steps":["trace[1126315249] 'process raft request' (duration: 210.520876ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.195435Z","caller":"traceutil/trace.go:171","msg":"trace[332312842] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"211.361201ms","start":"2026-04-14T04:06:45.984065Z","end":"2026-04-14T04:06:46.195427Z","steps":["trace[332312842] 'process raft request' (duration: 210.526426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:46.195863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.588945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-14T04:06:46.195914Z","caller":"traceutil/trace.go:171","msg":"trace[1597582431] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"160.685007ms","start":"2026-04-14T04:06:46.035215Z","end":"2026-04-14T04:06:46.1959Z","steps":["trace[1597582431] 'agreement among raft nodes before linearized reading' (duration: 160.570455ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.361794Z","caller":"traceutil/trace.go:171","msg":"trace[1932743182] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"163.432289ms","start":"2026-04-14T04:06:46.198332Z","end":"2026-04-14T04:06:46.361764Z","steps":["trace[1932743182] 'process raft request' (duration: 96.834038ms)","trace[1932743182] 'compare' (duration: 66.443258ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:06:46.361855Z","caller":"traceutil/trace.go:171","msg":"trace[1066486658] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"161.383351ms","start":"2026-04-14T04:06:46.200418Z","end":"2026-04-14T04:06:46.361801Z","steps":["trace[1066486658] 'process raft request' (duration: 161.296939ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.361982Z","caller":"traceutil/trace.go:171","msg":"trace[344651154] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"159.113508ms","start":"2026-04-14T04:06:46.202857Z","end":"2026-04-14T04:06:46.361971Z","steps":["trace[344651154] 'process raft request' (duration: 159.011756ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.362028Z","caller":"traceutil/trace.go:171","msg":"trace[2103551666] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"159.513236ms","start":"2026-04-14T04:06:46.202451Z","end":"2026-04-14T04:06:46.361964Z","steps":["trace[2103551666] 'process raft request' (duration: 159.368293ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.362066Z","caller":"traceutil/trace.go:171","msg":"trace[1096712887] linearizableReadLoop","detail":"{readStateIndex:31; appliedIndex:25; }","duration":"152.176207ms","start":"2026-04-14T04:06:46.209881Z","end":"2026-04-14T04:06:46.362057Z","steps":["trace[1096712887] 'read index received' (duration: 85.318982ms)","trace[1096712887] 'applied index is now lower than readState.Index' (duration: 66.856055ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:06:46.362083Z","caller":"traceutil/trace.go:171","msg":"trace[1219686312] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"159.678499ms","start":"2026-04-14T04:06:46.202389Z","end":"2026-04-14T04:06:46.362067Z","steps":["trace[1219686312] 'process raft request' (duration: 159.399683ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.362204Z","caller":"traceutil/trace.go:171","msg":"trace[290929898] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"147.875567ms","start":"2026-04-14T04:06:46.214089Z","end":"2026-04-14T04:06:46.361964Z","steps":["trace[290929898] 'process raft request' (duration: 147.838106ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.362048Z","caller":"traceutil/trace.go:171","msg":"trace[1994666931] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"161.288559ms","start":"2026-04-14T04:06:46.200749Z","end":"2026-04-14T04:06:46.362038Z","steps":["trace[1994666931] 'process raft request' (duration: 161.000104ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.362208Z","caller":"traceutil/trace.go:171","msg":"trace[49848070] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"148.793794ms","start":"2026-04-14T04:06:46.213406Z","end":"2026-04-14T04:06:46.3622Z","steps":["trace[49848070] 'process raft request' (duration: 148.492168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:46.362406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.168795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:06:46.362448Z","caller":"traceutil/trace.go:171","msg":"trace[112462214] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:29; }","duration":"162.238766ms","start":"2026-04-14T04:06:46.200196Z","end":"2026-04-14T04:06:46.362435Z","steps":["trace[112462214] 'agreement among raft nodes before linearized reading' (duration: 162.125054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:46.362467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.980704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:06:46.362544Z","caller":"traceutil/trace.go:171","msg":"trace[991645373] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:29; }","duration":"111.099836ms","start":"2026-04-14T04:06:46.251423Z","end":"2026-04-14T04:06:46.362523Z","steps":["trace[991645373] 'agreement among raft nodes before linearized reading' (duration: 110.906903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:46.36259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.174223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-14T04:06:46.362667Z","caller":"traceutil/trace.go:171","msg":"trace[1977301185] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:29; }","duration":"164.258224ms","start":"2026-04-14T04:06:46.198389Z","end":"2026-04-14T04:06:46.362647Z","steps":["trace[1977301185] 'agreement among raft nodes before linearized reading' (duration: 164.113722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:46.362792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.31144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:06:46.362833Z","caller":"traceutil/trace.go:171","msg":"trace[45317863] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:29; }","duration":"111.386042ms","start":"2026-04-14T04:06:46.251437Z","end":"2026-04-14T04:06:46.362823Z","steps":["trace[45317863] 'agreement among raft nodes before linearized reading' (duration: 111.29573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:06:46.636637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.595199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-14T04:06:46.636808Z","caller":"traceutil/trace.go:171","msg":"trace[33335578] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"208.115058ms","start":"2026-04-14T04:06:46.428668Z","end":"2026-04-14T04:06:46.636783Z","steps":["trace[33335578] 'process raft request' (duration: 42.263604ms)","trace[33335578] 'compare' (duration: 165.361485ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:06:46.636991Z","caller":"traceutil/trace.go:171","msg":"trace[1651066482] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"206.999698ms","start":"2026-04-14T04:06:46.429972Z","end":"2026-04-14T04:06:46.636972Z","steps":["trace[1651066482] 'read index received' (duration: 1.745753ms)","trace[1651066482] 'applied index is now lower than readState.Index' (duration: 205.251815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:06:46.637163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.171751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:06:46.637186Z","caller":"traceutil/trace.go:171","msg":"trace[1074399790] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:73; }","duration":"207.228591ms","start":"2026-04-14T04:06:46.42995Z","end":"2026-04-14T04:06:46.637178Z","steps":["trace[1074399790] 'agreement among raft nodes before linearized reading' (duration: 207.117859ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:06:46.636982Z","caller":"traceutil/trace.go:171","msg":"trace[1114214662] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"202.731417ms","start":"2026-04-14T04:06:46.434122Z","end":"2026-04-14T04:06:46.636854Z","steps":["trace[1114214662] 'process raft request' (duration: 202.631906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:03.178379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.070999ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T04:07:05.87556Z","caller":"traceutil/trace.go:171","msg":"trace[1234897006] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"243.335042ms","start":"2026-04-14T04:07:05.632201Z","end":"2026-04-14T04:07:05.875536Z","steps":["trace[1234897006] 'process raft request' (duration: 243.18787ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:07:05.881889Z","caller":"traceutil/trace.go:171","msg":"trace[1601330728] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"247.408851ms","start":"2026-04-14T04:07:05.634463Z","end":"2026-04-14T04:07:05.881871Z","steps":["trace[1601330728] 'process raft request' (duration: 247.339699ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:07:06.254773Z","caller":"traceutil/trace.go:171","msg":"trace[1003627930] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"163.579087ms","start":"2026-04-14T04:07:06.091168Z","end":"2026-04-14T04:07:06.254747Z","steps":["trace[1003627930] 'process raft request' (duration: 114.863292ms)","trace[1003627930] 'compare' (duration: 48.560262ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:07:10.897035Z","caller":"traceutil/trace.go:171","msg":"trace[890335269] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"395.283587ms","start":"2026-04-14T04:07:10.501705Z","end":"2026-04-14T04:07:10.896989Z","steps":["trace[890335269] 'read index received' (duration: 395.052663ms)","trace[890335269] 'applied index is now lower than readState.Index' (duration: 229.774µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:07:10.897076Z","caller":"traceutil/trace.go:171","msg":"trace[390623042] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"399.457776ms","start":"2026-04-14T04:07:10.497578Z","end":"2026-04-14T04:07:10.897036Z","steps":["trace[390623042] 'process raft request' (duration: 399.10897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:10.897282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:10.497555Z","time spent":"399.608968ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:07:10.897326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.578722ms","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-14T04:07:10.897399Z","caller":"traceutil/trace.go:171","msg":"trace[1689757410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"395.690203ms","start":"2026-04-14T04:07:10.50169Z","end":"2026-04-14T04:07:10.89738Z","steps":["trace[1689757410] 'agreement among raft nodes before linearized reading' (duration: 395.409259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:10.897451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:10.501683Z","time spent":"395.757715ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T04:07:11.186067Z","caller":"traceutil/trace.go:171","msg":"trace[2009615951] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"547.122582ms","start":"2026-04-14T04:07:10.638912Z","end":"2026-04-14T04:07:11.186034Z","steps":["trace[2009615951] 'process raft request' (duration: 546.570703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:11.186249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:10.638877Z","time spent":"547.289874ms","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:07:11.189455Z","caller":"traceutil/trace.go:171","msg":"trace[27025251] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:560; }","duration":"205.319039ms","start":"2026-04-14T04:07:10.984102Z","end":"2026-04-14T04:07:11.189421Z","steps":["trace[27025251] 'read index received' (duration: 200.869417ms)","trace[27025251] 'applied index is now lower than readState.Index' (duration: 4.448032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:07:11.189689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.554854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:07:11.189733Z","caller":"traceutil/trace.go:171","msg":"trace[1210339574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"205.672035ms","start":"2026-04-14T04:07:10.984046Z","end":"2026-04-14T04:07:11.189718Z","steps":["trace[1210339574] 'agreement among raft nodes before linearized reading' (duration: 205.503793ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:07:11.189734Z","caller":"traceutil/trace.go:171","msg":"trace[266064384] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"287.917615ms","start":"2026-04-14T04:07:10.901774Z","end":"2026-04-14T04:07:11.189691Z","steps":["trace[266064384] 'process raft request' (duration: 287.453497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:18.483022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878395521898432,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T04:07:18.808224Z","caller":"traceutil/trace.go:171","msg":"trace[1277484455] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"872.140456ms","start":"2026-04-14T04:07:17.936054Z","end":"2026-04-14T04:07:18.808195Z","steps":["trace[1277484455] 'process raft request' (duration: 871.971673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:18.808386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:17.936035Z","time spent":"872.262557ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:07:18.836292Z","caller":"traceutil/trace.go:171","msg":"trace[1268550735] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"853.884455ms","start":"2026-04-14T04:07:17.982377Z","end":"2026-04-14T04:07:18.836262Z","steps":["trace[1268550735] 'read index received' (duration: 825.71442ms)","trace[1268550735] 'applied index is now lower than readState.Index' (duration: 28.168695ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:07:18.836412Z","caller":"traceutil/trace.go:171","msg":"trace[1296787987] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"899.165922ms","start":"2026-04-14T04:07:17.937216Z","end":"2026-04-14T04:07:18.836382Z","steps":["trace[1296787987] 'process raft request' (duration: 898.887548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:18.83647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.034847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:07:18.836529Z","caller":"traceutil/trace.go:171","msg":"trace[1975214351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"854.152339ms","start":"2026-04-14T04:07:17.982359Z","end":"2026-04-14T04:07:18.836512Z","steps":["trace[1975214351] 'agreement among raft nodes before linearized reading' (duration: 854.019626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:18.836599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:17.982352Z","time spent":"854.23391ms","remote":"127.0.0.1:43958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:07:18.836535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:17.937202Z","time spent":"899.269394ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:07:23.809676Z","caller":"traceutil/trace.go:171","msg":"trace[1007439706] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"106.010496ms","start":"2026-04-14T04:07:23.703643Z","end":"2026-04-14T04:07:23.809654Z","steps":["trace[1007439706] 'process raft request' (duration: 105.766082ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:07:30.136995Z","caller":"traceutil/trace.go:171","msg":"trace[584341167] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"132.77205ms","start":"2026-04-14T04:07:30.004191Z","end":"2026-04-14T04:07:30.136963Z","steps":["trace[584341167] 'process raft request' (duration: 132.09139ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:07:33.518637Z","caller":"traceutil/trace.go:171","msg":"trace[1893898049] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"199.16525ms","start":"2026-04-14T04:07:33.319438Z","end":"2026-04-14T04:07:33.518603Z","steps":["trace[1893898049] 'process raft request' (duration: 198.957047ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:07:35.654785Z","caller":"traceutil/trace.go:171","msg":"trace[1771180581] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"127.814189ms","start":"2026-04-14T04:07:35.526945Z","end":"2026-04-14T04:07:35.654759Z","steps":["trace[1771180581] 'process raft request' (duration: 127.668997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:37.484036Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878395521898823,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T04:07:37.907966Z","caller":"traceutil/trace.go:171","msg":"trace[697547879] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"925.662889ms","start":"2026-04-14T04:07:36.982273Z","end":"2026-04-14T04:07:37.907936Z","steps":["trace[697547879] 'process raft request' (duration: 925.471816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:37.908156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:36.982239Z","time spent":"925.821071ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:07:38.001496Z","caller":"traceutil/trace.go:171","msg":"trace[204029070] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"1.016310912s","start":"2026-04-14T04:07:36.985157Z","end":"2026-04-14T04:07:38.001468Z","steps":["trace[204029070] 'process raft request' (duration: 1.016074929s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:38.001711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:36.985142Z","time spent":"1.016452305s","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:07:38.0018Z","caller":"traceutil/trace.go:171","msg":"trace[1764648982] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"718.301692ms","start":"2026-04-14T04:07:37.283472Z","end":"2026-04-14T04:07:38.001774Z","steps":["trace[1764648982] 'process raft request' (duration: 717.952817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:38.001995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:37.283431Z","time spent":"718.467154ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:07:38.002077Z","caller":"traceutil/trace.go:171","msg":"trace[1091275257] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:768; }","duration":"1.019067572s","start":"2026-04-14T04:07:36.98297Z","end":"2026-04-14T04:07:38.002038Z","steps":["trace[1091275257] 'read index received' (duration: 924.775435ms)","trace[1091275257] 'applied index is now lower than readState.Index' (duration: 94.290377ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:07:38.002159Z","caller":"traceutil/trace.go:171","msg":"trace[1815967239] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"483.256812ms","start":"2026-04-14T04:07:37.518869Z","end":"2026-04-14T04:07:38.002126Z","steps":["trace[1815967239] 'process raft request' (duration: 482.819265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:38.002249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019273755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:07:38.002297Z","caller":"traceutil/trace.go:171","msg":"trace[1210272299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"1.019352157s","start":"2026-04-14T04:07:36.982933Z","end":"2026-04-14T04:07:38.002285Z","steps":["trace[1210272299] 'agreement among raft nodes before linearized reading' (duration: 1.019279156s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:38.00232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:37.518837Z","time spent":"483.400214ms","remote":"127.0.0.1:44190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:07:38.002337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:36.982921Z","time spent":"1.019410158s","remote":"127.0.0.1:43958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:07:38.002492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.450377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-14T04:07:38.00257Z","caller":"traceutil/trace.go:171","msg":"trace[1355094491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:745; }","duration":"956.61227ms","start":"2026-04-14T04:07:37.045938Z","end":"2026-04-14T04:07:38.00255Z","steps":["trace[1355094491] 'agreement among raft nodes before linearized reading' (duration: 956.332006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:38.002577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.807497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-14T04:07:38.002623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:37.045907Z","time spent":"956.701221ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T04:07:38.003535Z","caller":"traceutil/trace.go:171","msg":"trace[1116869442] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:745; }","duration":"212.897858ms","start":"2026-04-14T04:07:37.789713Z","end":"2026-04-14T04:07:38.002611Z","steps":["trace[1116869442] 'agreement among raft nodes before linearized reading' (duration: 212.737155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:38.003739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.072352ms","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-14T04:07:38.0038Z","caller":"traceutil/trace.go:171","msg":"trace[1146629370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:745; }","duration":"344.192114ms","start":"2026-04-14T04:07:37.659592Z","end":"2026-04-14T04:07:38.003784Z","steps":["trace[1146629370] 'agreement among raft nodes before linearized reading' (duration: 342.979876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:07:38.003835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:07:37.659564Z","time spent":"344.263764ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T04:07:45.559708Z","caller":"traceutil/trace.go:171","msg":"trace[1343542388] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"202.033897ms","start":"2026-04-14T04:07:45.357653Z","end":"2026-04-14T04:07:45.559687Z","steps":["trace[1343542388] 'process raft request' (duration: 201.920505ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:21.233425Z","caller":"traceutil/trace.go:171","msg":"trace[1878602148] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"111.767461ms","start":"2026-04-14T04:08:21.121633Z","end":"2026-04-14T04:08:21.2334Z","steps":["trace[1878602148] 'process raft request' (duration: 111.63333ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:36.504623Z","caller":"traceutil/trace.go:171","msg":"trace[134994641] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"153.353093ms","start":"2026-04-14T04:08:36.351246Z","end":"2026-04-14T04:08:36.504599Z","steps":["trace[134994641] 'read index received' (duration: 101.602575ms)","trace[134994641] 'applied index is now lower than readState.Index' (duration: 51.749098ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:08:36.504771Z","caller":"traceutil/trace.go:171","msg":"trace[128357512] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"154.260884ms","start":"2026-04-14T04:08:36.350478Z","end":"2026-04-14T04:08:36.504739Z","steps":["trace[128357512] 'process raft request' (duration: 102.456575ms)","trace[128357512] 'compare' (duration: 51.554936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:08:36.504818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.549746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T04:08:36.504854Z","caller":"traceutil/trace.go:171","msg":"trace[1691577994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1068; }","duration":"153.642026ms","start":"2026-04-14T04:08:36.3512Z","end":"2026-04-14T04:08:36.504842Z","steps":["trace[1691577994] 'agreement among raft nodes before linearized reading' (duration: 153.492714ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:36.940792Z","caller":"traceutil/trace.go:171","msg":"trace[945085269] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"138.550988ms","start":"2026-04-14T04:08:36.802213Z","end":"2026-04-14T04:08:36.940764Z","steps":["trace[945085269] 'process raft request' (duration: 132.025316ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:09:10.824007Z","caller":"traceutil/trace.go:171","msg":"trace[834703479] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1227; }","duration":"118.976116ms","start":"2026-04-14T04:09:10.705011Z","end":"2026-04-14T04:09:10.823988Z","steps":["trace[834703479] 'read index received' (duration: 118.761563ms)","trace[834703479] 'applied index is now lower than readState.Index' (duration: 213.753µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:09:10.824123Z","caller":"traceutil/trace.go:171","msg":"trace[176117418] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"145.672056ms","start":"2026-04-14T04:09:10.678422Z","end":"2026-04-14T04:09:10.824094Z","steps":["trace[176117418] 'process raft request' (duration: 145.425503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:09:10.824202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.148068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T04:09:10.824232Z","caller":"traceutil/trace.go:171","msg":"trace[610902123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1181; }","duration":"119.244199ms","start":"2026-04-14T04:09:10.704978Z","end":"2026-04-14T04:09:10.824222Z","steps":["trace[610902123] 'agreement among raft nodes before linearized reading' (duration: 119.099068ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:09:24.502256Z","caller":"traceutil/trace.go:171","msg":"trace[1993558993] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"141.520614ms","start":"2026-04-14T04:09:24.360713Z","end":"2026-04-14T04:09:24.502234Z","steps":["trace[1993558993] 'process raft request' (duration: 141.424033ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:09:24.503347Z","caller":"traceutil/trace.go:171","msg":"trace[957283383] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"137.973712ms","start":"2026-04-14T04:09:24.365339Z","end":"2026-04-14T04:09:24.503313Z","steps":["trace[957283383] 'process raft request' (duration: 137.723219ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:09:25.995982Z","caller":"traceutil/trace.go:171","msg":"trace[1280623962] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"117.56112ms","start":"2026-04-14T04:09:25.878376Z","end":"2026-04-14T04:09:25.995937Z","steps":["trace[1280623962] 'process raft request' (duration: 117.257636ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:09:39.743347Z","caller":"traceutil/trace.go:171","msg":"trace[2039295701] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"102.438518ms","start":"2026-04-14T04:09:39.640881Z","end":"2026-04-14T04:09:39.74332Z","steps":["trace[2039295701] 'process raft request' (duration: 102.059513ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:09:39.941313Z","caller":"traceutil/trace.go:171","msg":"trace[1838669325] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"192.432071ms","start":"2026-04-14T04:09:39.748832Z","end":"2026-04-14T04:09:39.941264Z","steps":["trace[1838669325] 'process raft request' (duration: 98.31855ms)","trace[1838669325] 'compare' (duration: 93.722116ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:09:39.941885Z","caller":"traceutil/trace.go:171","msg":"trace[1992648076] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"171.998702ms","start":"2026-04-14T04:09:39.769822Z","end":"2026-04-14T04:09:39.94182Z","steps":["trace[1992648076] 'process raft request' (duration: 171.83246ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:04.452916Z","caller":"traceutil/trace.go:171","msg":"trace[285268909] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2276; }","duration":"336.061426ms","start":"2026-04-14T04:10:04.116829Z","end":"2026-04-14T04:10:04.45289Z","steps":["trace[285268909] 'read index received' (duration: 335.956214ms)","trace[285268909] 'applied index is now lower than readState.Index' (duration: 104.002µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:10:04.453086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.244648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44d2e892-0667-42d6-950c-cefbfe11e163\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-14T04:10:04.453111Z","caller":"traceutil/trace.go:171","msg":"trace[1012956430] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44d2e892-0667-42d6-950c-cefbfe11e163; range_end:; response_count:1; response_revision:2185; }","duration":"336.286058ms","start":"2026-04-14T04:10:04.116817Z","end":"2026-04-14T04:10:04.453103Z","steps":["trace[1012956430] 'agreement among raft nodes before linearized reading' (duration: 336.180907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:04.453134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:04.116783Z","time spent":"336.344488ms","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44d2e892-0667-42d6-950c-cefbfe11e163\" "} {"level":"info","ts":"2026-04-14T04:10:04.453187Z","caller":"traceutil/trace.go:171","msg":"trace[1529631484] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"359.974771ms","start":"2026-04-14T04:10:04.093167Z","end":"2026-04-14T04:10:04.453141Z","steps":["trace[1529631484] 'process raft request' (duration: 359.604806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:04.453564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:04.09313Z","time spent":"360.161033ms","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3722,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:10:04.466612Z","caller":"traceutil/trace.go:171","msg":"trace[597981945] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"230.041853ms","start":"2026-04-14T04:10:04.236552Z","end":"2026-04-14T04:10:04.466594Z","steps":["trace[597981945] 'process raft request' (duration: 229.84158ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:19.478458Z","caller":"traceutil/trace.go:171","msg":"trace[1184171122] linearizableReadLoop","detail":"{readStateIndex:2426; appliedIndex:2425; }","duration":"136.02226ms","start":"2026-04-14T04:10:19.342418Z","end":"2026-04-14T04:10:19.47844Z","steps":["trace[1184171122] 'read index received' (duration: 135.856604ms)","trace[1184171122] 'applied index is now lower than readState.Index' (duration: 165.136µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:10:19.478547Z","caller":"traceutil/trace.go:171","msg":"trace[1684493344] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"160.707764ms","start":"2026-04-14T04:10:19.317821Z","end":"2026-04-14T04:10:19.478529Z","steps":["trace[1684493344] 'process raft request' (duration: 160.468407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:19.478624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.197535ms","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-14T04:10:19.47865Z","caller":"traceutil/trace.go:171","msg":"trace[1181182797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2331; }","duration":"136.272287ms","start":"2026-04-14T04:10:19.342371Z","end":"2026-04-14T04:10:19.478643Z","steps":["trace[1181182797] 'agreement among raft nodes before linearized reading' (duration: 136.143933ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:33.502025Z","caller":"traceutil/trace.go:171","msg":"trace[339846593] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"234.062263ms","start":"2026-04-14T04:10:33.267919Z","end":"2026-04-14T04:10:33.501982Z","steps":["trace[339846593] 'process raft request' (duration: 180.193842ms)","trace[339846593] 'compare' (duration: 53.607023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:10:33.831117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.859579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-14T04:10:33.831227Z","caller":"traceutil/trace.go:171","msg":"trace[148010822] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2420; }","duration":"100.987203ms","start":"2026-04-14T04:10:33.730216Z","end":"2026-04-14T04:10:33.831203Z","steps":["trace[148010822] 'range keys from in-memory index tree' (duration: 100.638233ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:34.225112Z","caller":"traceutil/trace.go:171","msg":"trace[1876341522] transaction","detail":"{read_only:false; response_revision:2422; number_of_response:1; }","duration":"139.720559ms","start":"2026-04-14T04:10:34.085359Z","end":"2026-04-14T04:10:34.22508Z","steps":["trace[1876341522] 'process raft request' (duration: 139.492212ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:34.990924Z","caller":"traceutil/trace.go:171","msg":"trace[1571947697] linearizableReadLoop","detail":"{readStateIndex:2522; appliedIndex:2521; }","duration":"153.00809ms","start":"2026-04-14T04:10:34.8379Z","end":"2026-04-14T04:10:34.990909Z","steps":["trace[1571947697] 'read index received' (duration: 152.852886ms)","trace[1571947697] 'applied index is now lower than readState.Index' (duration: 154.624µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:10:34.99112Z","caller":"traceutil/trace.go:171","msg":"trace[1508465591] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"431.734507ms","start":"2026-04-14T04:10:34.559378Z","end":"2026-04-14T04:10:34.991113Z","steps":["trace[1508465591] 'process raft request' (duration: 431.428838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:34.991192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:34.559353Z","time spent":"431.788109ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:10:34.991359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.467463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-14T04:10:34.991384Z","caller":"traceutil/trace.go:171","msg":"trace[758155279] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2423; }","duration":"153.556916ms","start":"2026-04-14T04:10:34.837819Z","end":"2026-04-14T04:10:34.991376Z","steps":["trace[758155279] 'agreement among raft nodes before linearized reading' (duration: 153.480704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:35.416073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.068355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:10:35.416411Z","caller":"traceutil/trace.go:171","msg":"trace[362934875] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"420.523336ms","start":"2026-04-14T04:10:34.995864Z","end":"2026-04-14T04:10:35.416387Z","steps":["trace[362934875] 'process raft request' (duration: 199.05387ms)","trace[362934875] 'compare' (duration: 220.884539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:10:35.416561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:34.995846Z","time spent":"420.62048ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:12:03.701376Z","caller":"traceutil/trace.go:171","msg":"trace[145783175] transaction","detail":"{read_only:false; response_revision:2983; number_of_response:1; }","duration":"196.315569ms","start":"2026-04-14T04:12:03.505036Z","end":"2026-04-14T04:12:03.701351Z","steps":["trace[145783175] 'process raft request' (duration: 196.165086ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:14:31.488334Z","caller":"traceutil/trace.go:171","msg":"trace[1526968318] transaction","detail":"{read_only:false; response_revision:3930; number_of_response:1; }","duration":"104.552785ms","start":"2026-04-14T04:14:31.383755Z","end":"2026-04-14T04:14:31.488308Z","steps":["trace[1526968318] 'process raft request' (duration: 104.413273ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:15.452389Z","caller":"traceutil/trace.go:171","msg":"trace[675826946] linearizableReadLoop","detail":"{readStateIndex:4477; appliedIndex:4476; }","duration":"145.263443ms","start":"2026-04-14T04:15:15.3071Z","end":"2026-04-14T04:15:15.452363Z","steps":["trace[675826946] 'read index received' (duration: 143.903482ms)","trace[675826946] 'applied index is now lower than readState.Index' (duration: 1.358981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:15.452692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.575498ms","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-14T04:15:15.452735Z","caller":"traceutil/trace.go:171","msg":"trace[34260255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4314; }","duration":"145.650689ms","start":"2026-04-14T04:15:15.307074Z","end":"2026-04-14T04:15:15.452725Z","steps":["trace[34260255] 'agreement among raft nodes before linearized reading' (duration: 145.381275ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:15.452847Z","caller":"traceutil/trace.go:171","msg":"trace[210425789] transaction","detail":"{read_only:false; response_revision:4314; number_of_response:1; }","duration":"206.778564ms","start":"2026-04-14T04:15:15.245941Z","end":"2026-04-14T04:15:15.45272Z","steps":["trace[210425789] 'process raft request' (duration: 205.030938ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:22.813121Z","caller":"traceutil/trace.go:171","msg":"trace[217436458] transaction","detail":"{read_only:false; response_revision:4361; number_of_response:1; }","duration":"146.145173ms","start":"2026-04-14T04:15:22.666937Z","end":"2026-04-14T04:15:22.813082Z","steps":["trace[217436458] 'process raft request' (duration: 97.415862ms)","trace[217436458] 'compare' (duration: 48.490068ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:23.579578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.370099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:23.579663Z","caller":"traceutil/trace.go:171","msg":"trace[1806270690] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4367; }","duration":"222.496202ms","start":"2026-04-14T04:15:23.357148Z","end":"2026-04-14T04:15:23.579644Z","steps":["trace[1806270690] 'range keys from in-memory index tree' (duration: 222.282908ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:25.015223Z","caller":"traceutil/trace.go:171","msg":"trace[324301023] transaction","detail":"{read_only:false; response_revision:4376; number_of_response:1; }","duration":"100.842071ms","start":"2026-04-14T04:15:24.914358Z","end":"2026-04-14T04:15:25.0152Z","steps":["trace[324301023] 'process raft request' (duration: 100.674669ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:25.048994Z","caller":"traceutil/trace.go:171","msg":"trace[1578922767] linearizableReadLoop","detail":"{readStateIndex:4542; appliedIndex:4540; }","duration":"108.71323ms","start":"2026-04-14T04:15:24.940258Z","end":"2026-04-14T04:15:25.048971Z","steps":["trace[1578922767] 'read index received' (duration: 74.755765ms)","trace[1578922767] 'applied index is now lower than readState.Index' (duration: 33.956265ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:25.049112Z","caller":"traceutil/trace.go:171","msg":"trace[625786298] transaction","detail":"{read_only:false; response_revision:4377; number_of_response:1; }","duration":"121.810049ms","start":"2026-04-14T04:15:24.927288Z","end":"2026-04-14T04:15:25.049098Z","steps":["trace[625786298] 'process raft request' (duration: 121.557816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:25.049786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.514052ms","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-14T04:15:25.049901Z","caller":"traceutil/trace.go:171","msg":"trace[1110553949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4377; }","duration":"109.663934ms","start":"2026-04-14T04:15:24.940217Z","end":"2026-04-14T04:15:25.049881Z","steps":["trace[1110553949] 'agreement among raft nodes before linearized reading' (duration: 109.462961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:26.665654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.136718ms","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-14T04:15:26.66572Z","caller":"traceutil/trace.go:171","msg":"trace[1692401033] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4384; }","duration":"195.25388ms","start":"2026-04-14T04:15:26.470449Z","end":"2026-04-14T04:15:26.665703Z","steps":["trace[1692401033] 'count revisions from in-memory index tree' (duration: 195.040156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.138322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.760468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:15:27.138479Z","caller":"traceutil/trace.go:171","msg":"trace[494290037] transaction","detail":"{read_only:false; response_revision:4387; number_of_response:1; }","duration":"253.514172ms","start":"2026-04-14T04:15:26.884934Z","end":"2026-04-14T04:15:27.138448Z","steps":["trace[494290037] 'process raft request' (duration: 113.549502ms)","trace[494290037] 'compare' (duration: 139.666455ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:27.138553Z","caller":"traceutil/trace.go:171","msg":"trace[1019303655] linearizableReadLoop","detail":"{readStateIndex:4552; appliedIndex:4551; }","duration":"156.356119ms","start":"2026-04-14T04:15:26.982159Z","end":"2026-04-14T04:15:27.138515Z","steps":["trace[1019303655] 'read index received' (duration: 16.321397ms)","trace[1019303655] 'applied index is now lower than readState.Index' (duration: 140.029102ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:27.138589Z","caller":"traceutil/trace.go:171","msg":"trace[1739293199] transaction","detail":"{read_only:false; response_revision:4388; number_of_response:1; }","duration":"117.991128ms","start":"2026-04-14T04:15:27.020517Z","end":"2026-04-14T04:15:27.138508Z","steps":["trace[1739293199] 'process raft request' (duration: 117.910977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.138743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.559732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:27.138783Z","caller":"traceutil/trace.go:171","msg":"trace[2111838413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4389; }","duration":"156.651453ms","start":"2026-04-14T04:15:26.982121Z","end":"2026-04-14T04:15:27.138772Z","steps":["trace[2111838413] 'agreement among raft nodes before linearized reading' (duration: 156.553801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.369517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.138666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T04:15:27.36958Z","caller":"traceutil/trace.go:171","msg":"trace[1665347323] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4391; }","duration":"125.244167ms","start":"2026-04-14T04:15:27.244319Z","end":"2026-04-14T04:15:27.369563Z","steps":["trace[1665347323] 'count revisions from in-memory index tree' (duration: 125.044504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.369672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.5815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T04:15:27.369798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.504594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:27.369864Z","caller":"traceutil/trace.go:171","msg":"trace[874833359] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:4391; }","duration":"109.777073ms","start":"2026-04-14T04:15:27.260044Z","end":"2026-04-14T04:15:27.369821Z","steps":["trace[874833359] 'range keys from in-memory index tree' (duration: 109.496698ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:27.36989Z","caller":"traceutil/trace.go:171","msg":"trace[1882555865] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:4391; }","duration":"108.645595ms","start":"2026-04-14T04:15:27.261228Z","end":"2026-04-14T04:15:27.369874Z","steps":["trace[1882555865] 'range keys from in-memory index tree' (duration: 108.398192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.369631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.485253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6084"} {"level":"info","ts":"2026-04-14T04:15:27.370241Z","caller":"traceutil/trace.go:171","msg":"trace[2015436236] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:4391; }","duration":"109.131323ms","start":"2026-04-14T04:15:27.261092Z","end":"2026-04-14T04:15:27.370223Z","steps":["trace[2015436236] 'range keys from in-memory index tree' (duration: 108.311841ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:32.153867Z","caller":"traceutil/trace.go:171","msg":"trace[379686597] transaction","detail":"{read_only:false; response_revision:4460; number_of_response:1; }","duration":"154.140015ms","start":"2026-04-14T04:15:31.999667Z","end":"2026-04-14T04:15:32.153807Z","steps":["trace[379686597] 'process raft request' (duration: 113.601403ms)","trace[379686597] 'compare' (duration: 40.37128ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:38.950639Z","caller":"traceutil/trace.go:171","msg":"trace[428767440] linearizableReadLoop","detail":"{readStateIndex:4709; appliedIndex:4708; }","duration":"142.444809ms","start":"2026-04-14T04:15:38.808172Z","end":"2026-04-14T04:15:38.950617Z","steps":["trace[428767440] 'read index received' (duration: 142.347387ms)","trace[428767440] 'applied index is now lower than readState.Index' (duration: 96.622µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:38.950749Z","caller":"traceutil/trace.go:171","msg":"trace[508720585] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"246.874136ms","start":"2026-04-14T04:15:38.703842Z","end":"2026-04-14T04:15:38.950716Z","steps":["trace[508720585] 'process raft request' (duration: 246.624093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:38.950849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.659292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-14T04:15:38.95089Z","caller":"traceutil/trace.go:171","msg":"trace[618155835] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4538; }","duration":"142.738503ms","start":"2026-04-14T04:15:38.808142Z","end":"2026-04-14T04:15:38.950881Z","steps":["trace[618155835] 'agreement among raft nodes before linearized reading' (duration: 142.58495ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:39.071675Z","caller":"traceutil/trace.go:171","msg":"trace[1852552829] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"155.773899ms","start":"2026-04-14T04:15:38.915867Z","end":"2026-04-14T04:15:39.071641Z","steps":["trace[1852552829] 'process raft request' (duration: 103.8951ms)","trace[1852552829] 'compare' (duration: 51.707226ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:39.071738Z","caller":"traceutil/trace.go:171","msg":"trace[390082799] linearizableReadLoop","detail":"{readStateIndex:4710; appliedIndex:4709; }","duration":"121.001227ms","start":"2026-04-14T04:15:38.950707Z","end":"2026-04-14T04:15:39.071708Z","steps":["trace[390082799] 'read index received' (duration: 68.999266ms)","trace[390082799] 'applied index is now lower than readState.Index' (duration: 51.999811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:39.071982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.619447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-data-valkey-node-1.18a61df34c30ea19\" ","response":"range_response_count:1 size:764"} {"level":"info","ts":"2026-04-14T04:15:39.072066Z","caller":"traceutil/trace.go:171","msg":"trace[1226313673] range","detail":"{range_begin:/registry/events/openstack/valkey-data-valkey-node-1.18a61df34c30ea19; range_end:; response_count:1; response_revision:4539; }","duration":"227.739629ms","start":"2026-04-14T04:15:38.844303Z","end":"2026-04-14T04:15:39.072043Z","steps":["trace[1226313673] 'agreement among raft nodes before linearized reading' (duration: 227.510455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:39.072062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.041138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-14T04:15:39.072126Z","caller":"traceutil/trace.go:171","msg":"trace[2024678352] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4539; }","duration":"117.143939ms","start":"2026-04-14T04:15:38.954963Z","end":"2026-04-14T04:15:39.072107Z","steps":["trace[2024678352] 'agreement among raft nodes before linearized reading' (duration: 116.937786ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:39.469398Z","caller":"traceutil/trace.go:171","msg":"trace[1161668624] transaction","detail":"{read_only:false; response_revision:4545; number_of_response:1; }","duration":"105.99691ms","start":"2026-04-14T04:15:39.363377Z","end":"2026-04-14T04:15:39.469374Z","steps":["trace[1161668624] 'process raft request' (duration: 104.216393ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:41.66882Z","caller":"traceutil/trace.go:171","msg":"trace[426798261] linearizableReadLoop","detail":"{readStateIndex:4732; appliedIndex:4731; }","duration":"163.268526ms","start":"2026-04-14T04:15:41.505528Z","end":"2026-04-14T04:15:41.668796Z","steps":["trace[426798261] 'read index received' (duration: 124.489175ms)","trace[426798261] 'applied index is now lower than readState.Index' (duration: 38.778491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:41.669029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.482199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T04:15:41.669064Z","caller":"traceutil/trace.go:171","msg":"trace[1176356628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4561; }","duration":"163.572681ms","start":"2026-04-14T04:15:41.505481Z","end":"2026-04-14T04:15:41.669054Z","steps":["trace[1176356628] 'agreement among raft nodes before linearized reading' (duration: 163.410869ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:41.668939Z","caller":"traceutil/trace.go:171","msg":"trace[1174679325] transaction","detail":"{read_only:false; response_revision:4561; number_of_response:1; }","duration":"191.865165ms","start":"2026-04-14T04:15:41.477039Z","end":"2026-04-14T04:15:41.668904Z","steps":["trace[1174679325] 'process raft request' (duration: 152.966122ms)","trace[1174679325] 'compare' (duration: 38.668859ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:44.901746Z","caller":"traceutil/trace.go:171","msg":"trace[208516622] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"110.478752ms","start":"2026-04-14T04:15:44.791242Z","end":"2026-04-14T04:15:44.901721Z","steps":["trace[208516622] 'process raft request' (duration: 110.329669ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:57.737977Z","caller":"traceutil/trace.go:171","msg":"trace[1406907332] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"116.12676ms","start":"2026-04-14T04:15:57.62182Z","end":"2026-04-14T04:15:57.737947Z","steps":["trace[1406907332] 'process raft request' (duration: 36.370329ms)","trace[1406907332] 'compare' (duration: 79.619849ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:59.264157Z","caller":"traceutil/trace.go:171","msg":"trace[1920095388] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"109.919346ms","start":"2026-04-14T04:15:59.154197Z","end":"2026-04-14T04:15:59.264117Z","steps":["trace[1920095388] 'process raft request' (duration: 109.310677ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:01.859909Z","caller":"traceutil/trace.go:171","msg":"trace[1844317148] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"101.906356ms","start":"2026-04-14T04:16:01.75798Z","end":"2026-04-14T04:16:01.859886Z","steps":["trace[1844317148] 'process raft request' (duration: 101.671102ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:02.064528Z","caller":"traceutil/trace.go:171","msg":"trace[1476298465] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"123.612616ms","start":"2026-04-14T04:16:01.94089Z","end":"2026-04-14T04:16:02.064502Z","steps":["trace[1476298465] 'process raft request' (duration: 45.580233ms)","trace[1476298465] 'compare' (duration: 77.533675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:12.13225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.371852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T04:16:12.132333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.86068ms","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-14T04:16:12.132363Z","caller":"traceutil/trace.go:171","msg":"trace[1948781577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4812; }","duration":"149.527554ms","start":"2026-04-14T04:16:11.982811Z","end":"2026-04-14T04:16:12.132339Z","steps":["trace[1948781577] 'range keys from in-memory index tree' (duration: 149.281811ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:12.132384Z","caller":"traceutil/trace.go:171","msg":"trace[1436800855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4812; }","duration":"211.95181ms","start":"2026-04-14T04:16:11.920417Z","end":"2026-04-14T04:16:12.132369Z","steps":["trace[1436800855] 'range keys from in-memory index tree' (duration: 211.656526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:12.132739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.638247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T04:16:12.132815Z","caller":"traceutil/trace.go:171","msg":"trace[965383575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4812; }","duration":"209.730088ms","start":"2026-04-14T04:16:11.923067Z","end":"2026-04-14T04:16:12.132797Z","steps":["trace[965383575] 'range keys from in-memory index tree' (duration: 209.524195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:12.133406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.951166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-p58rd\" ","response":"range_response_count:1 size:4357"} {"level":"info","ts":"2026-04-14T04:16:12.133537Z","caller":"traceutil/trace.go:171","msg":"trace[742080856] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-create-p58rd; range_end:; response_count:1; response_revision:4812; }","duration":"195.074382ms","start":"2026-04-14T04:16:11.938446Z","end":"2026-04-14T04:16:12.133521Z","steps":["trace[742080856] 'range keys from in-memory index tree' (duration: 193.846354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:12.133507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.853243ms","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-14T04:16:12.1336Z","caller":"traceutil/trace.go:171","msg":"trace[1602819800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4812; }","duration":"231.315945ms","start":"2026-04-14T04:16:11.902264Z","end":"2026-04-14T04:16:12.13358Z","steps":["trace[1602819800] 'range keys from in-memory index tree' (duration: 229.62699ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:17.761001Z","caller":"traceutil/trace.go:171","msg":"trace[1291970424] linearizableReadLoop","detail":"{readStateIndex:5370; appliedIndex:5369; }","duration":"179.227562ms","start":"2026-04-14T04:16:17.581753Z","end":"2026-04-14T04:16:17.760981Z","steps":["trace[1291970424] 'read index received' (duration: 107.946679ms)","trace[1291970424] 'applied index is now lower than readState.Index' (duration: 71.265013ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:17.76111Z","caller":"traceutil/trace.go:171","msg":"trace[980412873] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"193.913787ms","start":"2026-04-14T04:16:17.567188Z","end":"2026-04-14T04:16:17.761102Z","steps":["trace[980412873] 'process raft request' (duration: 122.502322ms)","trace[980412873] 'compare' (duration: 71.014129ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:17.761251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.486126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:16:17.76133Z","caller":"traceutil/trace.go:171","msg":"trace[864055052] range","detail":"{range_begin:/registry/rolebindings/monitoring/kube-prometheus-stack-admission; range_end:; response_count:0; response_revision:5175; }","duration":"179.595468ms","start":"2026-04-14T04:16:17.581717Z","end":"2026-04-14T04:16:17.761312Z","steps":["trace[864055052] 'agreement among raft nodes before linearized reading' (duration: 179.485696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:17.762164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.226405ms","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-14T04:16:17.762211Z","caller":"traceutil/trace.go:171","msg":"trace[1667687110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5178; }","duration":"104.316696ms","start":"2026-04-14T04:16:17.657884Z","end":"2026-04-14T04:16:17.762201Z","steps":["trace[1667687110] 'agreement among raft nodes before linearized reading' (duration: 104.142683ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:17.762378Z","caller":"traceutil/trace.go:171","msg":"trace[53038391] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"151.77667ms","start":"2026-04-14T04:16:17.610591Z","end":"2026-04-14T04:16:17.762368Z","steps":["trace[53038391] 'process raft request' (duration: 151.11372ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:17.762579Z","caller":"traceutil/trace.go:171","msg":"trace[1132916726] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"122.785626ms","start":"2026-04-14T04:16:17.639784Z","end":"2026-04-14T04:16:17.76257Z","steps":["trace[1132916726] 'process raft request' (duration: 122.012185ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:17.963973Z","caller":"traceutil/trace.go:171","msg":"trace[2141331063] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"186.382246ms","start":"2026-04-14T04:16:17.777567Z","end":"2026-04-14T04:16:17.963949Z","steps":["trace[2141331063] 'process raft request' (duration: 179.170591ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:17.964041Z","caller":"traceutil/trace.go:171","msg":"trace[62499232] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"153.299593ms","start":"2026-04-14T04:16:17.810727Z","end":"2026-04-14T04:16:17.964027Z","steps":["trace[62499232] 'process raft request' (duration: 153.181081ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:17.963969Z","caller":"traceutil/trace.go:171","msg":"trace[338854988] linearizableReadLoop","detail":"{readStateIndex:5377; appliedIndex:5374; }","duration":"146.878948ms","start":"2026-04-14T04:16:17.817074Z","end":"2026-04-14T04:16:17.963953Z","steps":["trace[338854988] 'read index received' (duration: 139.790465ms)","trace[338854988] 'applied index is now lower than readState.Index' (duration: 7.087833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:17.964138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.043802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T04:16:17.964179Z","caller":"traceutil/trace.go:171","msg":"trace[154901787] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:5182; }","duration":"147.122653ms","start":"2026-04-14T04:16:17.817047Z","end":"2026-04-14T04:16:17.96417Z","steps":["trace[154901787] 'agreement among raft nodes before linearized reading' (duration: 146.96431ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:17.964061Z","caller":"traceutil/trace.go:171","msg":"trace[310449126] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"184.226406ms","start":"2026-04-14T04:16:17.779804Z","end":"2026-04-14T04:16:17.964031Z","steps":["trace[310449126] 'process raft request' (duration: 184.010212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:23.388487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.884145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-14T04:16:23.388582Z","caller":"traceutil/trace.go:171","msg":"trace[311239156] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5334; }","duration":"222.014437ms","start":"2026-04-14T04:16:23.166541Z","end":"2026-04-14T04:16:23.388556Z","steps":["trace[311239156] 'range keys from in-memory index tree' (duration: 221.731263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:23.388669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.8169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1370"} {"level":"info","ts":"2026-04-14T04:16:23.388736Z","caller":"traceutil/trace.go:171","msg":"trace[1188110452] range","detail":"{range_begin:/registry/clusterroles/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5334; }","duration":"220.922981ms","start":"2026-04-14T04:16:23.167796Z","end":"2026-04-14T04:16:23.388718Z","steps":["trace[1188110452] 'range keys from in-memory index tree' (duration: 220.671528ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:23.466988Z","caller":"traceutil/trace.go:171","msg":"trace[1219795936] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"139.902ms","start":"2026-04-14T04:16:23.327067Z","end":"2026-04-14T04:16:23.466969Z","steps":["trace[1219795936] 'process raft request' (duration: 139.835539ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:23.467002Z","caller":"traceutil/trace.go:171","msg":"trace[682569129] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"236.359566ms","start":"2026-04-14T04:16:23.23061Z","end":"2026-04-14T04:16:23.46697Z","steps":["trace[682569129] 'process raft request' (duration: 236.083732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:23.910399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.202947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:489387"} {"level":"info","ts":"2026-04-14T04:16:23.910463Z","caller":"traceutil/trace.go:171","msg":"trace[685808616] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:5352; }","duration":"180.304798ms","start":"2026-04-14T04:16:23.730144Z","end":"2026-04-14T04:16:23.910449Z","steps":["trace[685808616] 'range keys from in-memory index tree' (duration: 179.909593ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:23.91049Z","caller":"traceutil/trace.go:171","msg":"trace[1676020551] linearizableReadLoop","detail":"{readStateIndex:5551; appliedIndex:5550; }","duration":"102.297882ms","start":"2026-04-14T04:16:23.808168Z","end":"2026-04-14T04:16:23.910466Z","steps":["trace[1676020551] 'read index received' (duration: 76.682278ms)","trace[1676020551] 'applied index is now lower than readState.Index' (duration: 25.613564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:23.910712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.525145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cf6d68a4-fb9c-4011-9728-e4eeb273057b\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-04-14T04:16:23.910753Z","caller":"traceutil/trace.go:171","msg":"trace[2015031521] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cf6d68a4-fb9c-4011-9728-e4eeb273057b; range_end:; response_count:1; response_revision:5354; }","duration":"102.604446ms","start":"2026-04-14T04:16:23.808138Z","end":"2026-04-14T04:16:23.910742Z","steps":["trace[2015031521] 'agreement among raft nodes before linearized reading' (duration: 102.455824ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:23.910427Z","caller":"traceutil/trace.go:171","msg":"trace[615690508] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"126.8595ms","start":"2026-04-14T04:16:23.783546Z","end":"2026-04-14T04:16:23.910405Z","steps":["trace[615690508] 'process raft request' (duration: 101.309517ms)","trace[615690508] 'compare' (duration: 25.143337ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:26.352105Z","caller":"traceutil/trace.go:171","msg":"trace[1116439917] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"165.071292ms","start":"2026-04-14T04:16:26.187011Z","end":"2026-04-14T04:16:26.352082Z","steps":["trace[1116439917] 'process raft request' (duration: 164.994701ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:26.352113Z","caller":"traceutil/trace.go:171","msg":"trace[124998513] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"165.191494ms","start":"2026-04-14T04:16:26.186886Z","end":"2026-04-14T04:16:26.352077Z","steps":["trace[124998513] 'process raft request' (duration: 164.978981ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:26.352172Z","caller":"traceutil/trace.go:171","msg":"trace[1403698739] linearizableReadLoop","detail":"{readStateIndex:5596; appliedIndex:5592; }","duration":"163.740693ms","start":"2026-04-14T04:16:26.188401Z","end":"2026-04-14T04:16:26.352142Z","steps":["trace[1403698739] 'read index received' (duration: 43.481943ms)","trace[1403698739] 'applied index is now lower than readState.Index' (duration: 120.25811ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:26.352274Z","caller":"traceutil/trace.go:171","msg":"trace[1827556948] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"165.219995ms","start":"2026-04-14T04:16:26.187047Z","end":"2026-04-14T04:16:26.352267Z","steps":["trace[1827556948] 'process raft request' (duration: 164.979052ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:26.352397Z","caller":"traceutil/trace.go:171","msg":"trace[452748934] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"155.730957ms","start":"2026-04-14T04:16:26.196659Z","end":"2026-04-14T04:16:26.35239Z","steps":["trace[452748934] 'process raft request' (duration: 155.434593ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:26.352448Z","caller":"traceutil/trace.go:171","msg":"trace[121832397] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"161.150066ms","start":"2026-04-14T04:16:26.191289Z","end":"2026-04-14T04:16:26.352439Z","steps":["trace[121832397] 'process raft request' (duration: 160.78232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.352554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.139179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-14T04:16:26.35257Z","caller":"traceutil/trace.go:171","msg":"trace[2002757513] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"162.572506ms","start":"2026-04-14T04:16:26.18999Z","end":"2026-04-14T04:16:26.352563Z","steps":["trace[2002757513] 'process raft request' (duration: 162.061559ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:26.352587Z","caller":"traceutil/trace.go:171","msg":"trace[1435786199] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5400; }","duration":"164.20298ms","start":"2026-04-14T04:16:26.188374Z","end":"2026-04-14T04:16:26.352577Z","steps":["trace[1435786199] 'agreement among raft nodes before linearized reading' (duration: 164.077538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.352722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.817307ms","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-14T04:16:26.352746Z","caller":"traceutil/trace.go:171","msg":"trace[1719980839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5400; }","duration":"146.920919ms","start":"2026-04-14T04:16:26.20582Z","end":"2026-04-14T04:16:26.352741Z","steps":["trace[1719980839] 'agreement among raft nodes before linearized reading' (duration: 146.860198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.352872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.710405ms","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-14T04:16:26.352895Z","caller":"traceutil/trace.go:171","msg":"trace[527917246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5400; }","duration":"102.760316ms","start":"2026-04-14T04:16:26.25013Z","end":"2026-04-14T04:16:26.352891Z","steps":["trace[527917246] 'agreement among raft nodes before linearized reading' (duration: 102.704605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.353013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.109377ms","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-14T04:16:26.353052Z","caller":"traceutil/trace.go:171","msg":"trace[2145064387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5400; }","duration":"124.200387ms","start":"2026-04-14T04:16:26.228845Z","end":"2026-04-14T04:16:26.353045Z","steps":["trace[2145064387] 'agreement among raft nodes before linearized reading' (duration: 124.016175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.353154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.530862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T04:16:26.353181Z","caller":"traceutil/trace.go:171","msg":"trace[2094689389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5400; }","duration":"104.586412ms","start":"2026-04-14T04:16:26.248589Z","end":"2026-04-14T04:16:26.353175Z","steps":["trace[2094689389] 'agreement among raft nodes before linearized reading' (duration: 104.525131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.817631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.245251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:16:26.817873Z","caller":"traceutil/trace.go:171","msg":"trace[946972232] linearizableReadLoop","detail":"{readStateIndex:5606; appliedIndex:5600; }","duration":"439.887603ms","start":"2026-04-14T04:16:26.377974Z","end":"2026-04-14T04:16:26.817862Z","steps":["trace[946972232] 'read index received' (duration: 117.366858ms)","trace[946972232] 'applied index is now lower than readState.Index' (duration: 322.520115ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:26.817925Z","caller":"traceutil/trace.go:171","msg":"trace[1290150871] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"459.301757ms","start":"2026-04-14T04:16:26.358615Z","end":"2026-04-14T04:16:26.817916Z","steps":["trace[1290150871] 'process raft request' (duration: 136.721181ms)","trace[1290150871] 'compare' (duration: 322.022908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:26.817962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:26.358604Z","time spent":"459.338647ms","remote":"127.0.0.1:43996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:16:26.817989Z","caller":"traceutil/trace.go:171","msg":"trace[470563208] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"459.287666ms","start":"2026-04-14T04:16:26.358687Z","end":"2026-04-14T04:16:26.817975Z","steps":["trace[470563208] 'process raft request' (duration: 459.013302ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:26.817994Z","caller":"traceutil/trace.go:171","msg":"trace[1057429725] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"459.188025ms","start":"2026-04-14T04:16:26.358773Z","end":"2026-04-14T04:16:26.817961Z","steps":["trace[1057429725] 'process raft request' (duration: 458.994872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.818053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:26.358683Z","time spent":"459.335547ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:16:26.818093Z","caller":"traceutil/trace.go:171","msg":"trace[1149697598] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"455.802855ms","start":"2026-04-14T04:16:26.362285Z","end":"2026-04-14T04:16:26.818087Z","steps":["trace[1149697598] 'process raft request' (duration: 455.503761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.818127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:26.362278Z","time spent":"455.834796ms","remote":"127.0.0.1:43996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":918,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:16:26.818155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:26.35877Z","time spent":"459.301276ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:16:26.818178Z","caller":"traceutil/trace.go:171","msg":"trace[405879054] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"451.235609ms","start":"2026-04-14T04:16:26.366931Z","end":"2026-04-14T04:16:26.818167Z","steps":["trace[405879054] 'process raft request' (duration: 450.883223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.818258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:26.366923Z","time spent":"451.304549ms","remote":"127.0.0.1:38290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:16:26.818506Z","caller":"traceutil/trace.go:171","msg":"trace[1314839851] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"459.767833ms","start":"2026-04-14T04:16:26.358731Z","end":"2026-04-14T04:16:26.818499Z","steps":["trace[1314839851] 'process raft request' (duration: 459.017772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.818575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:26.358728Z","time spent":"459.816594ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:16:26.81879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.518656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-14T04:16:26.818818Z","caller":"traceutil/trace.go:171","msg":"trace[649381361] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5407; }","duration":"458.557566ms","start":"2026-04-14T04:16:26.360254Z","end":"2026-04-14T04:16:26.818812Z","steps":["trace[649381361] 'agreement among raft nodes before linearized reading' (duration: 457.941447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.818838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:26.360249Z","time spent":"458.583016ms","remote":"127.0.0.1:44024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2193188,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-04-14T04:16:26.833323Z","caller":"traceutil/trace.go:171","msg":"trace[525258943] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"115.37264ms","start":"2026-04-14T04:16:26.717938Z","end":"2026-04-14T04:16:26.833311Z","steps":["trace[525258943] 'process raft request' (duration: 115.213988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.833483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.486649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:16:26.833507Z","caller":"traceutil/trace.go:171","msg":"trace[793369965] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5408; }","duration":"119.558531ms","start":"2026-04-14T04:16:26.713941Z","end":"2026-04-14T04:16:26.8335Z","steps":["trace[793369965] 'agreement among raft nodes before linearized reading' (duration: 119.49347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.833648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.655559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-dh7rk\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-04-14T04:16:26.833671Z","caller":"traceutil/trace.go:171","msg":"trace[1499293211] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-dh7rk; range_end:; response_count:1; response_revision:5408; }","duration":"206.718539ms","start":"2026-04-14T04:16:26.626946Z","end":"2026-04-14T04:16:26.833665Z","steps":["trace[1499293211] 'agreement among raft nodes before linearized reading' (duration: 206.646618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.833794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.817298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63276eb2-5624-474a-8389-1f1ab3eb9dc5\" ","response":"range_response_count:1 size:4077"} {"level":"info","ts":"2026-04-14T04:16:26.833814Z","caller":"traceutil/trace.go:171","msg":"trace[907524041] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63276eb2-5624-474a-8389-1f1ab3eb9dc5; range_end:; response_count:1; response_revision:5408; }","duration":"348.871688ms","start":"2026-04-14T04:16:26.484938Z","end":"2026-04-14T04:16:26.83381Z","steps":["trace[907524041] 'agreement among raft nodes before linearized reading' (duration: 348.815347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.833854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:26.484921Z","time spent":"348.92784ms","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4100,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63276eb2-5624-474a-8389-1f1ab3eb9dc5\" "} {"level":"warn","ts":"2026-04-14T04:16:26.833965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.387635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-14T04:16:26.833994Z","caller":"traceutil/trace.go:171","msg":"trace[1515712264] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5408; }","duration":"452.431646ms","start":"2026-04-14T04:16:26.381549Z","end":"2026-04-14T04:16:26.833981Z","steps":["trace[1515712264] 'agreement among raft nodes before linearized reading' (duration: 452.378965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:26.83401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:26.381536Z","time spent":"452.469547ms","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":673,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"info","ts":"2026-04-14T04:16:28.583551Z","caller":"traceutil/trace.go:171","msg":"trace[1541997670] linearizableReadLoop","detail":"{readStateIndex:5644; appliedIndex:5643; }","duration":"406.603213ms","start":"2026-04-14T04:16:28.176921Z","end":"2026-04-14T04:16:28.583524Z","steps":["trace[1541997670] 'read index received' (duration: 406.182976ms)","trace[1541997670] 'applied index is now lower than readState.Index' (duration: 419.167µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:28.583875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.482729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"warn","ts":"2026-04-14T04:16:28.583919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.980248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:16:28.583942Z","caller":"traceutil/trace.go:171","msg":"trace[1130152510] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5442; }","duration":"388.59458ms","start":"2026-04-14T04:16:28.19533Z","end":"2026-04-14T04:16:28.583924Z","steps":["trace[1130152510] 'agreement among raft nodes before linearized reading' (duration: 388.392307ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:28.583976Z","caller":"traceutil/trace.go:171","msg":"trace[505639020] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5442; }","duration":"407.08736ms","start":"2026-04-14T04:16:28.176876Z","end":"2026-04-14T04:16:28.583964Z","steps":["trace[505639020] 'agreement among raft nodes before linearized reading' (duration: 406.990469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:28.583983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:28.195307Z","time spent":"388.667311ms","remote":"127.0.0.1:44112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-04-14T04:16:28.583981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.85093ms","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":"warn","ts":"2026-04-14T04:16:28.584002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:28.176852Z","time spent":"407.14482ms","remote":"127.0.0.1:59954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-04-14T04:16:28.584032Z","caller":"traceutil/trace.go:171","msg":"trace[100470818] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5442; }","duration":"224.938861ms","start":"2026-04-14T04:16:28.359077Z","end":"2026-04-14T04:16:28.584016Z","steps":["trace[100470818] 'agreement among raft nodes before linearized reading' (duration: 224.841029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:28.583873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.779619ms","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-14T04:16:28.584167Z","caller":"traceutil/trace.go:171","msg":"trace[1927584733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5442; }","duration":"201.111275ms","start":"2026-04-14T04:16:28.383041Z","end":"2026-04-14T04:16:28.584153Z","steps":["trace[1927584733] 'agreement among raft nodes before linearized reading' (duration: 200.687429ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:28.584058Z","caller":"traceutil/trace.go:171","msg":"trace[878659313] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"447.081631ms","start":"2026-04-14T04:16:28.13676Z","end":"2026-04-14T04:16:28.583842Z","steps":["trace[878659313] 'process raft request' (duration: 446.617874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:28.586377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:28.136748Z","time spent":"447.604449ms","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:16:29.430563Z","caller":"traceutil/trace.go:171","msg":"trace[1987602765] linearizableReadLoop","detail":"{readStateIndex:5663; appliedIndex:5662; }","duration":"151.873157ms","start":"2026-04-14T04:16:29.278671Z","end":"2026-04-14T04:16:29.430544Z","steps":["trace[1987602765] 'read index received' (duration: 151.770846ms)","trace[1987602765] 'applied index is now lower than readState.Index' (duration: 101.381µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:29.430599Z","caller":"traceutil/trace.go:171","msg":"trace[758784560] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"167.284372ms","start":"2026-04-14T04:16:29.263295Z","end":"2026-04-14T04:16:29.430579Z","steps":["trace[758784560] 'process raft request' (duration: 167.13547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:29.430779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.10143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-14T04:16:29.430811Z","caller":"traceutil/trace.go:171","msg":"trace[1582411472] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5461; }","duration":"152.182502ms","start":"2026-04-14T04:16:29.278619Z","end":"2026-04-14T04:16:29.430802Z","steps":["trace[1582411472] 'agreement among raft nodes before linearized reading' (duration: 152.0298ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:29.615759Z","caller":"traceutil/trace.go:171","msg":"trace[690349131] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5663; }","duration":"185.106061ms","start":"2026-04-14T04:16:29.43063Z","end":"2026-04-14T04:16:29.615736Z","steps":["trace[690349131] 'read index received' (duration: 184.434821ms)","trace[690349131] 'applied index is now lower than readState.Index' (duration: 670.35µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:29.615956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.951616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:16:29.616016Z","caller":"traceutil/trace.go:171","msg":"trace[2140467140] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5462; }","duration":"189.045687ms","start":"2026-04-14T04:16:29.426947Z","end":"2026-04-14T04:16:29.615993Z","steps":["trace[2140467140] 'agreement among raft nodes before linearized reading' (duration: 188.910565ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:29.616031Z","caller":"traceutil/trace.go:171","msg":"trace[999854901] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"191.726357ms","start":"2026-04-14T04:16:29.424284Z","end":"2026-04-14T04:16:29.616011Z","steps":["trace[999854901] 'process raft request' (duration: 190.769233ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:29.620462Z","caller":"traceutil/trace.go:171","msg":"trace[1221536674] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"184.579663ms","start":"2026-04-14T04:16:29.435854Z","end":"2026-04-14T04:16:29.620433Z","steps":["trace[1221536674] 'process raft request' (duration: 184.339779ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:29.620523Z","caller":"traceutil/trace.go:171","msg":"trace[246776082] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"174.377714ms","start":"2026-04-14T04:16:29.44613Z","end":"2026-04-14T04:16:29.620508Z","steps":["trace[246776082] 'process raft request' (duration: 174.175921ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:29.620606Z","caller":"traceutil/trace.go:171","msg":"trace[441090118] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"103.811539ms","start":"2026-04-14T04:16:29.516774Z","end":"2026-04-14T04:16:29.620586Z","steps":["trace[441090118] 'process raft request' (duration: 103.562066ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:30.041682Z","caller":"traceutil/trace.go:171","msg":"trace[1249500679] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"113.884985ms","start":"2026-04-14T04:16:29.92774Z","end":"2026-04-14T04:16:30.041625Z","steps":["trace[1249500679] 'process raft request' (duration: 113.613141ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:33.938362Z","caller":"traceutil/trace.go:171","msg":"trace[539581871] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"232.778006ms","start":"2026-04-14T04:16:33.705557Z","end":"2026-04-14T04:16:33.938335Z","steps":["trace[539581871] 'process raft request' (duration: 141.819717ms)","trace[539581871] 'compare' (duration: 90.850918ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:34.354617Z","caller":"traceutil/trace.go:171","msg":"trace[1381495970] linearizableReadLoop","detail":"{readStateIndex:5705; appliedIndex:5704; }","duration":"175.321301ms","start":"2026-04-14T04:16:34.179276Z","end":"2026-04-14T04:16:34.354597Z","steps":["trace[1381495970] 'read index received' (duration: 175.141458ms)","trace[1381495970] 'applied index is now lower than readState.Index' (duration: 178.933µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:34.354729Z","caller":"traceutil/trace.go:171","msg":"trace[147533047] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"207.013781ms","start":"2026-04-14T04:16:34.147705Z","end":"2026-04-14T04:16:34.354719Z","steps":["trace[147533047] 'process raft request' (duration: 206.753407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:34.354964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.605646ms","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-14T04:16:34.355038Z","caller":"traceutil/trace.go:171","msg":"trace[98488791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5502; }","duration":"175.708217ms","start":"2026-04-14T04:16:34.179313Z","end":"2026-04-14T04:16:34.355021Z","steps":["trace[98488791] 'agreement among raft nodes before linearized reading' (duration: 175.515084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:34.355142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.848019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-679cc58c4-kz92f.18a61e1865434f8c\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-04-14T04:16:34.355192Z","caller":"traceutil/trace.go:171","msg":"trace[2075847056] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-679cc58c4-kz92f.18a61e1865434f8c; range_end:; response_count:1; response_revision:5502; }","duration":"175.933641ms","start":"2026-04-14T04:16:34.179245Z","end":"2026-04-14T04:16:34.355179Z","steps":["trace[2075847056] 'agreement among raft nodes before linearized reading' (duration: 175.786368ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:34.492313Z","caller":"traceutil/trace.go:171","msg":"trace[636946982] linearizableReadLoop","detail":"{readStateIndex:5707; appliedIndex:5705; }","duration":"121.369468ms","start":"2026-04-14T04:16:34.370923Z","end":"2026-04-14T04:16:34.492292Z","steps":["trace[636946982] 'read index received' (duration: 73.977681ms)","trace[636946982] 'applied index is now lower than readState.Index' (duration: 47.390667ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:34.492527Z","caller":"traceutil/trace.go:171","msg":"trace[2120413420] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"133.87082ms","start":"2026-04-14T04:16:34.358609Z","end":"2026-04-14T04:16:34.49248Z","steps":["trace[2120413420] 'process raft request' (duration: 133.631156ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:34.492571Z","caller":"traceutil/trace.go:171","msg":"trace[409760124] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"134.968016ms","start":"2026-04-14T04:16:34.357577Z","end":"2026-04-14T04:16:34.492545Z","steps":["trace[409760124] 'process raft request' (duration: 87.242584ms)","trace[409760124] 'compare' (duration: 47.305776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:34.492603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.48942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:16:34.492946Z","caller":"traceutil/trace.go:171","msg":"trace[1328041264] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:5504; }","duration":"121.744784ms","start":"2026-04-14T04:16:34.370891Z","end":"2026-04-14T04:16:34.492636Z","steps":["trace[1328041264] 'agreement among raft nodes before linearized reading' (duration: 121.48908ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:39.857489Z","caller":"traceutil/trace.go:171","msg":"trace[503338150] transaction","detail":"{read_only:false; response_revision:5558; number_of_response:1; }","duration":"100.064207ms","start":"2026-04-14T04:16:39.757399Z","end":"2026-04-14T04:16:39.857464Z","steps":["trace[503338150] 'process raft request' (duration: 99.910875ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:39.86318Z","caller":"traceutil/trace.go:171","msg":"trace[1361579325] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"105.438234ms","start":"2026-04-14T04:16:39.757717Z","end":"2026-04-14T04:16:39.863155Z","steps":["trace[1361579325] 'process raft request' (duration: 105.220131ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:39.863272Z","caller":"traceutil/trace.go:171","msg":"trace[1153566535] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"104.603122ms","start":"2026-04-14T04:16:39.758617Z","end":"2026-04-14T04:16:39.86322Z","steps":["trace[1153566535] 'process raft request' (duration: 104.44834ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:44.012344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2836} {"level":"info","ts":"2026-04-14T04:16:44.154934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2836,"took":"141.543411ms","hash":638592855,"current-db-size-bytes":55345152,"current-db-size":"55 MB","current-db-size-in-use-bytes":30810112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-14T04:16:44.154993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":638592855,"revision":2836,"compact-revision":-1} {"level":"info","ts":"2026-04-14T04:16:51.04307Z","caller":"traceutil/trace.go:171","msg":"trace[1733540144] linearizableReadLoop","detail":"{readStateIndex:5891; appliedIndex:5890; }","duration":"116.298412ms","start":"2026-04-14T04:16:50.926746Z","end":"2026-04-14T04:16:51.043044Z","steps":["trace[1733540144] 'read index received' (duration: 95.94773ms)","trace[1733540144] 'applied index is now lower than readState.Index' (duration: 20.347252ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:51.043302Z","caller":"traceutil/trace.go:171","msg":"trace[116487578] transaction","detail":"{read_only:false; response_revision:5682; number_of_response:1; }","duration":"123.482497ms","start":"2026-04-14T04:16:50.919791Z","end":"2026-04-14T04:16:51.043273Z","steps":["trace[116487578] 'process raft request' (duration: 103.006242ms)","trace[116487578] 'compare' (duration: 19.932577ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:51.043319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.559107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-14T04:16:51.043372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.970295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T04:16:51.043391Z","caller":"traceutil/trace.go:171","msg":"trace[685465313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5682; }","duration":"116.676358ms","start":"2026-04-14T04:16:50.926697Z","end":"2026-04-14T04:16:51.043374Z","steps":["trace[685465313] 'agreement among raft nodes before linearized reading' (duration: 116.496795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:51.043434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.829673ms","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-14T04:16:51.043446Z","caller":"traceutil/trace.go:171","msg":"trace[1044246166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5682; }","duration":"113.053816ms","start":"2026-04-14T04:16:50.930372Z","end":"2026-04-14T04:16:51.043426Z","steps":["trace[1044246166] 'agreement among raft nodes before linearized reading' (duration: 112.873563ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:51.043484Z","caller":"traceutil/trace.go:171","msg":"trace[531466808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5682; }","duration":"112.913794ms","start":"2026-04-14T04:16:50.930554Z","end":"2026-04-14T04:16:51.043468Z","steps":["trace[531466808] 'agreement among raft nodes before linearized reading' (duration: 112.776552ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:17:03.21289Z","caller":"traceutil/trace.go:171","msg":"trace[2083226133] transaction","detail":"{read_only:false; response_revision:5762; number_of_response:1; }","duration":"105.534159ms","start":"2026-04-14T04:17:03.10733Z","end":"2026-04-14T04:17:03.212864Z","steps":["trace[2083226133] 'process raft request' (duration: 71.755886ms)","trace[2083226133] 'compare' (duration: 33.630181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:17:03.454767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.11845ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T04:17:03.454945Z","caller":"traceutil/trace.go:171","msg":"trace[2006874737] linearizableReadLoop","detail":"{readStateIndex:5977; appliedIndex:5976; }","duration":"114.899884ms","start":"2026-04-14T04:17:03.340025Z","end":"2026-04-14T04:17:03.454925Z","steps":["trace[2006874737] 'read index received' (duration: 42.551µs)","trace[2006874737] 'applied index is now lower than readState.Index' (duration: 114.855723ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:17:03.455072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.996295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T04:17:03.455142Z","caller":"traceutil/trace.go:171","msg":"trace[2136892655] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5764; }","duration":"115.145687ms","start":"2026-04-14T04:17:03.339954Z","end":"2026-04-14T04:17:03.4551Z","steps":["trace[2136892655] 'agreement among raft nodes before linearized reading' (duration: 115.020576ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:17:14.913343Z","caller":"traceutil/trace.go:171","msg":"trace[1446600786] linearizableReadLoop","detail":"{readStateIndex:6032; appliedIndex:6031; }","duration":"256.833558ms","start":"2026-04-14T04:17:14.656487Z","end":"2026-04-14T04:17:14.913321Z","steps":["trace[1446600786] 'read index received' (duration: 256.639505ms)","trace[1446600786] 'applied index is now lower than readState.Index' (duration: 192.873µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:17:14.913572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.063771ms","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-14T04:17:14.913638Z","caller":"traceutil/trace.go:171","msg":"trace[1217944151] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5817; }","duration":"257.202493ms","start":"2026-04-14T04:17:14.656422Z","end":"2026-04-14T04:17:14.913625Z","steps":["trace[1217944151] 'agreement among raft nodes before linearized reading' (duration: 257.043731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:17:14.914004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.088126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-04-14T04:17:14.914086Z","caller":"traceutil/trace.go:171","msg":"trace[1899032124] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5817; }","duration":"199.220558ms","start":"2026-04-14T04:17:14.714845Z","end":"2026-04-14T04:17:14.914065Z","steps":["trace[1899032124] 'agreement among raft nodes before linearized reading' (duration: 198.762171ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:17:14.914141Z","caller":"traceutil/trace.go:171","msg":"trace[1803345206] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"311.417746ms","start":"2026-04-14T04:17:14.602029Z","end":"2026-04-14T04:17:14.913447Z","steps":["trace[1803345206] 'process raft request' (duration: 311.133592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:17:14.914848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:17:14.602003Z","time spent":"312.650543ms","remote":"127.0.0.1:44232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:17:14.918845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.468621ms","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-14T04:17:14.918883Z","caller":"traceutil/trace.go:171","msg":"trace[1254787064] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5817; }","duration":"218.531603ms","start":"2026-04-14T04:17:14.700342Z","end":"2026-04-14T04:17:14.918874Z","steps":["trace[1254787064] 'agreement among raft nodes before linearized reading' (duration: 213.138386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:17:15.110313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.883348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:17:15.110965Z","caller":"traceutil/trace.go:171","msg":"trace[643692240] linearizableReadLoop","detail":"{readStateIndex:6035; appliedIndex:6032; }","duration":"128.261996ms","start":"2026-04-14T04:17:14.982673Z","end":"2026-04-14T04:17:15.110935Z","steps":["trace[643692240] 'read index received' (duration: 110.071977ms)","trace[643692240] 'applied index is now lower than readState.Index' (duration: 18.189069ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:17:15.11118Z","caller":"traceutil/trace.go:171","msg":"trace[280485018] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"191.604749ms","start":"2026-04-14T04:17:14.919529Z","end":"2026-04-14T04:17:15.111134Z","steps":["trace[280485018] 'process raft request' (duration: 39.800987ms)","trace[280485018] 'compare' (duration: 150.677936ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:17:15.111213Z","caller":"traceutil/trace.go:171","msg":"trace[1765260920] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"189.253714ms","start":"2026-04-14T04:17:14.921941Z","end":"2026-04-14T04:17:15.111195Z","steps":["trace[1765260920] 'process raft request' (duration: 188.452153ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:17:15.111325Z","caller":"traceutil/trace.go:171","msg":"trace[2012088923] transaction","detail":"{read_only:false; response_revision:5820; number_of_response:1; }","duration":"187.124345ms","start":"2026-04-14T04:17:14.924173Z","end":"2026-04-14T04:17:15.111297Z","steps":["trace[2012088923] 'process raft request' (duration: 186.719039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:17:15.11254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.855338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:17:15.112671Z","caller":"traceutil/trace.go:171","msg":"trace[655817129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5820; }","duration":"129.94548ms","start":"2026-04-14T04:17:14.982647Z","end":"2026-04-14T04:17:15.112593Z","steps":["trace[655817129] 'agreement among raft nodes before linearized reading' (duration: 128.366618ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:17:23.726704Z","caller":"traceutil/trace.go:171","msg":"trace[1723370931] transaction","detail":"{read_only:false; response_revision:5873; number_of_response:1; }","duration":"167.583659ms","start":"2026-04-14T04:17:23.559091Z","end":"2026-04-14T04:17:23.726674Z","steps":["trace[1723370931] 'process raft request' (duration: 139.953466ms)","trace[1723370931] 'compare' (duration: 27.474531ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:18:23.156376Z","caller":"traceutil/trace.go:171","msg":"trace[1417970239] transaction","detail":"{read_only:false; response_revision:6369; number_of_response:1; }","duration":"134.333634ms","start":"2026-04-14T04:18:23.022022Z","end":"2026-04-14T04:18:23.156356Z","steps":["trace[1417970239] 'process raft request' (duration: 134.198373ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:18:29.87215Z","caller":"traceutil/trace.go:171","msg":"trace[551781180] linearizableReadLoop","detail":"{readStateIndex:6638; appliedIndex:6637; }","duration":"231.537787ms","start":"2026-04-14T04:18:29.640589Z","end":"2026-04-14T04:18:29.872127Z","steps":["trace[551781180] 'read index received' (duration: 231.321024ms)","trace[551781180] 'applied index is now lower than readState.Index' (duration: 215.853µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:18:29.872269Z","caller":"traceutil/trace.go:171","msg":"trace[1160985299] transaction","detail":"{read_only:false; response_revision:6404; number_of_response:1; }","duration":"234.604629ms","start":"2026-04-14T04:18:29.637653Z","end":"2026-04-14T04:18:29.872257Z","steps":["trace[1160985299] 'process raft request' (duration: 234.280124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:18:29.872395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.651255ms","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":"info","ts":"2026-04-14T04:18:29.872458Z","caller":"traceutil/trace.go:171","msg":"trace[1225889980] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:6404; }","duration":"225.743546ms","start":"2026-04-14T04:18:29.646701Z","end":"2026-04-14T04:18:29.872445Z","steps":["trace[1225889980] 'agreement among raft nodes before linearized reading' (duration: 225.633375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:18:29.872501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.892582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T04:18:29.872542Z","caller":"traceutil/trace.go:171","msg":"trace[1036953151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6404; }","duration":"231.967483ms","start":"2026-04-14T04:18:29.640565Z","end":"2026-04-14T04:18:29.872532Z","steps":["trace[1036953151] 'agreement among raft nodes before linearized reading' (duration: 231.829361ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:18:52.922174Z","caller":"traceutil/trace.go:171","msg":"trace[283622386] transaction","detail":"{read_only:false; response_revision:6583; number_of_response:1; }","duration":"166.393308ms","start":"2026-04-14T04:18:52.755756Z","end":"2026-04-14T04:18:52.922149Z","steps":["trace[283622386] 'process raft request' (duration: 166.260684ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:18:53.201517Z","caller":"traceutil/trace.go:171","msg":"trace[684236751] transaction","detail":"{read_only:false; response_revision:6584; number_of_response:1; }","duration":"119.287669ms","start":"2026-04-14T04:18:53.082202Z","end":"2026-04-14T04:18:53.20149Z","steps":["trace[684236751] 'process raft request' (duration: 119.074821ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:18:55.067919Z","caller":"traceutil/trace.go:171","msg":"trace[1983209439] transaction","detail":"{read_only:false; response_revision:6594; number_of_response:1; }","duration":"136.23524ms","start":"2026-04-14T04:18:54.931655Z","end":"2026-04-14T04:18:55.06789Z","steps":["trace[1983209439] 'process raft request' (duration: 135.484504ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:19:02.167969Z","caller":"traceutil/trace.go:171","msg":"trace[966623110] transaction","detail":"{read_only:false; response_revision:6632; number_of_response:1; }","duration":"109.370787ms","start":"2026-04-14T04:19:02.058571Z","end":"2026-04-14T04:19:02.167942Z","steps":["trace[966623110] 'process raft request' (duration: 109.18282ms)"],"step_count":1}