{"level":"warn","ts":"2026-05-01T00:57:05.225203Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-01T00:57:05.225349Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-01T00:57:05.225423Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-01T00:57:05.225433Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-01T00:57:05.22546Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-01T00:57:05.226141Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-05-01T00:57:05.226227Z","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":16,"max-cpu-available":16,"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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-01T00:57:05.229109Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.62074ms"} {"level":"info","ts":"2026-05-01T00:57:05.236005Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-05-01T00:57:05.236103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-05-01T00:57:05.236134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-05-01T00:57:05.236154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-01T00:57:05.236172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-05-01T00:57:05.236227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-05-01T00:57:05.241905Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-01T00:57:05.243477Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-01T00:57:05.244473Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-01T00:57:05.24796Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-01T00:57:05.248243Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-01T00:57:05.248308Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-01T00:57:05.248391Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-01T00:57:05.24841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-01T00:57:05.250743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-05-01T00:57:05.250957Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-01T00:57:05.254668Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-01T00:57:05.25502Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-01T00:57:05.255069Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-01T00:57:05.255125Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-01T00:57:05.255214Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-01T00:57:06.03726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-05-01T00:57:06.037395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-01T00:57:06.037464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-05-01T00:57:06.037488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-05-01T00:57:06.037501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-01T00:57:06.037519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-05-01T00:57:06.037535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-01T00:57:06.038941Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T00:57:06.038966Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T00:57:06.038968Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T00:57:06.039504Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-01T00:57:06.039482Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-01T00:57:06.039635Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-01T00:57:06.040803Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T00:57:06.040979Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T00:57:06.041051Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T00:57:06.042832Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-01T00:57:06.043508Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"warn","ts":"2026-05-01T00:57:17.15687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.321433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-05-01T00:57:17.156899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.354252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-01T00:57:17.156994Z","caller":"traceutil/trace.go:171","msg":"trace[483804388] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:369; }","duration":"251.521738ms","start":"2026-05-01T00:57:16.905452Z","end":"2026-05-01T00:57:17.156974Z","steps":["trace[483804388] 'range keys from in-memory index tree' (duration: 251.22065ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:57:17.157029Z","caller":"traceutil/trace.go:171","msg":"trace[80092903] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:369; }","duration":"301.533867ms","start":"2026-05-01T00:57:16.855459Z","end":"2026-05-01T00:57:17.156993Z","steps":["trace[80092903] 'range keys from in-memory index tree' (duration: 301.221188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:57:17.15708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.346623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-05-01T00:57:17.157088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:57:16.855435Z","time spent":"301.63766ms","remote":"127.0.0.1:59770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-05-01T00:57:17.157192Z","caller":"traceutil/trace.go:171","msg":"trace[235720122] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:369; }","duration":"251.557609ms","start":"2026-05-01T00:57:16.905612Z","end":"2026-05-01T00:57:17.15717Z","steps":["trace[235720122] 'range keys from in-memory index tree' (duration: 251.147728ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:57:21.217775Z","caller":"traceutil/trace.go:171","msg":"trace[1726592508] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"137.816995ms","start":"2026-05-01T00:57:21.079929Z","end":"2026-05-01T00:57:21.217746Z","steps":["trace[1726592508] 'process raft request' (duration: 135.562264ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:57:24.646325Z","caller":"traceutil/trace.go:171","msg":"trace[1360303572] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"275.580388ms","start":"2026-05-01T00:57:24.37072Z","end":"2026-05-01T00:57:24.6463Z","steps":["trace[1360303572] 'process raft request' (duration: 275.423083ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:57:36.018709Z","caller":"traceutil/trace.go:171","msg":"trace[577944491] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"318.575557ms","start":"2026-05-01T00:57:35.699281Z","end":"2026-05-01T00:57:36.017857Z","steps":["trace[577944491] 'process raft request' (duration: 311.740853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:57:36.019866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:57:35.699272Z","time spent":"319.572503ms","remote":"127.0.0.1:59652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:57:36.023603Z","caller":"traceutil/trace.go:171","msg":"trace[406358422] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"323.56404ms","start":"2026-05-01T00:57:35.699996Z","end":"2026-05-01T00:57:36.02356Z","steps":["trace[406358422] 'process raft request' (duration: 318.775261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:57:36.023804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:57:35.699988Z","time spent":"323.713054ms","remote":"127.0.0.1:59652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:57:36.024036Z","caller":"traceutil/trace.go:171","msg":"trace[1679018115] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"306.574903ms","start":"2026-05-01T00:57:35.717428Z","end":"2026-05-01T00:57:36.024003Z","steps":["trace[1679018115] 'process raft request' (duration: 305.232027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:57:36.024195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:57:35.717388Z","time spent":"306.742027ms","remote":"127.0.0.1:59652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:57:36.024187Z","caller":"traceutil/trace.go:171","msg":"trace[47545519] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"249.622518ms","start":"2026-05-01T00:57:35.774524Z","end":"2026-05-01T00:57:36.024147Z","steps":["trace[47545519] 'process raft request' (duration: 249.488944ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:57:36.024195Z","caller":"traceutil/trace.go:171","msg":"trace[149009812] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:578; }","duration":"300.628112ms","start":"2026-05-01T00:57:35.723467Z","end":"2026-05-01T00:57:36.024095Z","steps":["trace[149009812] 'read index received' (duration: 287.620123ms)","trace[149009812] 'applied index is now lower than readState.Index' (duration: 13.006769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:57:36.024669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.203427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-01T00:57:36.024721Z","caller":"traceutil/trace.go:171","msg":"trace[1593245492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"301.29679ms","start":"2026-05-01T00:57:35.72341Z","end":"2026-05-01T00:57:36.024706Z","steps":["trace[1593245492] 'agreement among raft nodes before linearized reading' (duration: 301.168526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:57:36.024765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:57:35.723389Z","time spent":"301.367202ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-01T00:57:36.873327Z","caller":"traceutil/trace.go:171","msg":"trace[1513599134] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"168.521652ms","start":"2026-05-01T00:57:36.704759Z","end":"2026-05-01T00:57:36.873281Z","steps":["trace[1513599134] 'read index received' (duration: 136.178161ms)","trace[1513599134] 'applied index is now lower than readState.Index' (duration: 32.342401ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:57:36.8734Z","caller":"traceutil/trace.go:171","msg":"trace[455117003] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"286.983564ms","start":"2026-05-01T00:57:36.586384Z","end":"2026-05-01T00:57:36.873367Z","steps":["trace[455117003] 'process raft request' (duration: 254.89538ms)","trace[455117003] 'compare' (duration: 31.17093ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:57:36.873508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.731827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-01T00:57:36.873548Z","caller":"traceutil/trace.go:171","msg":"trace[2121103814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"168.852431ms","start":"2026-05-01T00:57:36.704686Z","end":"2026-05-01T00:57:36.873538Z","steps":["trace[2121103814] 'agreement among raft nodes before linearized reading' (duration: 168.719097ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:57:41.89062Z","caller":"traceutil/trace.go:171","msg":"trace[630379023] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"114.584617ms","start":"2026-05-01T00:57:41.776006Z","end":"2026-05-01T00:57:41.89059Z","steps":["trace[630379023] 'process raft request' (duration: 62.504774ms)","trace[630379023] 'compare' (duration: 51.779955ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:57:42.535568Z","caller":"traceutil/trace.go:171","msg":"trace[2100904616] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"458.614936ms","start":"2026-05-01T00:57:42.076906Z","end":"2026-05-01T00:57:42.53552Z","steps":["trace[2100904616] 'process raft request' (duration: 425.225866ms)","trace[2100904616] 'compare' (duration: 31.899989ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:57:42.535689Z","caller":"traceutil/trace.go:171","msg":"trace[1966814142] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"456.150759ms","start":"2026-05-01T00:57:42.079468Z","end":"2026-05-01T00:57:42.535618Z","steps":["trace[1966814142] 'process raft request' (duration: 455.953954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:57:42.535842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:57:42.076879Z","time spent":"458.778439ms","remote":"127.0.0.1:59682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:57:42.53604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:57:42.079445Z","time spent":"456.419576ms","remote":"127.0.0.1:59750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:57:43.055292Z","caller":"traceutil/trace.go:171","msg":"trace[1798073016] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"137.650568ms","start":"2026-05-01T00:57:42.917613Z","end":"2026-05-01T00:57:43.055264Z","steps":["trace[1798073016] 'process raft request' (duration: 137.434233ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:57:43.099253Z","caller":"traceutil/trace.go:171","msg":"trace[138174488] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"169.763454ms","start":"2026-05-01T00:57:42.929469Z","end":"2026-05-01T00:57:43.099233Z","steps":["trace[138174488] 'process raft request' (duration: 164.732399ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:57:43.099509Z","caller":"traceutil/trace.go:171","msg":"trace[1578676590] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"101.209097ms","start":"2026-05-01T00:57:42.998274Z","end":"2026-05-01T00:57:43.099483Z","steps":["trace[1578676590] 'process raft request' (duration: 100.920719ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:57:43.100514Z","caller":"traceutil/trace.go:171","msg":"trace[1111955988] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"103.974941ms","start":"2026-05-01T00:57:42.996502Z","end":"2026-05-01T00:57:43.100477Z","steps":["trace[1111955988] 'read index received' (duration: 58.477086ms)","trace[1111955988] 'applied index is now lower than readState.Index' (duration: 45.496045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:57:43.100846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.32398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xz2gt\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-05-01T00:57:43.100904Z","caller":"traceutil/trace.go:171","msg":"trace[1971035910] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xz2gt; range_end:; response_count:1; response_revision:623; }","duration":"104.439743ms","start":"2026-05-01T00:57:42.996444Z","end":"2026-05-01T00:57:43.100884Z","steps":["trace[1971035910] 'agreement among raft nodes before linearized reading' (duration: 104.155036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:58:24.875809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.572381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:58:24.876075Z","caller":"traceutil/trace.go:171","msg":"trace[1125626841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"116.943431ms","start":"2026-05-01T00:58:24.759079Z","end":"2026-05-01T00:58:24.876023Z","steps":["trace[1125626841] 'range keys from in-memory index tree' (duration: 116.399046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:58:45.225008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:58:44.818252Z","time spent":"406.747518ms","remote":"127.0.0.1:38928","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-01T00:58:45.22526Z","caller":"traceutil/trace.go:171","msg":"trace[915448281] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"465.382998ms","start":"2026-05-01T00:58:44.759852Z","end":"2026-05-01T00:58:45.225235Z","steps":["trace[915448281] 'process raft request' (duration: 463.465066ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:58:45.225255Z","caller":"traceutil/trace.go:171","msg":"trace[521940572] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"194.184687ms","start":"2026-05-01T00:58:45.03103Z","end":"2026-05-01T00:58:45.225214Z","steps":["trace[521940572] 'read index received' (duration: 192.273435ms)","trace[521940572] 'applied index is now lower than readState.Index' (duration: 1.909692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:58:45.225475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:58:44.759829Z","time spent":"465.543772ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:58:45.225555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.506415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-01T00:58:45.225652Z","caller":"traceutil/trace.go:171","msg":"trace[1452259970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1419; }","duration":"194.68374ms","start":"2026-05-01T00:58:45.030946Z","end":"2026-05-01T00:58:45.225629Z","steps":["trace[1452259970] 'agreement among raft nodes before linearized reading' (duration: 194.387932ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:58:48.323952Z","caller":"traceutil/trace.go:171","msg":"trace[661873526] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"339.637407ms","start":"2026-05-01T00:58:47.984287Z","end":"2026-05-01T00:58:48.323925Z","steps":["trace[661873526] 'process raft request' (duration: 339.476643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:58:48.324149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:58:47.984259Z","time spent":"339.784921ms","remote":"127.0.0.1:59652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4663,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:58:48.324431Z","caller":"traceutil/trace.go:171","msg":"trace[1548443528] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1506; }","duration":"177.321671ms","start":"2026-05-01T00:58:48.147059Z","end":"2026-05-01T00:58:48.324381Z","steps":["trace[1548443528] 'read index received' (duration: 177.307031ms)","trace[1548443528] 'applied index is now lower than readState.Index' (duration: 12.1µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:58:48.324704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.585758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:58:48.324752Z","caller":"traceutil/trace.go:171","msg":"trace[1591131679] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:0; response_revision:1461; }","duration":"177.704771ms","start":"2026-05-01T00:58:48.147031Z","end":"2026-05-01T00:58:48.324736Z","steps":["trace[1591131679] 'agreement among raft nodes before linearized reading' (duration: 177.503075ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:00:48.120967Z","caller":"traceutil/trace.go:171","msg":"trace[909021869] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"199.749097ms","start":"2026-05-01T01:00:47.921187Z","end":"2026-05-01T01:00:48.120936Z","steps":["trace[909021869] 'process raft request' (duration: 199.429278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:03:47.328911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.860664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T01:03:47.32912Z","caller":"traceutil/trace.go:171","msg":"trace[1437880810] transaction","detail":"{read_only:false; response_revision:3751; number_of_response:1; }","duration":"309.911119ms","start":"2026-05-01T01:03:47.019163Z","end":"2026-05-01T01:03:47.329074Z","steps":["trace[1437880810] 'process raft request' (duration: 137.648454ms)","trace[1437880810] 'compare' (duration: 171.631577ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:03:47.329141Z","caller":"traceutil/trace.go:171","msg":"trace[1193446918] transaction","detail":"{read_only:false; response_revision:3752; number_of_response:1; }","duration":"308.044939ms","start":"2026-05-01T01:03:47.021063Z","end":"2026-05-01T01:03:47.329108Z","steps":["trace[1193446918] 'process raft request' (duration: 307.936736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:03:47.32956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:03:47.021053Z","time spent":"308.416319ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:03:47.329348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:03:47.019142Z","time spent":"310.078384ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:05:01.517502Z","caller":"traceutil/trace.go:171","msg":"trace[1214329394] linearizableReadLoop","detail":"{readStateIndex:4830; appliedIndex:4828; }","duration":"108.186262ms","start":"2026-05-01T01:05:01.409276Z","end":"2026-05-01T01:05:01.517462Z","steps":["trace[1214329394] 'read index received' (duration: 39.107063ms)","trace[1214329394] 'applied index is now lower than readState.Index' (duration: 69.077469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:05:01.517888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.717266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:11983"} {"level":"info","ts":"2026-05-01T01:05:01.517939Z","caller":"traceutil/trace.go:171","msg":"trace[1881474427] range","detail":"{range_begin:/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4652; }","duration":"105.825689ms","start":"2026-05-01T01:05:01.412101Z","end":"2026-05-01T01:05:01.517927Z","steps":["trace[1881474427] 'agreement among raft nodes before linearized reading' (duration: 105.571701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:05:01.518013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.711666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:61926"} {"level":"warn","ts":"2026-05-01T01:05:01.518084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.062908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:11983"} {"level":"info","ts":"2026-05-01T01:05:01.518118Z","caller":"traceutil/trace.go:171","msg":"trace[328413500] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4652; }","duration":"108.848241ms","start":"2026-05-01T01:05:01.409248Z","end":"2026-05-01T01:05:01.518096Z","steps":["trace[328413500] 'agreement among raft nodes before linearized reading' (duration: 108.427758ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:05:01.51818Z","caller":"traceutil/trace.go:171","msg":"trace[939320734] range","detail":"{range_begin:/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4652; }","duration":"105.196352ms","start":"2026-05-01T01:05:01.412962Z","end":"2026-05-01T01:05:01.518158Z","steps":["trace[939320734] 'agreement among raft nodes before linearized reading' (duration: 104.895754ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:05:01.518358Z","caller":"traceutil/trace.go:171","msg":"trace[999333624] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"109.58581ms","start":"2026-05-01T01:05:01.40807Z","end":"2026-05-01T01:05:01.517656Z","steps":["trace[999333624] 'process raft request' (duration: 109.173209ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:05:06.814924Z","caller":"traceutil/trace.go:171","msg":"trace[1049150860] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"129.702381ms","start":"2026-05-01T01:05:06.685195Z","end":"2026-05-01T01:05:06.814897Z","steps":["trace[1049150860] 'process raft request' (duration: 129.543257ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:05:06.81964Z","caller":"traceutil/trace.go:171","msg":"trace[77083977] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"119.109446ms","start":"2026-05-01T01:05:06.700486Z","end":"2026-05-01T01:05:06.819595Z","steps":["trace[77083977] 'process raft request' (duration: 119.012873ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:05:10.679751Z","caller":"traceutil/trace.go:171","msg":"trace[1852740333] linearizableReadLoop","detail":"{readStateIndex:5135; appliedIndex:5134; }","duration":"199.234033ms","start":"2026-05-01T01:05:10.480494Z","end":"2026-05-01T01:05:10.679728Z","steps":["trace[1852740333] 'read index received' (duration: 198.983556ms)","trace[1852740333] 'applied index is now lower than readState.Index' (duration: 249.517µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:05:10.679839Z","caller":"traceutil/trace.go:171","msg":"trace[347583925] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"226.677272ms","start":"2026-05-01T01:05:10.453119Z","end":"2026-05-01T01:05:10.679797Z","steps":["trace[347583925] 'process raft request' (duration: 226.469046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:05:10.68004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.51027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c15ffa25-bdd5-40c0-a14a-04e1bb45f6d8\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-01T01:05:10.680129Z","caller":"traceutil/trace.go:171","msg":"trace[373398953] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c15ffa25-bdd5-40c0-a14a-04e1bb45f6d8; range_end:; response_count:1; response_revision:4949; }","duration":"199.639843ms","start":"2026-05-01T01:05:10.48047Z","end":"2026-05-01T01:05:10.680109Z","steps":["trace[373398953] 'agreement among raft nodes before linearized reading' (duration: 199.363496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:05:10.680287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.060402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:778249"} {"level":"info","ts":"2026-05-01T01:05:10.680334Z","caller":"traceutil/trace.go:171","msg":"trace[1848422655] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4949; }","duration":"178.120784ms","start":"2026-05-01T01:05:10.502201Z","end":"2026-05-01T01:05:10.680322Z","steps":["trace[1848422655] 'agreement among raft nodes before linearized reading' (duration: 177.674252ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:05:11.10852Z","caller":"traceutil/trace.go:171","msg":"trace[1059036387] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"101.40049ms","start":"2026-05-01T01:05:11.007086Z","end":"2026-05-01T01:05:11.108487Z","steps":["trace[1059036387] 'process raft request' (duration: 89.930861ms)","trace[1059036387] 'compare' (duration: 11.350216ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:05:20.922591Z","caller":"traceutil/trace.go:171","msg":"trace[1764042035] linearizableReadLoop","detail":"{readStateIndex:5348; appliedIndex:5347; }","duration":"244.580403ms","start":"2026-05-01T01:05:20.677986Z","end":"2026-05-01T01:05:20.922566Z","steps":["trace[1764042035] 'read index received' (duration: 244.392878ms)","trace[1764042035] 'applied index is now lower than readState.Index' (duration: 186.614µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:05:20.922895Z","caller":"traceutil/trace.go:171","msg":"trace[1197738893] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"276.204453ms","start":"2026-05-01T01:05:20.646678Z","end":"2026-05-01T01:05:20.922882Z","steps":["trace[1197738893] 'process raft request' (duration: 275.761931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:05:20.92313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.130888ms","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-05-01T01:05:20.923176Z","caller":"traceutil/trace.go:171","msg":"trace[1159031156] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5156; }","duration":"245.23175ms","start":"2026-05-01T01:05:20.677932Z","end":"2026-05-01T01:05:20.923164Z","steps":["trace[1159031156] 'agreement among raft nodes before linearized reading' (duration: 245.146168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:05:20.959935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.249ms","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-05-01T01:05:20.96001Z","caller":"traceutil/trace.go:171","msg":"trace[1342105813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5157; }","duration":"242.353682ms","start":"2026-05-01T01:05:20.717637Z","end":"2026-05-01T01:05:20.959991Z","steps":["trace[1342105813] 'agreement among raft nodes before linearized reading' (duration: 242.149907ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:05:39.176413Z","caller":"traceutil/trace.go:171","msg":"trace[124035215] linearizableReadLoop","detail":"{readStateIndex:5680; appliedIndex:5679; }","duration":"109.362114ms","start":"2026-05-01T01:05:39.067029Z","end":"2026-05-01T01:05:39.176392Z","steps":["trace[124035215] 'read index received' (duration: 109.196099ms)","trace[124035215] 'applied index is now lower than readState.Index' (duration: 165.065µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:05:39.176476Z","caller":"traceutil/trace.go:171","msg":"trace[1009028612] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"116.218357ms","start":"2026-05-01T01:05:39.060222Z","end":"2026-05-01T01:05:39.17644Z","steps":["trace[1009028612] 'process raft request' (duration: 116.041292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:05:39.17658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.531998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:05:39.176626Z","caller":"traceutil/trace.go:171","msg":"trace[373690537] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5481; }","duration":"109.61621ms","start":"2026-05-01T01:05:39.066998Z","end":"2026-05-01T01:05:39.176614Z","steps":["trace[373690537] 'agreement among raft nodes before linearized reading' (duration: 109.517217ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:07:06.070084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3028} {"level":"info","ts":"2026-05-01T01:07:06.156477Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3028,"took":"82.811379ms","hash":111009812,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":32239616,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-01T01:07:06.156583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":111009812,"revision":3028,"compact-revision":-1} {"level":"warn","ts":"2026-05-01T01:08:40.669335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.507637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:08:40.669404Z","caller":"traceutil/trace.go:171","msg":"trace[321567491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6728; }","duration":"109.63319ms","start":"2026-05-01T01:08:40.559756Z","end":"2026-05-01T01:08:40.669389Z","steps":["trace[321567491] 'range keys from in-memory index tree' (duration: 109.426805ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:11:31.754694Z","caller":"traceutil/trace.go:171","msg":"trace[1801726563] linearizableReadLoop","detail":"{readStateIndex:8433; appliedIndex:8432; }","duration":"238.686822ms","start":"2026-05-01T01:11:31.515982Z","end":"2026-05-01T01:11:31.754669Z","steps":["trace[1801726563] 'read index received' (duration: 238.449526ms)","trace[1801726563] 'applied index is now lower than readState.Index' (duration: 236.256µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:11:31.754755Z","caller":"traceutil/trace.go:171","msg":"trace[117666188] transaction","detail":"{read_only:false; response_revision:8149; number_of_response:1; }","duration":"243.966365ms","start":"2026-05-01T01:11:31.510757Z","end":"2026-05-01T01:11:31.754723Z","steps":["trace[117666188] 'process raft request' (duration: 243.755399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:11:31.75487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.869167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:11:31.754943Z","caller":"traceutil/trace.go:171","msg":"trace[991449672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:8149; }","duration":"238.959729ms","start":"2026-05-01T01:11:31.515962Z","end":"2026-05-01T01:11:31.754922Z","steps":["trace[991449672] 'agreement among raft nodes before linearized reading' (duration: 238.833096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:11:31.754956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.557124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-01T01:11:31.754992Z","caller":"traceutil/trace.go:171","msg":"trace[1590158448] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8149; }","duration":"236.655977ms","start":"2026-05-01T01:11:31.518327Z","end":"2026-05-01T01:11:31.754983Z","steps":["trace[1590158448] 'agreement among raft nodes before linearized reading' (duration: 236.480092ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:11:32.012278Z","caller":"traceutil/trace.go:171","msg":"trace[1111813153] transaction","detail":"{read_only:false; response_revision:8150; number_of_response:1; }","duration":"253.086631ms","start":"2026-05-01T01:11:31.759164Z","end":"2026-05-01T01:11:32.01225Z","steps":["trace[1111813153] 'process raft request' (duration: 252.949687ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:11:32.013621Z","caller":"traceutil/trace.go:171","msg":"trace[712494042] linearizableReadLoop","detail":"{readStateIndex:8435; appliedIndex:8433; }","duration":"253.162693ms","start":"2026-05-01T01:11:31.76042Z","end":"2026-05-01T01:11:32.013582Z","steps":["trace[712494042] 'read index received' (duration: 251.696434ms)","trace[712494042] 'applied index is now lower than readState.Index' (duration: 1.464859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:11:32.013981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.731072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-01T01:11:32.014068Z","caller":"traceutil/trace.go:171","msg":"trace[1082940142] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8151; }","duration":"245.882127ms","start":"2026-05-01T01:11:31.768156Z","end":"2026-05-01T01:11:32.014039Z","steps":["trace[1082940142] 'agreement among raft nodes before linearized reading' (duration: 245.610989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:11:32.014134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.627837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19444"} {"level":"info","ts":"2026-05-01T01:11:32.014244Z","caller":"traceutil/trace.go:171","msg":"trace[1279407448] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8151; }","duration":"253.858413ms","start":"2026-05-01T01:11:31.76036Z","end":"2026-05-01T01:11:32.014218Z","steps":["trace[1279407448] 'agreement among raft nodes before linearized reading' (duration: 253.351568ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:11:32.01434Z","caller":"traceutil/trace.go:171","msg":"trace[229313569] transaction","detail":"{read_only:false; response_revision:8151; number_of_response:1; }","duration":"254.255853ms","start":"2026-05-01T01:11:31.759473Z","end":"2026-05-01T01:11:32.013729Z","steps":["trace[229313569] 'process raft request' (duration: 253.828951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:11:32.014235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.422585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jj5ch\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-01T01:11:32.0153Z","caller":"traceutil/trace.go:171","msg":"trace[1574973292] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jj5ch; range_end:; response_count:1; response_revision:8151; }","duration":"110.562585ms","start":"2026-05-01T01:11:31.904707Z","end":"2026-05-01T01:11:32.015269Z","steps":["trace[1574973292] 'agreement among raft nodes before linearized reading' (duration: 109.174457ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:11:42.8596Z","caller":"traceutil/trace.go:171","msg":"trace[1174933433] transaction","detail":"{read_only:false; response_revision:8207; number_of_response:1; }","duration":"207.053749ms","start":"2026-05-01T01:11:42.652519Z","end":"2026-05-01T01:11:42.859572Z","steps":["trace[1174933433] 'process raft request' (duration: 206.868814ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:11:44.455458Z","caller":"traceutil/trace.go:171","msg":"trace[2120960342] transaction","detail":"{read_only:false; response_revision:8215; number_of_response:1; }","duration":"161.326801ms","start":"2026-05-01T01:11:44.294107Z","end":"2026-05-01T01:11:44.455433Z","steps":["trace[2120960342] 'process raft request' (duration: 161.166046ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:12:06.077587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6208} {"level":"info","ts":"2026-05-01T01:12:06.174188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6208,"took":"94.760927ms","hash":3178908992,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":22958080,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-01T01:12:06.17426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3178908992,"revision":6208,"compact-revision":3028} {"level":"info","ts":"2026-05-01T01:12:11.124585Z","caller":"traceutil/trace.go:171","msg":"trace[204814391] linearizableReadLoop","detail":"{readStateIndex:8709; appliedIndex:8708; }","duration":"171.750543ms","start":"2026-05-01T01:12:10.952811Z","end":"2026-05-01T01:12:11.124562Z","steps":["trace[204814391] 'read index received' (duration: 171.570008ms)","trace[204814391] 'applied index is now lower than readState.Index' (duration: 179.255µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:12:11.124826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.961359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:12:11.1249Z","caller":"traceutil/trace.go:171","msg":"trace[1067948563] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8413; }","duration":"172.124964ms","start":"2026-05-01T01:12:10.952757Z","end":"2026-05-01T01:12:11.124882Z","steps":["trace[1067948563] 'agreement among raft nodes before linearized reading' (duration: 171.953499ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:12:11.12521Z","caller":"traceutil/trace.go:171","msg":"trace[1243742690] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"172.706849ms","start":"2026-05-01T01:12:10.95249Z","end":"2026-05-01T01:12:11.125197Z","steps":["trace[1243742690] 'process raft request' (duration: 171.884437ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:12:23.25893Z","caller":"traceutil/trace.go:171","msg":"trace[1872239405] linearizableReadLoop","detail":"{readStateIndex:8819; appliedIndex:8818; }","duration":"251.154373ms","start":"2026-05-01T01:12:23.007687Z","end":"2026-05-01T01:12:23.258841Z","steps":["trace[1872239405] 'read index received' (duration: 6.111145ms)","trace[1872239405] 'applied index is now lower than readState.Index' (duration: 245.041488ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:12:23.258978Z","caller":"traceutil/trace.go:171","msg":"trace[890570025] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"270.495697ms","start":"2026-05-01T01:12:22.988439Z","end":"2026-05-01T01:12:23.258935Z","steps":["trace[890570025] 'process raft request' (duration: 270.031564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:12:23.25928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.572124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-01T01:12:23.259367Z","caller":"traceutil/trace.go:171","msg":"trace[937799417] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8518; }","duration":"251.694238ms","start":"2026-05-01T01:12:23.007651Z","end":"2026-05-01T01:12:23.259345Z","steps":["trace[937799417] 'agreement among raft nodes before linearized reading' (duration: 251.340808ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:12:23.264689Z","caller":"traceutil/trace.go:171","msg":"trace[2015580535] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"157.891237ms","start":"2026-05-01T01:12:23.106771Z","end":"2026-05-01T01:12:23.264662Z","steps":["trace[2015580535] 'process raft request' (duration: 157.657371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:12:23.264882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.979714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-mw9d7\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-05-01T01:12:23.264977Z","caller":"traceutil/trace.go:171","msg":"trace[234540647] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"103.388281ms","start":"2026-05-01T01:12:23.161573Z","end":"2026-05-01T01:12:23.264961Z","steps":["trace[234540647] 'process raft request' (duration: 103.000001ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:12:23.264997Z","caller":"traceutil/trace.go:171","msg":"trace[143722345] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-mw9d7; range_end:; response_count:1; response_revision:8520; }","duration":"160.106587ms","start":"2026-05-01T01:12:23.104868Z","end":"2026-05-01T01:12:23.264974Z","steps":["trace[143722345] 'agreement among raft nodes before linearized reading' (duration: 159.867591ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:12:24.105545Z","caller":"traceutil/trace.go:171","msg":"trace[1336616000] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"132.364326ms","start":"2026-05-01T01:12:23.973157Z","end":"2026-05-01T01:12:24.105521Z","steps":["trace[1336616000] 'process raft request' (duration: 132.221272ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:13:06.602633Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244b.wal"} {"level":"info","ts":"2026-05-01T01:14:26.750513Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-01T01:14:26.75829Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-01T01:14:26.758444Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-01T01:17:06.083548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8340} {"level":"info","ts":"2026-05-01T01:17:06.161195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8340,"took":"75.085617ms","hash":2559229380,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":26951680,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-01T01:17:06.161271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2559229380,"revision":8340,"compact-revision":6208} {"level":"warn","ts":"2026-05-01T01:17:07.191488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.097347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T01:17:07.191629Z","caller":"traceutil/trace.go:171","msg":"trace[1926297123] linearizableReadLoop","detail":"{readStateIndex:11448; appliedIndex:11447; }","duration":"148.750067ms","start":"2026-05-01T01:17:07.042863Z","end":"2026-05-01T01:17:07.191613Z","steps":["trace[1926297123] 'read index received' (duration: 43.469546ms)","trace[1926297123] 'applied index is now lower than readState.Index' (duration: 105.279401ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:17:07.191698Z","caller":"traceutil/trace.go:171","msg":"trace[1262143597] transaction","detail":"{read_only:false; response_revision:11072; number_of_response:1; }","duration":"192.216952ms","start":"2026-05-01T01:17:06.999469Z","end":"2026-05-01T01:17:07.191686Z","steps":["trace[1262143597] 'process raft request' (duration: 86.839708ms)","trace[1262143597] 'compare' (duration: 104.973883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:17:07.19197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.088486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-01T01:17:07.192036Z","caller":"traceutil/trace.go:171","msg":"trace[1257072803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11072; }","duration":"149.21983ms","start":"2026-05-01T01:17:07.042801Z","end":"2026-05-01T01:17:07.192021Z","steps":["trace[1257072803] 'agreement among raft nodes before linearized reading' (duration: 148.911712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:17:07.208989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.913499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T01:17:07.20912Z","caller":"traceutil/trace.go:171","msg":"trace[1320231125] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:11073; }","duration":"106.387611ms","start":"2026-05-01T01:17:07.102715Z","end":"2026-05-01T01:17:07.209102Z","steps":["trace[1320231125] 'agreement among raft nodes before linearized reading' (duration: 105.907569ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:22:06.090803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11068} {"level":"info","ts":"2026-05-01T01:22:06.169878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11068,"took":"76.51881ms","hash":2944767612,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":27529216,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-01T01:22:06.169933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2944767612,"revision":11068,"compact-revision":8340} {"level":"info","ts":"2026-05-01T01:27:06.09988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13101} {"level":"info","ts":"2026-05-01T01:27:06.178977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13101,"took":"76.907532ms","hash":2539722665,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":23642112,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-01T01:27:06.179031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2539722665,"revision":13101,"compact-revision":11068} {"level":"info","ts":"2026-05-01T01:27:49.708746Z","caller":"traceutil/trace.go:171","msg":"trace[1299180237] transaction","detail":"{read_only:false; response_revision:14971; number_of_response:1; }","duration":"120.623735ms","start":"2026-05-01T01:27:49.588072Z","end":"2026-05-01T01:27:49.708695Z","steps":["trace[1299180237] 'process raft request' (duration: 120.296315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:27:58.194255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.728355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ef467fa5-9257-4574-9662-88275c5cc92e\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-05-01T01:27:58.194362Z","caller":"traceutil/trace.go:171","msg":"trace[621093870] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ef467fa5-9257-4574-9662-88275c5cc92e; range_end:; response_count:1; response_revision:15127; }","duration":"121.893089ms","start":"2026-05-01T01:27:58.072446Z","end":"2026-05-01T01:27:58.194339Z","steps":["trace[621093870] 'range keys from in-memory index tree' (duration: 121.5335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:27:58.194259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.646749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0579f372-f087-48a2-ab5b-0d11fe42675e\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-05-01T01:27:58.194492Z","caller":"traceutil/trace.go:171","msg":"trace[752034491] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0579f372-f087-48a2-ab5b-0d11fe42675e; range_end:; response_count:1; response_revision:15127; }","duration":"122.907446ms","start":"2026-05-01T01:27:58.071534Z","end":"2026-05-01T01:27:58.194441Z","steps":["trace[752034491] 'range keys from in-memory index tree' (duration: 122.477725ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:27:58.510399Z","caller":"traceutil/trace.go:171","msg":"trace[2091961887] linearizableReadLoop","detail":"{readStateIndex:15653; appliedIndex:15652; }","duration":"171.345157ms","start":"2026-05-01T01:27:58.339029Z","end":"2026-05-01T01:27:58.510374Z","steps":["trace[2091961887] 'read index received' (duration: 171.208994ms)","trace[2091961887] 'applied index is now lower than readState.Index' (duration: 135.013µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:27:58.510576Z","caller":"traceutil/trace.go:171","msg":"trace[1509450381] transaction","detail":"{read_only:false; response_revision:15128; number_of_response:1; }","duration":"242.960994ms","start":"2026-05-01T01:27:58.267602Z","end":"2026-05-01T01:27:58.510563Z","steps":["trace[1509450381] 'process raft request' (duration: 242.605435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:27:58.511039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.083722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-01T01:27:58.511103Z","caller":"traceutil/trace.go:171","msg":"trace[1311077654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15128; }","duration":"167.164374ms","start":"2026-05-01T01:27:58.343923Z","end":"2026-05-01T01:27:58.511087Z","steps":["trace[1311077654] 'agreement among raft nodes before linearized reading' (duration: 166.918408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:27:58.511105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.050797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-01T01:27:58.511105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.941312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T01:27:58.511192Z","caller":"traceutil/trace.go:171","msg":"trace[2057468324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15128; }","duration":"172.187521ms","start":"2026-05-01T01:27:58.338985Z","end":"2026-05-01T01:27:58.511172Z","steps":["trace[2057468324] 'agreement among raft nodes before linearized reading' (duration: 171.865502ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:27:58.511195Z","caller":"traceutil/trace.go:171","msg":"trace[46400920] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:15128; }","duration":"108.079316ms","start":"2026-05-01T01:27:58.4031Z","end":"2026-05-01T01:27:58.511179Z","steps":["trace[46400920] 'agreement among raft nodes before linearized reading' (duration: 107.941882ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:29:38.236089Z","caller":"traceutil/trace.go:171","msg":"trace[1323932100] transaction","detail":"{read_only:false; response_revision:16214; number_of_response:1; }","duration":"106.612206ms","start":"2026-05-01T01:29:38.12944Z","end":"2026-05-01T01:29:38.236052Z","steps":["trace[1323932100] 'process raft request' (duration: 106.378079ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:29:38.372654Z","caller":"traceutil/trace.go:171","msg":"trace[1011188937] transaction","detail":"{read_only:false; response_revision:16216; number_of_response:1; }","duration":"121.19966ms","start":"2026-05-01T01:29:38.251425Z","end":"2026-05-01T01:29:38.372625Z","steps":["trace[1011188937] 'process raft request' (duration: 119.100753ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:31:02.679381Z","caller":"traceutil/trace.go:171","msg":"trace[86961576] transaction","detail":"{read_only:false; response_revision:16778; number_of_response:1; }","duration":"100.438019ms","start":"2026-05-01T01:31:02.578882Z","end":"2026-05-01T01:31:02.67932Z","steps":["trace[86961576] 'process raft request' (duration: 64.253779ms)","trace[86961576] 'compare' (duration: 35.931852ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:32:06.108156Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14737} {"level":"info","ts":"2026-05-01T01:32:06.187701Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14737,"took":"75.212515ms","hash":396974753,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":29704192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-01T01:32:06.187833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":396974753,"revision":14737,"compact-revision":13101} {"level":"info","ts":"2026-05-01T01:35:20.333256Z","caller":"traceutil/trace.go:171","msg":"trace[1245923145] transaction","detail":"{read_only:false; response_revision:18788; number_of_response:1; }","duration":"103.928212ms","start":"2026-05-01T01:35:20.229304Z","end":"2026-05-01T01:35:20.333233Z","steps":["trace[1245923145] 'process raft request' (duration: 103.759417ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:35:20.699079Z","caller":"traceutil/trace.go:171","msg":"trace[1880968916] linearizableReadLoop","detail":"{readStateIndex:19435; appliedIndex:19434; }","duration":"139.036593ms","start":"2026-05-01T01:35:20.560015Z","end":"2026-05-01T01:35:20.699051Z","steps":["trace[1880968916] 'read index received' (duration: 127.716737ms)","trace[1880968916] 'applied index is now lower than readState.Index' (duration: 11.318486ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:35:20.699516Z","caller":"traceutil/trace.go:171","msg":"trace[1544511721] transaction","detail":"{read_only:false; response_revision:18790; number_of_response:1; }","duration":"192.51793ms","start":"2026-05-01T01:35:20.506966Z","end":"2026-05-01T01:35:20.699484Z","steps":["trace[1544511721] 'process raft request' (duration: 180.846464ms)","trace[1544511721] 'compare' (duration: 11.07132ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:35:20.699695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.642779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:35:20.699816Z","caller":"traceutil/trace.go:171","msg":"trace[907547298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18790; }","duration":"139.835114ms","start":"2026-05-01T01:35:20.55995Z","end":"2026-05-01T01:35:20.699785Z","steps":["trace[907547298] 'agreement among raft nodes before linearized reading' (duration: 139.457644ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:36:04.546443Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-01T01:36:04.551413Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-01T01:36:04.551506Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-01T01:37:06.11517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17310} {"level":"info","ts":"2026-05-01T01:37:06.214745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17310,"took":"96.802514ms","hash":378647927,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":33628160,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-01T01:37:06.214806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":378647927,"revision":17310,"compact-revision":14737} {"level":"info","ts":"2026-05-01T01:40:03.625952Z","caller":"traceutil/trace.go:171","msg":"trace[1129104171] transaction","detail":"{read_only:false; response_revision:21033; number_of_response:1; }","duration":"202.629402ms","start":"2026-05-01T01:40:03.423286Z","end":"2026-05-01T01:40:03.625916Z","steps":["trace[1129104171] 'process raft request' (duration: 202.400946ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:40:03.721932Z","caller":"traceutil/trace.go:171","msg":"trace[55996728] transaction","detail":"{read_only:false; response_revision:21034; number_of_response:1; }","duration":"273.507696ms","start":"2026-05-01T01:40:03.448388Z","end":"2026-05-01T01:40:03.721896Z","steps":["trace[55996728] 'process raft request' (duration: 238.955273ms)","trace[55996728] 'compare' (duration: 34.151753ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:40:03.721913Z","caller":"traceutil/trace.go:171","msg":"trace[1826156535] linearizableReadLoop","detail":"{readStateIndex:21753; appliedIndex:21751; }","duration":"161.500331ms","start":"2026-05-01T01:40:03.560378Z","end":"2026-05-01T01:40:03.721878Z","steps":["trace[1826156535] 'read index received' (duration: 65.358035ms)","trace[1826156535] 'applied index is now lower than readState.Index' (duration: 96.140826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:40:03.722083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.685056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:40:03.72224Z","caller":"traceutil/trace.go:171","msg":"trace[713180615] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:21034; }","duration":"161.851321ms","start":"2026-05-01T01:40:03.560322Z","end":"2026-05-01T01:40:03.722173Z","steps":["trace[713180615] 'agreement among raft nodes before linearized reading' (duration: 161.661916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:40:04.076611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.356853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-01T01:40:04.076711Z","caller":"traceutil/trace.go:171","msg":"trace[651789865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21034; }","duration":"246.518747ms","start":"2026-05-01T01:40:03.830172Z","end":"2026-05-01T01:40:04.07669Z","steps":["trace[651789865] 'range keys from in-memory index tree' (duration: 246.108136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:40:04.076728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.702695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-01T01:40:04.076807Z","caller":"traceutil/trace.go:171","msg":"trace[147569557] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:21034; }","duration":"242.833239ms","start":"2026-05-01T01:40:03.833954Z","end":"2026-05-01T01:40:04.076787Z","steps":["trace[147569557] 'range keys from in-memory index tree' (duration: 242.463148ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:40:04.331895Z","caller":"traceutil/trace.go:171","msg":"trace[1645158663] transaction","detail":"{read_only:false; response_revision:21035; number_of_response:1; }","duration":"250.742561ms","start":"2026-05-01T01:40:04.08109Z","end":"2026-05-01T01:40:04.331832Z","steps":["trace[1645158663] 'process raft request' (duration: 250.461614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:40:04.687205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.485134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:40:04.687759Z","caller":"traceutil/trace.go:171","msg":"trace[1010379645] transaction","detail":"{read_only:false; response_revision:21036; number_of_response:1; }","duration":"349.168769ms","start":"2026-05-01T01:40:04.338553Z","end":"2026-05-01T01:40:04.687722Z","steps":["trace[1010379645] 'process raft request' (duration: 91.07678ms)","trace[1010379645] 'compare' (duration: 257.265447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:40:04.687957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:40:04.338512Z","time spent":"349.355264ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:40:04.735676Z","caller":"traceutil/trace.go:171","msg":"trace[1580250333] linearizableReadLoop","detail":"{readStateIndex:21756; appliedIndex:21754; }","duration":"175.997702ms","start":"2026-05-01T01:40:04.55966Z","end":"2026-05-01T01:40:04.735657Z","steps":["trace[1580250333] 'read index received' (duration: 22.410785ms)","trace[1580250333] 'applied index is now lower than readState.Index' (duration: 153.585957ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:40:04.735993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.325851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:40:04.736066Z","caller":"traceutil/trace.go:171","msg":"trace[1592569431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21036; }","duration":"176.432933ms","start":"2026-05-01T01:40:04.559619Z","end":"2026-05-01T01:40:04.736052Z","steps":["trace[1592569431] 'agreement among raft nodes before linearized reading' (duration: 176.30347ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:40:05.19612Z","caller":"traceutil/trace.go:171","msg":"trace[469630862] transaction","detail":"{read_only:false; response_revision:21039; number_of_response:1; }","duration":"451.947346ms","start":"2026-05-01T01:40:04.74415Z","end":"2026-05-01T01:40:05.196097Z","steps":["trace[469630862] 'process raft request' (duration: 451.802182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:40:05.196289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:40:04.744133Z","time spent":"452.065349ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:40:05.221396Z","caller":"traceutil/trace.go:171","msg":"trace[1591102397] linearizableReadLoop","detail":"{readStateIndex:21760; appliedIndex:21758; }","duration":"252.331174ms","start":"2026-05-01T01:40:04.968948Z","end":"2026-05-01T01:40:05.221279Z","steps":["trace[1591102397] 'read index received' (duration: 227.058202ms)","trace[1591102397] 'applied index is now lower than readState.Index' (duration: 25.271822ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:40:05.221555Z","caller":"traceutil/trace.go:171","msg":"trace[973447213] transaction","detail":"{read_only:false; response_revision:21040; number_of_response:1; }","duration":"274.242876ms","start":"2026-05-01T01:40:04.947258Z","end":"2026-05-01T01:40:05.221501Z","steps":["trace[973447213] 'process raft request' (duration: 272.218761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:40:05.221726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.753056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-01T01:40:05.22178Z","caller":"traceutil/trace.go:171","msg":"trace[1301337851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21040; }","duration":"252.859169ms","start":"2026-05-01T01:40:04.968904Z","end":"2026-05-01T01:40:05.221764Z","steps":["trace[1301337851] 'agreement among raft nodes before linearized reading' (duration: 252.643983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:40:05.221843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.260796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-01T01:40:05.221965Z","caller":"traceutil/trace.go:171","msg":"trace[1934983128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21040; }","duration":"251.40946ms","start":"2026-05-01T01:40:04.97054Z","end":"2026-05-01T01:40:05.221949Z","steps":["trace[1934983128] 'agreement among raft nodes before linearized reading' (duration: 251.139393ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:42:06.124212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19770} {"level":"info","ts":"2026-05-01T01:42:06.259036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19770,"took":"130.658719ms","hash":4205892927,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":29790208,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-01T01:42:06.259114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4205892927,"revision":19770,"compact-revision":17310} {"level":"warn","ts":"2026-05-01T01:43:53.865108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.280093ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:43:53.865275Z","caller":"traceutil/trace.go:171","msg":"trace[1968637582] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23443; }","duration":"152.467688ms","start":"2026-05-01T01:43:53.712786Z","end":"2026-05-01T01:43:53.865253Z","steps":["trace[1968637582] 'range keys from in-memory index tree' (duration: 152.261383ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:43:53.865529Z","caller":"traceutil/trace.go:171","msg":"trace[1710284095] transaction","detail":"{read_only:false; response_revision:23444; number_of_response:1; }","duration":"249.647792ms","start":"2026-05-01T01:43:53.615848Z","end":"2026-05-01T01:43:53.865496Z","steps":["trace[1710284095] 'process raft request' (duration: 162.787356ms)","trace[1710284095] 'compare' (duration: 86.380633ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:06.129349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22038} {"level":"info","ts":"2026-05-01T01:47:06.239727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22038,"took":"106.934992ms","hash":3894694909,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":43343872,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-01T01:47:06.239791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3894694909,"revision":22038,"compact-revision":19770} {"level":"info","ts":"2026-05-01T01:47:18.6096Z","caller":"traceutil/trace.go:171","msg":"trace[1019831283] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"132.095002ms","start":"2026-05-01T01:47:18.477464Z","end":"2026-05-01T01:47:18.609559Z","steps":["trace[1019831283] 'process raft request' (duration: 131.802604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:18.882047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.044684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:18.882142Z","caller":"traceutil/trace.go:171","msg":"trace[58548145] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25515; }","duration":"165.193797ms","start":"2026-05-01T01:47:18.716926Z","end":"2026-05-01T01:47:18.88212Z","steps":["trace[58548145] 'count revisions from in-memory index tree' (duration: 164.949141ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:19.250289Z","caller":"traceutil/trace.go:171","msg":"trace[2078828989] linearizableReadLoop","detail":"{readStateIndex:26362; appliedIndex:26361; }","duration":"284.469482ms","start":"2026-05-01T01:47:18.965788Z","end":"2026-05-01T01:47:19.250257Z","steps":["trace[2078828989] 'read index received' (duration: 284.273197ms)","trace[2078828989] 'applied index is now lower than readState.Index' (duration: 195.275µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:19.250386Z","caller":"traceutil/trace.go:171","msg":"trace[1307443504] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"291.8014ms","start":"2026-05-01T01:47:18.958552Z","end":"2026-05-01T01:47:19.250353Z","steps":["trace[1307443504] 'process raft request' (duration: 291.504242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:19.250547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.74429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-01T01:47:19.250593Z","caller":"traceutil/trace.go:171","msg":"trace[2059096903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25516; }","duration":"284.851022ms","start":"2026-05-01T01:47:18.965731Z","end":"2026-05-01T01:47:19.250582Z","steps":["trace[2059096903] 'agreement among raft nodes before linearized reading' (duration: 284.651226ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:19.266118Z","caller":"traceutil/trace.go:171","msg":"trace[2055042046] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"240.550805ms","start":"2026-05-01T01:47:19.025535Z","end":"2026-05-01T01:47:19.266086Z","steps":["trace[2055042046] 'process raft request' (duration: 240.37158ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:19.434267Z","caller":"traceutil/trace.go:171","msg":"trace[728024874] linearizableReadLoop","detail":"{readStateIndex:26365; appliedIndex:26364; }","duration":"127.680942ms","start":"2026-05-01T01:47:19.30655Z","end":"2026-05-01T01:47:19.434231Z","steps":["trace[728024874] 'read index received' (duration: 126.365797ms)","trace[728024874] 'applied index is now lower than readState.Index' (duration: 1.313255ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:19.434558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.97687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-01T01:47:19.434608Z","caller":"traceutil/trace.go:171","msg":"trace[480758077] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:25519; }","duration":"128.071053ms","start":"2026-05-01T01:47:19.306526Z","end":"2026-05-01T01:47:19.434597Z","steps":["trace[480758077] 'agreement among raft nodes before linearized reading' (duration: 127.834506ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:19.434872Z","caller":"traceutil/trace.go:171","msg":"trace[549714531] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"147.875478ms","start":"2026-05-01T01:47:19.286982Z","end":"2026-05-01T01:47:19.434858Z","steps":["trace[549714531] 'process raft request' (duration: 146.006448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:19.792827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.462069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:19.792899Z","caller":"traceutil/trace.go:171","msg":"trace[1700192910] linearizableReadLoop","detail":"{readStateIndex:26368; appliedIndex:26367; }","duration":"260.440032ms","start":"2026-05-01T01:47:19.532448Z","end":"2026-05-01T01:47:19.792888Z","steps":["trace[1700192910] 'read index received' (duration: 99.824499ms)","trace[1700192910] 'applied index is now lower than readState.Index' (duration: 160.614593ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:19.792949Z","caller":"traceutil/trace.go:171","msg":"trace[359995] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"326.453017ms","start":"2026-05-01T01:47:19.466489Z","end":"2026-05-01T01:47:19.792942Z","steps":["trace[359995] 'process raft request' (duration: 165.809403ms)","trace[359995] 'compare' (duration: 160.058608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:19.792985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:19.466471Z","time spent":"326.494518ms","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:19.793172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.735891ms","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:6"} {"level":"info","ts":"2026-05-01T01:47:19.793207Z","caller":"traceutil/trace.go:171","msg":"trace[310952170] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25521; }","duration":"260.825072ms","start":"2026-05-01T01:47:19.532374Z","end":"2026-05-01T01:47:19.793199Z","steps":["trace[310952170] 'agreement among raft nodes before linearized reading' (duration: 260.72909ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:19.795168Z","caller":"traceutil/trace.go:171","msg":"trace[948688053] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"211.239792ms","start":"2026-05-01T01:47:19.583894Z","end":"2026-05-01T01:47:19.795134Z","steps":["trace[948688053] 'process raft request' (duration: 210.669707ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:19.79545Z","caller":"traceutil/trace.go:171","msg":"trace[542005883] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"208.732484ms","start":"2026-05-01T01:47:19.586705Z","end":"2026-05-01T01:47:19.795438Z","steps":["trace[542005883] 'process raft request' (duration: 208.018045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:19.795839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.506086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-01T01:47:19.795903Z","caller":"traceutil/trace.go:171","msg":"trace[734011195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25523; }","duration":"205.576479ms","start":"2026-05-01T01:47:19.590295Z","end":"2026-05-01T01:47:19.795872Z","steps":["trace[734011195] 'agreement among raft nodes before linearized reading' (duration: 205.470446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:19.796071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.913649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-01T01:47:19.796105Z","caller":"traceutil/trace.go:171","msg":"trace[239719720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25523; }","duration":"208.96468ms","start":"2026-05-01T01:47:19.587132Z","end":"2026-05-01T01:47:19.796097Z","steps":["trace[239719720] 'agreement among raft nodes before linearized reading' (duration: 208.880928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:19.796348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.524275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-01T01:47:19.796359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.838399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:19.796434Z","caller":"traceutil/trace.go:171","msg":"trace[1546625186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25523; }","duration":"209.644168ms","start":"2026-05-01T01:47:19.586771Z","end":"2026-05-01T01:47:19.796415Z","steps":["trace[1546625186] 'agreement among raft nodes before linearized reading' (duration: 209.384221ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:19.796483Z","caller":"traceutil/trace.go:171","msg":"trace[2083666637] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25523; }","duration":"258.991873ms","start":"2026-05-01T01:47:19.53746Z","end":"2026-05-01T01:47:19.796452Z","steps":["trace[2083666637] 'agreement among raft nodes before linearized reading' (duration: 258.754067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:19.796628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.613098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:19.796676Z","caller":"traceutil/trace.go:171","msg":"trace[2101806830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25523; }","duration":"236.67617ms","start":"2026-05-01T01:47:19.559986Z","end":"2026-05-01T01:47:19.796663Z","steps":["trace[2101806830] 'agreement among raft nodes before linearized reading' (duration: 236.607148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:20.076216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.806115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:20.076315Z","caller":"traceutil/trace.go:171","msg":"trace[322332618] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"269.079186ms","start":"2026-05-01T01:47:19.80722Z","end":"2026-05-01T01:47:20.0763Z","steps":["trace[322332618] 'process raft request' (duration: 120.110358ms)","trace[322332618] 'compare' (duration: 148.380812ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:20.104612Z","caller":"traceutil/trace.go:171","msg":"trace[2066201778] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"297.106604ms","start":"2026-05-01T01:47:19.807487Z","end":"2026-05-01T01:47:20.104593Z","steps":["trace[2066201778] 'process raft request' (duration: 297.018492ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:20.104664Z","caller":"traceutil/trace.go:171","msg":"trace[1573774665] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"297.341161ms","start":"2026-05-01T01:47:19.807278Z","end":"2026-05-01T01:47:20.104619Z","steps":["trace[1573774665] 'process raft request' (duration: 297.100814ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:20.104951Z","caller":"traceutil/trace.go:171","msg":"trace[776651736] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"275.550842ms","start":"2026-05-01T01:47:19.829391Z","end":"2026-05-01T01:47:20.104941Z","steps":["trace[776651736] 'process raft request' (duration: 275.391817ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:20.104946Z","caller":"traceutil/trace.go:171","msg":"trace[1884568663] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"296.845577ms","start":"2026-05-01T01:47:19.808085Z","end":"2026-05-01T01:47:20.10493Z","steps":["trace[1884568663] 'process raft request' (duration: 296.467286ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:20.104912Z","caller":"traceutil/trace.go:171","msg":"trace[1741555348] linearizableReadLoop","detail":"{readStateIndex:26374; appliedIndex:26370; }","duration":"295.218583ms","start":"2026-05-01T01:47:19.809667Z","end":"2026-05-01T01:47:20.104885Z","steps":["trace[1741555348] 'read index received' (duration: 117.61626ms)","trace[1741555348] 'applied index is now lower than readState.Index' (duration: 177.601123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:20.105081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.397209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-01T01:47:20.105122Z","caller":"traceutil/trace.go:171","msg":"trace[1394104952] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:25528; }","duration":"295.472921ms","start":"2026-05-01T01:47:19.809639Z","end":"2026-05-01T01:47:20.105112Z","steps":["trace[1394104952] 'agreement among raft nodes before linearized reading' (duration: 295.330857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:20.354384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.439565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:20.354488Z","caller":"traceutil/trace.go:171","msg":"trace[1698366730] linearizableReadLoop","detail":"{readStateIndex:26376; appliedIndex:26375; }","duration":"249.431595ms","start":"2026-05-01T01:47:20.105041Z","end":"2026-05-01T01:47:20.354472Z","steps":["trace[1698366730] 'read index received' (duration: 107.687231ms)","trace[1698366730] 'applied index is now lower than readState.Index' (duration: 141.743204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:20.354703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.793797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:20.354859Z","caller":"traceutil/trace.go:171","msg":"trace[495591004] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25529; }","duration":"479.042284ms","start":"2026-05-01T01:47:19.875788Z","end":"2026-05-01T01:47:20.354831Z","steps":["trace[495591004] 'agreement among raft nodes before linearized reading' (duration: 478.794018ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:20.354848Z","caller":"traceutil/trace.go:171","msg":"trace[1691305084] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"420.432229ms","start":"2026-05-01T01:47:19.934379Z","end":"2026-05-01T01:47:20.354811Z","steps":["trace[1691305084] 'process raft request' (duration: 278.457689ms)","trace[1691305084] 'compare' (duration: 141.188748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:20.354913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:19.875761Z","time spent":"479.140126ms","remote":"127.0.0.1:48570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true "} {"level":"warn","ts":"2026-05-01T01:47:20.35494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.333639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-01T01:47:20.354959Z","caller":"traceutil/trace.go:171","msg":"trace[1529366736] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25529; }","duration":"247.359759ms","start":"2026-05-01T01:47:20.107593Z","end":"2026-05-01T01:47:20.354953Z","steps":["trace[1529366736] 'agreement among raft nodes before linearized reading' (duration: 247.229846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:20.354949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.631248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-01T01:47:20.354984Z","caller":"traceutil/trace.go:171","msg":"trace[153456918] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25529; }","duration":"341.741811ms","start":"2026-05-01T01:47:20.013233Z","end":"2026-05-01T01:47:20.354975Z","steps":["trace[153456918] 'agreement among raft nodes before linearized reading' (duration: 341.587317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:20.355003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:20.013208Z","time spent":"341.791153ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-01T01:47:20.354807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.202652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-01T01:47:20.35503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:19.93434Z","time spent":"420.590413ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:20.35507Z","caller":"traceutil/trace.go:171","msg":"trace[516628239] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25529; }","duration":"248.479619ms","start":"2026-05-01T01:47:20.106583Z","end":"2026-05-01T01:47:20.355062Z","steps":["trace[516628239] 'agreement among raft nodes before linearized reading' (duration: 248.155071ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:20.927832Z","caller":"traceutil/trace.go:171","msg":"trace[801909498] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"184.138499ms","start":"2026-05-01T01:47:20.743675Z","end":"2026-05-01T01:47:20.927814Z","steps":["trace[801909498] 'process raft request' (duration: 87.311841ms)","trace[801909498] 'compare' (duration: 96.634943ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:20.927804Z","caller":"traceutil/trace.go:171","msg":"trace[143835843] linearizableReadLoop","detail":"{readStateIndex:26379; appliedIndex:26378; }","duration":"127.934838ms","start":"2026-05-01T01:47:20.799833Z","end":"2026-05-01T01:47:20.927768Z","steps":["trace[143835843] 'read index received' (duration: 31.157442ms)","trace[143835843] 'applied index is now lower than readState.Index' (duration: 96.776486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:20.928045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.196016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-01T01:47:20.928098Z","caller":"traceutil/trace.go:171","msg":"trace[1292911656] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25532; }","duration":"128.313599ms","start":"2026-05-01T01:47:20.799774Z","end":"2026-05-01T01:47:20.928088Z","steps":["trace[1292911656] 'agreement among raft nodes before linearized reading' (duration: 128.090053ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:21.473709Z","caller":"traceutil/trace.go:171","msg":"trace[1217977815] linearizableReadLoop","detail":"{readStateIndex:26380; appliedIndex:26379; }","duration":"196.433952ms","start":"2026-05-01T01:47:21.277244Z","end":"2026-05-01T01:47:21.473678Z","steps":["trace[1217977815] 'read index received' (duration: 196.210736ms)","trace[1217977815] 'applied index is now lower than readState.Index' (duration: 221.796µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:21.473773Z","caller":"traceutil/trace.go:171","msg":"trace[2073619184] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"201.8899ms","start":"2026-05-01T01:47:21.271856Z","end":"2026-05-01T01:47:21.473746Z","steps":["trace[2073619184] 'process raft request' (duration: 201.636383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:21.473985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.716579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-01T01:47:21.47406Z","caller":"traceutil/trace.go:171","msg":"trace[488953487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25533; }","duration":"196.811872ms","start":"2026-05-01T01:47:21.277236Z","end":"2026-05-01T01:47:21.474048Z","steps":["trace[488953487] 'agreement among raft nodes before linearized reading' (duration: 196.601966ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:21.609083Z","caller":"traceutil/trace.go:171","msg":"trace[1514225631] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"159.40019ms","start":"2026-05-01T01:47:21.449635Z","end":"2026-05-01T01:47:21.609036Z","steps":["trace[1514225631] 'process raft request' (duration: 126.411878ms)","trace[1514225631] 'compare' (duration: 32.741665ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:21.60918Z","caller":"traceutil/trace.go:171","msg":"trace[470560766] linearizableReadLoop","detail":"{readStateIndex:26381; appliedIndex:26380; }","duration":"135.373ms","start":"2026-05-01T01:47:21.473795Z","end":"2026-05-01T01:47:21.609168Z","steps":["trace[470560766] 'read index received' (duration: 102.338938ms)","trace[470560766] 'applied index is now lower than readState.Index' (duration: 33.032752ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:21.609109Z","caller":"traceutil/trace.go:171","msg":"trace[1696736884] transaction","detail":"{read_only:false; response_revision:25535; number_of_response:1; }","duration":"130.242181ms","start":"2026-05-01T01:47:21.478841Z","end":"2026-05-01T01:47:21.609083Z","steps":["trace[1696736884] 'process raft request' (duration: 130.128758ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:21.609887Z","caller":"traceutil/trace.go:171","msg":"trace[1983140258] transaction","detail":"{read_only:false; response_revision:25536; number_of_response:1; }","duration":"115.750201ms","start":"2026-05-01T01:47:21.49411Z","end":"2026-05-01T01:47:21.60986Z","steps":["trace[1983140258] 'process raft request' (duration: 114.930158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:21.610205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.48918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-01T01:47:21.610286Z","caller":"traceutil/trace.go:171","msg":"trace[46624428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25536; }","duration":"170.640735ms","start":"2026-05-01T01:47:21.439627Z","end":"2026-05-01T01:47:21.610268Z","steps":["trace[46624428] 'agreement among raft nodes before linearized reading' (duration: 169.868444ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:21.741837Z","caller":"traceutil/trace.go:171","msg":"trace[1023805334] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"126.772368ms","start":"2026-05-01T01:47:21.615025Z","end":"2026-05-01T01:47:21.741797Z","steps":["trace[1023805334] 'process raft request' (duration: 124.649561ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:22.052962Z","caller":"traceutil/trace.go:171","msg":"trace[1426027353] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"249.327512ms","start":"2026-05-01T01:47:21.803596Z","end":"2026-05-01T01:47:22.052924Z","steps":["trace[1426027353] 'process raft request' (duration: 249.043075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:22.311786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.140292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:22.311921Z","caller":"traceutil/trace.go:171","msg":"trace[1892150078] linearizableReadLoop","detail":"{readStateIndex:26386; appliedIndex:26385; }","duration":"236.631008ms","start":"2026-05-01T01:47:22.075273Z","end":"2026-05-01T01:47:22.311904Z","steps":["trace[1892150078] 'read index received' (duration: 38.881µs)","trace[1892150078] 'applied index is now lower than readState.Index' (duration: 236.590867ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:22.311974Z","caller":"traceutil/trace.go:171","msg":"trace[2081448293] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"502.435896ms","start":"2026-05-01T01:47:21.809491Z","end":"2026-05-01T01:47:22.311927Z","steps":["trace[2081448293] 'process raft request' (duration: 258.063698ms)","trace[2081448293] 'compare' (duration: 243.913135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:22.312092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.826794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-01T01:47:22.312129Z","caller":"traceutil/trace.go:171","msg":"trace[387405310] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25539; }","duration":"236.910416ms","start":"2026-05-01T01:47:22.075209Z","end":"2026-05-01T01:47:22.312119Z","steps":["trace[387405310] 'agreement among raft nodes before linearized reading' (duration: 236.766352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:22.312187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:21.809469Z","time spent":"502.602761ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:22.325702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.565642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-01T01:47:22.325747Z","caller":"traceutil/trace.go:171","msg":"trace[1297578898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25540; }","duration":"217.657645ms","start":"2026-05-01T01:47:22.108081Z","end":"2026-05-01T01:47:22.325738Z","steps":["trace[1297578898] 'agreement among raft nodes before linearized reading' (duration: 217.518571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:22.32584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.521814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-01T01:47:22.325902Z","caller":"traceutil/trace.go:171","msg":"trace[1732278004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25540; }","duration":"216.604186ms","start":"2026-05-01T01:47:22.109286Z","end":"2026-05-01T01:47:22.32589Z","steps":["trace[1732278004] 'agreement among raft nodes before linearized reading' (duration: 216.447052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:22.325993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.399638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-01T01:47:22.326035Z","caller":"traceutil/trace.go:171","msg":"trace[550209664] transaction","detail":"{read_only:false; response_revision:25540; number_of_response:1; }","duration":"216.117234ms","start":"2026-05-01T01:47:22.10991Z","end":"2026-05-01T01:47:22.326027Z","steps":["trace[550209664] 'process raft request' (duration: 215.503977ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:22.326037Z","caller":"traceutil/trace.go:171","msg":"trace[265104064] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25540; }","duration":"217.550022ms","start":"2026-05-01T01:47:22.108475Z","end":"2026-05-01T01:47:22.326025Z","steps":["trace[265104064] 'agreement among raft nodes before linearized reading' (duration: 217.447419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:22.32609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.888164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-01T01:47:22.326174Z","caller":"traceutil/trace.go:171","msg":"trace[1240545425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25540; }","duration":"244.036588ms","start":"2026-05-01T01:47:22.082122Z","end":"2026-05-01T01:47:22.326159Z","steps":["trace[1240545425] 'agreement among raft nodes before linearized reading' (duration: 243.848883ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:22.452962Z","caller":"traceutil/trace.go:171","msg":"trace[70725433] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"122.065591ms","start":"2026-05-01T01:47:22.330866Z","end":"2026-05-01T01:47:22.452931Z","steps":["trace[70725433] 'process raft request' (duration: 120.334384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:22.788907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.432463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:22.78951Z","caller":"traceutil/trace.go:171","msg":"trace[938364507] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"457.849572ms","start":"2026-05-01T01:47:22.331629Z","end":"2026-05-01T01:47:22.789478Z","steps":["trace[938364507] 'process raft request' (duration: 457.39188ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:22.789599Z","caller":"traceutil/trace.go:171","msg":"trace[1651280749] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"428.002894ms","start":"2026-05-01T01:47:22.361582Z","end":"2026-05-01T01:47:22.789585Z","steps":["trace[1651280749] 'process raft request' (duration: 427.591733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:22.789673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:22.331624Z","time spent":"457.967105ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:22.789723Z","caller":"traceutil/trace.go:171","msg":"trace[1074059500] linearizableReadLoop","detail":"{readStateIndex:26392; appliedIndex:26387; }","duration":"394.520098ms","start":"2026-05-01T01:47:22.395193Z","end":"2026-05-01T01:47:22.789713Z","steps":["trace[1074059500] 'read index received' (duration: 56.068916ms)","trace[1074059500] 'applied index is now lower than readState.Index' (duration: 338.450132ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:22.789669Z","caller":"traceutil/trace.go:171","msg":"trace[1323161197] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"457.963215ms","start":"2026-05-01T01:47:22.331584Z","end":"2026-05-01T01:47:22.789547Z","steps":["trace[1323161197] 'process raft request' (duration: 284.783751ms)","trace[1323161197] 'compare' (duration: 172.091134ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:22.789678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:22.361567Z","time spent":"428.072096ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:22.789544Z","caller":"traceutil/trace.go:171","msg":"trace[2140165094] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"457.033989ms","start":"2026-05-01T01:47:22.332489Z","end":"2026-05-01T01:47:22.789523Z","steps":["trace[2140165094] 'process raft request' (duration: 456.638728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:22.789828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.646872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-01T01:47:22.790075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:22.331562Z","time spent":"458.313034ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:22.790137Z","caller":"traceutil/trace.go:171","msg":"trace[613345807] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25546; }","duration":"394.994911ms","start":"2026-05-01T01:47:22.395117Z","end":"2026-05-01T01:47:22.790112Z","steps":["trace[613345807] 'agreement among raft nodes before linearized reading' (duration: 394.639211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:22.790228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:22.395091Z","time spent":"395.125864ms","remote":"127.0.0.1:60036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-01T01:47:22.790279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:22.332468Z","time spent":"457.583133ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:23.607234Z","caller":"traceutil/trace.go:171","msg":"trace[1824936133] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"127.20246ms","start":"2026-05-01T01:47:23.480013Z","end":"2026-05-01T01:47:23.607215Z","steps":["trace[1824936133] 'process raft request' (duration: 126.975494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:23.902401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.801773ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:23.902542Z","caller":"traceutil/trace.go:171","msg":"trace[214292952] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25547; }","duration":"189.967307ms","start":"2026-05-01T01:47:23.712556Z","end":"2026-05-01T01:47:23.902524Z","steps":["trace[214292952] 'range keys from in-memory index tree' (duration: 189.788853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:23.90282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.094655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:23.902928Z","caller":"traceutil/trace.go:171","msg":"trace[284093978] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"286.816236ms","start":"2026-05-01T01:47:23.616095Z","end":"2026-05-01T01:47:23.902911Z","steps":["trace[284093978] 'process raft request' (duration: 138.529336ms)","trace[284093978] 'compare' (duration: 147.91944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:24.217611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.418402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:24.217896Z","caller":"traceutil/trace.go:171","msg":"trace[575922509] transaction","detail":"{read_only:false; response_revision:25550; number_of_response:1; }","duration":"158.447945ms","start":"2026-05-01T01:47:24.05943Z","end":"2026-05-01T01:47:24.217878Z","steps":["trace[575922509] 'process raft request' (duration: 158.302371ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:24.218051Z","caller":"traceutil/trace.go:171","msg":"trace[1661167506] linearizableReadLoop","detail":"{readStateIndex:26396; appliedIndex:26394; }","duration":"471.232442ms","start":"2026-05-01T01:47:23.746784Z","end":"2026-05-01T01:47:24.218017Z","steps":["trace[1661167506] 'read index received' (duration: 7.844081ms)","trace[1661167506] 'applied index is now lower than readState.Index' (duration: 463.385751ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:24.218169Z","caller":"traceutil/trace.go:171","msg":"trace[25272396] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"601.975188ms","start":"2026-05-01T01:47:23.616167Z","end":"2026-05-01T01:47:24.218142Z","steps":["trace[25272396] 'process raft request' (duration: 421.943691ms)","trace[25272396] 'compare' (duration: 179.0544ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:24.218276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.805604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-01T01:47:24.218328Z","caller":"traceutil/trace.go:171","msg":"trace[402401157] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25550; }","duration":"226.902076ms","start":"2026-05-01T01:47:23.991411Z","end":"2026-05-01T01:47:24.218313Z","steps":["trace[402401157] 'agreement among raft nodes before linearized reading' (duration: 226.800783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:24.218316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.793197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:24.218414Z","caller":"traceutil/trace.go:171","msg":"trace[1308827369] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25550; }","duration":"316.957351ms","start":"2026-05-01T01:47:23.901432Z","end":"2026-05-01T01:47:24.218389Z","steps":["trace[1308827369] 'agreement among raft nodes before linearized reading' (duration: 316.781726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:24.218458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:23.901415Z","time spent":"317.033973ms","remote":"127.0.0.1:46746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-05-01T01:47:24.21857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.760527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-01T01:47:24.218657Z","caller":"traceutil/trace.go:171","msg":"trace[61768971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25550; }","duration":"471.86476ms","start":"2026-05-01T01:47:23.746772Z","end":"2026-05-01T01:47:24.218636Z","steps":["trace[61768971] 'agreement among raft nodes before linearized reading' (duration: 471.367376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:24.218706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:23.746719Z","time spent":"471.977962ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-01T01:47:24.218785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:23.616143Z","time spent":"602.089931ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:24.492217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.466487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:24.492473Z","caller":"traceutil/trace.go:171","msg":"trace[1392711610] transaction","detail":"{read_only:false; response_revision:25552; number_of_response:1; }","duration":"173.557203ms","start":"2026-05-01T01:47:24.318897Z","end":"2026-05-01T01:47:24.492454Z","steps":["trace[1392711610] 'process raft request' (duration: 173.44037ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:24.492517Z","caller":"traceutil/trace.go:171","msg":"trace[1579028308] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"269.761306ms","start":"2026-05-01T01:47:24.222712Z","end":"2026-05-01T01:47:24.492473Z","steps":["trace[1579028308] 'process raft request' (duration: 131.942779ms)","trace[1579028308] 'compare' (duration: 137.090276ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:24.492585Z","caller":"traceutil/trace.go:171","msg":"trace[1855646221] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"162.189295ms","start":"2026-05-01T01:47:24.330358Z","end":"2026-05-01T01:47:24.492547Z","steps":["trace[1855646221] 'process raft request' (duration: 162.058642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:24.638084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.00541ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-01T01:47:24.638203Z","caller":"traceutil/trace.go:171","msg":"trace[272083055] linearizableReadLoop","detail":"{readStateIndex:26401; appliedIndex:26400; }","duration":"181.215191ms","start":"2026-05-01T01:47:24.456967Z","end":"2026-05-01T01:47:24.638182Z","steps":["trace[272083055] 'read index received' (duration: 39.094907ms)","trace[272083055] 'applied index is now lower than readState.Index' (duration: 142.118494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:24.63861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.643362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-01T01:47:24.638646Z","caller":"traceutil/trace.go:171","msg":"trace[286442748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25553; }","duration":"181.727834ms","start":"2026-05-01T01:47:24.456906Z","end":"2026-05-01T01:47:24.638634Z","steps":["trace[286442748] 'agreement among raft nodes before linearized reading' (duration: 181.533749ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:24.646775Z","caller":"traceutil/trace.go:171","msg":"trace[1677147611] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"178.446106ms","start":"2026-05-01T01:47:24.468318Z","end":"2026-05-01T01:47:24.646764Z","steps":["trace[1677147611] 'process raft request' (duration: 178.341223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:24.647093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.561897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:24.647127Z","caller":"traceutil/trace.go:171","msg":"trace[1501926363] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25554; }","duration":"148.641779ms","start":"2026-05-01T01:47:24.498478Z","end":"2026-05-01T01:47:24.647119Z","steps":["trace[1501926363] 'agreement among raft nodes before linearized reading' (duration: 148.578038ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:29.759365Z","caller":"traceutil/trace.go:171","msg":"trace[1986582994] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"133.344276ms","start":"2026-05-01T01:47:29.625989Z","end":"2026-05-01T01:47:29.759333Z","steps":["trace[1986582994] 'process raft request' (duration: 133.136361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:30.027553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.77061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-01T01:47:30.027621Z","caller":"traceutil/trace.go:171","msg":"trace[970885151] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25591; }","duration":"122.897443ms","start":"2026-05-01T01:47:29.90471Z","end":"2026-05-01T01:47:30.027608Z","steps":["trace[970885151] 'range keys from in-memory index tree' (duration: 122.553234ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:30.051866Z","caller":"traceutil/trace.go:171","msg":"trace[1006773030] linearizableReadLoop","detail":"{readStateIndex:26441; appliedIndex:26440; }","duration":"106.062528ms","start":"2026-05-01T01:47:29.945775Z","end":"2026-05-01T01:47:30.051837Z","steps":["trace[1006773030] 'read index received' (duration: 105.836382ms)","trace[1006773030] 'applied index is now lower than readState.Index' (duration: 224.496µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:30.051907Z","caller":"traceutil/trace.go:171","msg":"trace[1636367021] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"130.793126ms","start":"2026-05-01T01:47:29.92108Z","end":"2026-05-01T01:47:30.051873Z","steps":["trace[1636367021] 'process raft request' (duration: 130.55583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:30.052047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.253093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:30.052132Z","caller":"traceutil/trace.go:171","msg":"trace[1717074763] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:25592; }","duration":"106.412088ms","start":"2026-05-01T01:47:29.9457Z","end":"2026-05-01T01:47:30.052112Z","steps":["trace[1717074763] 'agreement among raft nodes before linearized reading' (duration: 106.255113ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:33.902076Z","caller":"traceutil/trace.go:171","msg":"trace[1256775858] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"128.802613ms","start":"2026-05-01T01:47:33.773249Z","end":"2026-05-01T01:47:33.902052Z","steps":["trace[1256775858] 'process raft request' (duration: 128.581447ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:34.459481Z","caller":"traceutil/trace.go:171","msg":"trace[1330655574] linearizableReadLoop","detail":"{readStateIndex:26468; appliedIndex:26467; }","duration":"204.360266ms","start":"2026-05-01T01:47:34.255087Z","end":"2026-05-01T01:47:34.459447Z","steps":["trace[1330655574] 'read index received' (duration: 204.175272ms)","trace[1330655574] 'applied index is now lower than readState.Index' (duration: 183.604µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:34.45956Z","caller":"traceutil/trace.go:171","msg":"trace[1852533656] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"394.327623ms","start":"2026-05-01T01:47:34.065189Z","end":"2026-05-01T01:47:34.459516Z","steps":["trace[1852533656] 'process raft request' (duration: 393.986063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:34.459765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.065167Z","time spent":"394.493658ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:34.459779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.679965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-01T01:47:34.459847Z","caller":"traceutil/trace.go:171","msg":"trace[303923670] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25619; }","duration":"204.806808ms","start":"2026-05-01T01:47:34.255021Z","end":"2026-05-01T01:47:34.459828Z","steps":["trace[303923670] 'agreement among raft nodes before linearized reading' (duration: 204.583032ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:34.478071Z","caller":"traceutil/trace.go:171","msg":"trace[666669712] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"211.768796ms","start":"2026-05-01T01:47:34.266278Z","end":"2026-05-01T01:47:34.478047Z","steps":["trace[666669712] 'process raft request' (duration: 211.493998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:34.478243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.797981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-01T01:47:34.478318Z","caller":"traceutil/trace.go:171","msg":"trace[807345126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25620; }","duration":"196.915234ms","start":"2026-05-01T01:47:34.281383Z","end":"2026-05-01T01:47:34.478299Z","steps":["trace[807345126] 'agreement among raft nodes before linearized reading' (duration: 196.564865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:34.856063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.61758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:34.856177Z","caller":"traceutil/trace.go:171","msg":"trace[1268568076] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"373.15405ms","start":"2026-05-01T01:47:34.483003Z","end":"2026-05-01T01:47:34.856158Z","steps":["trace[1268568076] 'process raft request' (duration: 96.338515ms)","trace[1268568076] 'compare' (duration: 276.497587ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:34.856237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.482967Z","time spent":"373.241243ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:35.41789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.087281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:35.418124Z","caller":"traceutil/trace.go:171","msg":"trace[1155307923] linearizableReadLoop","detail":"{readStateIndex:26473; appliedIndex:26470; }","duration":"880.140319ms","start":"2026-05-01T01:47:34.537962Z","end":"2026-05-01T01:47:35.418103Z","steps":["trace[1155307923] 'read index received' (duration: 41.392988ms)","trace[1155307923] 'applied index is now lower than readState.Index' (duration: 838.746001ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:35.418223Z","caller":"traceutil/trace.go:171","msg":"trace[1923392070] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"882.115803ms","start":"2026-05-01T01:47:34.536097Z","end":"2026-05-01T01:47:35.418213Z","steps":["trace[1923392070] 'process raft request' (duration: 881.900088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:35.41828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.536075Z","time spent":"882.171915ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:35.418369Z","caller":"traceutil/trace.go:171","msg":"trace[512481958] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"882.36996ms","start":"2026-05-01T01:47:34.535956Z","end":"2026-05-01T01:47:35.418326Z","steps":["trace[512481958] 'process raft request' (duration: 495.742195ms)","trace[512481958] 'compare' (duration: 385.911936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:35.418574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.53594Z","time spent":"882.511194ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:35.418815Z","caller":"traceutil/trace.go:171","msg":"trace[735716718] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"588.551675ms","start":"2026-05-01T01:47:34.83025Z","end":"2026-05-01T01:47:35.418801Z","steps":["trace[735716718] 'process raft request' (duration: 587.813345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:35.418884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.830235Z","time spent":"588.613716ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:35.418837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.837319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-01T01:47:35.418962Z","caller":"traceutil/trace.go:171","msg":"trace[1249070959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25625; }","duration":"881.013494ms","start":"2026-05-01T01:47:34.537921Z","end":"2026-05-01T01:47:35.418934Z","steps":["trace[1249070959] 'agreement among raft nodes before linearized reading' (duration: 880.593962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:35.419027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.537908Z","time spent":"881.108876ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-01T01:47:35.419093Z","caller":"traceutil/trace.go:171","msg":"trace[742160768] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"585.948905ms","start":"2026-05-01T01:47:34.833102Z","end":"2026-05-01T01:47:35.419051Z","steps":["trace[742160768] 'process raft request' (duration: 585.537204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:35.419317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.833071Z","time spent":"586.114549ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:35.705576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.773225ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-01T01:47:35.707356Z","caller":"traceutil/trace.go:171","msg":"trace[605902199] linearizableReadLoop","detail":"{readStateIndex:26477; appliedIndex:26473; }","duration":"289.179459ms","start":"2026-05-01T01:47:35.418156Z","end":"2026-05-01T01:47:35.707335Z","steps":["trace[605902199] 'read index received' (duration: 320.748µs)","trace[605902199] 'applied index is now lower than readState.Index' (duration: 288.857301ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:35.707372Z","caller":"traceutil/trace.go:171","msg":"trace[286017237] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"761.228685ms","start":"2026-05-01T01:47:34.946118Z","end":"2026-05-01T01:47:35.707347Z","steps":["trace[286017237] 'process raft request' (duration: 759.543819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:35.707513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.946103Z","time spent":"761.328847ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:36.012948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453769192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:36.013059Z","caller":"traceutil/trace.go:171","msg":"trace[2076170697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25627; }","duration":"1.453926645s","start":"2026-05-01T01:47:34.559105Z","end":"2026-05-01T01:47:36.013032Z","steps":["trace[2076170697] 'agreement among raft nodes before linearized reading' (duration: 1.148345492s)","trace[2076170697] 'range keys from in-memory index tree' (duration: 305.418788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:36.01302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154586091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-01T01:47:36.013112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.559096Z","time spent":"1.454007328s","remote":"127.0.0.1:59626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-01T01:47:36.013148Z","caller":"traceutil/trace.go:171","msg":"trace[2003293807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25627; }","duration":"1.154769766s","start":"2026-05-01T01:47:34.858347Z","end":"2026-05-01T01:47:36.013117Z","steps":["trace[2003293807] 'agreement among raft nodes before linearized reading' (duration: 849.047909ms)","trace[2003293807] 'range keys from in-memory index tree' (duration: 305.432369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:36.013205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.858336Z","time spent":"1.154855059s","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T01:47:36.01327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150542341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-01T01:47:36.013379Z","caller":"traceutil/trace.go:171","msg":"trace[1691226017] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25627; }","duration":"1.150708835s","start":"2026-05-01T01:47:34.862644Z","end":"2026-05-01T01:47:36.013353Z","steps":["trace[1691226017] 'agreement among raft nodes before linearized reading' (duration: 844.746572ms)","trace[1691226017] 'range keys from in-memory index tree' (duration: 305.709916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:36.013432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.862633Z","time spent":"1.150782328s","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-01T01:47:36.013493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15418144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-01T01:47:36.013596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257275907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-01T01:47:36.013624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.906535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:36.013656Z","caller":"traceutil/trace.go:171","msg":"trace[174153425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25627; }","duration":"1.25737261s","start":"2026-05-01T01:47:34.756269Z","end":"2026-05-01T01:47:36.013641Z","steps":["trace[174153425] 'agreement among raft nodes before linearized reading' (duration: 951.132649ms)","trace[174153425] 'range keys from in-memory index tree' (duration: 306.055986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:36.013688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167653694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:36.013688Z","caller":"traceutil/trace.go:171","msg":"trace[991560740] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25627; }","duration":"609.027258ms","start":"2026-05-01T01:47:35.404639Z","end":"2026-05-01T01:47:36.013666Z","steps":["trace[991560740] 'agreement among raft nodes before linearized reading' (duration: 302.732436ms)","trace[991560740] 'count revisions from in-memory index tree' (duration: 306.180149ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:36.013706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.756249Z","time spent":"1.257446442s","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-01T01:47:36.01364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.960371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-01T01:47:36.013747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:35.404617Z","time spent":"609.113461ms","remote":"127.0.0.1:48874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-05-01T01:47:36.01371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258810249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:36.013834Z","caller":"traceutil/trace.go:171","msg":"trace[1489355170] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25627; }","duration":"1.259003714s","start":"2026-05-01T01:47:34.754797Z","end":"2026-05-01T01:47:36.0138Z","steps":["trace[1489355170] 'agreement among raft nodes before linearized reading' (duration: 952.610509ms)","trace[1489355170] 'count revisions from in-memory index tree' (duration: 306.227491ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:36.013855Z","caller":"traceutil/trace.go:171","msg":"trace[1649245124] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25627; }","duration":"739.263569ms","start":"2026-05-01T01:47:35.27452Z","end":"2026-05-01T01:47:36.013783Z","steps":["trace[1649245124] 'agreement among raft nodes before linearized reading' (duration: 432.861975ms)","trace[1649245124] 'count revisions from in-memory index tree' (duration: 306.146068ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:36.013605Z","caller":"traceutil/trace.go:171","msg":"trace[2021250546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25627; }","duration":"1.154355705s","start":"2026-05-01T01:47:34.859218Z","end":"2026-05-01T01:47:36.013574Z","steps":["trace[2021250546] 'agreement among raft nodes before linearized reading' (duration: 848.121574ms)","trace[2021250546] 'range keys from in-memory index tree' (duration: 305.880931ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:36.013717Z","caller":"traceutil/trace.go:171","msg":"trace[1978006133] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25627; }","duration":"1.167716035s","start":"2026-05-01T01:47:34.845992Z","end":"2026-05-01T01:47:36.013708Z","steps":["trace[1978006133] 'agreement among raft nodes before linearized reading' (duration: 861.378232ms)","trace[1978006133] 'count revisions from in-memory index tree' (duration: 306.282601ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:36.013912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.754773Z","time spent":"1.259121467s","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"warn","ts":"2026-05-01T01:47:36.013944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.84598Z","time spent":"1.167953511s","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-01T01:47:36.013958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:34.859205Z","time spent":"1.154717824s","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-01T01:47:36.014249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.838477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:36.014311Z","caller":"traceutil/trace.go:171","msg":"trace[2031657301] linearizableReadLoop","detail":"{readStateIndex:26478; appliedIndex:26477; }","duration":"306.891149ms","start":"2026-05-01T01:47:35.707409Z","end":"2026-05-01T01:47:36.0143Z","steps":["trace[2031657301] 'read index received' (duration: 53.432µs)","trace[2031657301] 'applied index is now lower than readState.Index' (duration: 306.836027ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:36.014389Z","caller":"traceutil/trace.go:171","msg":"trace[103750592] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"590.120288ms","start":"2026-05-01T01:47:35.424244Z","end":"2026-05-01T01:47:36.014364Z","steps":["trace[103750592] 'process raft request' (duration: 283.080225ms)","trace[103750592] 'compare' (duration: 306.337753ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:36.014413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.098892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-01T01:47:36.01454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:35.274499Z","time spent":"739.398333ms","remote":"127.0.0.1:43102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":31,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-05-01T01:47:36.014572Z","caller":"traceutil/trace.go:171","msg":"trace[517848987] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25628; }","duration":"568.293067ms","start":"2026-05-01T01:47:35.446264Z","end":"2026-05-01T01:47:36.014557Z","steps":["trace[517848987] 'agreement among raft nodes before linearized reading' (duration: 568.066041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:36.014559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:35.42422Z","time spent":"590.22776ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:36.014621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:35.446246Z","time spent":"568.36676ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-01T01:47:36.039107Z","caller":"traceutil/trace.go:171","msg":"trace[204031304] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"129.706198ms","start":"2026-05-01T01:47:35.909377Z","end":"2026-05-01T01:47:36.039083Z","steps":["trace[204031304] 'process raft request' (duration: 128.877325ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:36.330206Z","caller":"traceutil/trace.go:171","msg":"trace[472318204] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"381.834455ms","start":"2026-05-01T01:47:35.948333Z","end":"2026-05-01T01:47:36.330167Z","steps":["trace[472318204] 'process raft request' (duration: 381.488296ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:36.330281Z","caller":"traceutil/trace.go:171","msg":"trace[416068909] linearizableReadLoop","detail":"{readStateIndex:26480; appliedIndex:26478; }","duration":"315.208083ms","start":"2026-05-01T01:47:36.015044Z","end":"2026-05-01T01:47:36.330252Z","steps":["trace[416068909] 'read index received' (duration: 23.326971ms)","trace[416068909] 'applied index is now lower than readState.Index' (duration: 291.879902ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:36.33029Z","caller":"traceutil/trace.go:171","msg":"trace[1009844845] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"311.883624ms","start":"2026-05-01T01:47:36.018354Z","end":"2026-05-01T01:47:36.330238Z","steps":["trace[1009844845] 'process raft request' (duration: 311.674178ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:36.330307Z","caller":"traceutil/trace.go:171","msg":"trace[1967974386] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"311.145684ms","start":"2026-05-01T01:47:36.019132Z","end":"2026-05-01T01:47:36.330278Z","steps":["trace[1967974386] 'process raft request' (duration: 311.00561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:36.330418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:35.948305Z","time spent":"382.00235ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:36.330491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:36.018337Z","time spent":"312.031537ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:36.330543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:36.019126Z","time spent":"311.308708ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:36.330609Z","caller":"traceutil/trace.go:171","msg":"trace[982283580] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"311.649258ms","start":"2026-05-01T01:47:36.018935Z","end":"2026-05-01T01:47:36.330584Z","steps":["trace[982283580] 'process raft request' (duration: 311.159504ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:36.330597Z","caller":"traceutil/trace.go:171","msg":"trace[537545133] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"311.390971ms","start":"2026-05-01T01:47:36.019188Z","end":"2026-05-01T01:47:36.330579Z","steps":["trace[537545133] 'process raft request' (duration: 310.989119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:36.330906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:36.019181Z","time spent":"311.649548ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:36.330625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.579714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:36.331036Z","caller":"traceutil/trace.go:171","msg":"trace[1149619066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25635; }","duration":"316.000215ms","start":"2026-05-01T01:47:36.015003Z","end":"2026-05-01T01:47:36.331003Z","steps":["trace[1149619066] 'agreement among raft nodes before linearized reading' (duration: 315.575254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:36.331103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:36.01498Z","time spent":"316.109628ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-01T01:47:36.330683Z","caller":"traceutil/trace.go:171","msg":"trace[1389566113] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"311.442802ms","start":"2026-05-01T01:47:36.019226Z","end":"2026-05-01T01:47:36.330669Z","steps":["trace[1389566113] 'process raft request' (duration: 310.985739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:36.331259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:36.019221Z","time spent":"311.951776ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:36.330754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.648059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-01T01:47:36.331339Z","caller":"traceutil/trace.go:171","msg":"trace[623098673] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25635; }","duration":"173.311697ms","start":"2026-05-01T01:47:36.158009Z","end":"2026-05-01T01:47:36.331321Z","steps":["trace[623098673] 'agreement among raft nodes before linearized reading' (duration: 172.67734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:36.330763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:36.018927Z","time spent":"311.72944ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:43.293615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.344025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-01T01:47:43.29369Z","caller":"traceutil/trace.go:171","msg":"trace[24364554] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25737; }","duration":"140.469358ms","start":"2026-05-01T01:47:43.153205Z","end":"2026-05-01T01:47:43.293674Z","steps":["trace[24364554] 'range keys from in-memory index tree' (duration: 140.185701ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:43.569432Z","caller":"traceutil/trace.go:171","msg":"trace[1191163051] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"109.347778ms","start":"2026-05-01T01:47:43.460052Z","end":"2026-05-01T01:47:43.569399Z","steps":["trace[1191163051] 'process raft request' (duration: 109.08267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:43.877466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.430213ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:43.877584Z","caller":"traceutil/trace.go:171","msg":"trace[1418756405] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25738; }","duration":"165.562438ms","start":"2026-05-01T01:47:43.712005Z","end":"2026-05-01T01:47:43.877567Z","steps":["trace[1418756405] 'range keys from in-memory index tree' (duration: 165.414473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:43.878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.142958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:47:43.878207Z","caller":"traceutil/trace.go:171","msg":"trace[657802454] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"417.404557ms","start":"2026-05-01T01:47:43.46079Z","end":"2026-05-01T01:47:43.878195Z","steps":["trace[657802454] 'process raft request' (duration: 218.994162ms)","trace[657802454] 'compare' (duration: 197.895342ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:43.878252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:43.460778Z","time spent":"417.452208ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:43.878355Z","caller":"traceutil/trace.go:171","msg":"trace[268293036] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"417.440078ms","start":"2026-05-01T01:47:43.460903Z","end":"2026-05-01T01:47:43.878344Z","steps":["trace[268293036] 'process raft request' (duration: 417.167051ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:43.878605Z","caller":"traceutil/trace.go:171","msg":"trace[580050521] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"410.245564ms","start":"2026-05-01T01:47:43.468345Z","end":"2026-05-01T01:47:43.878591Z","steps":["trace[580050521] 'process raft request' (duration: 409.815733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:43.878666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:43.468327Z","time spent":"410.303896ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:43.87882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:43.460878Z","time spent":"417.50146ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:47:44.184667Z","caller":"traceutil/trace.go:171","msg":"trace[528043622] linearizableReadLoop","detail":"{readStateIndex:26594; appliedIndex:26593; }","duration":"300.499096ms","start":"2026-05-01T01:47:43.884145Z","end":"2026-05-01T01:47:44.184644Z","steps":["trace[528043622] 'read index received' (duration: 300.208408ms)","trace[528043622] 'applied index is now lower than readState.Index' (duration: 289.208µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:44.184736Z","caller":"traceutil/trace.go:171","msg":"trace[1661967901] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"300.992639ms","start":"2026-05-01T01:47:43.883702Z","end":"2026-05-01T01:47:44.184695Z","steps":["trace[1661967901] 'process raft request' (duration: 300.65886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:44.185005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:43.883685Z","time spent":"301.143033ms","remote":"127.0.0.1:59750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:44.185032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.838595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-05-01T01:47:44.185161Z","caller":"traceutil/trace.go:171","msg":"trace[2127530360] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25742; }","duration":"301.02635ms","start":"2026-05-01T01:47:43.884118Z","end":"2026-05-01T01:47:44.185144Z","steps":["trace[2127530360] 'agreement among raft nodes before linearized reading' (duration: 300.683621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:44.185193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:43.88411Z","time spent":"301.075091ms","remote":"127.0.0.1:59682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1178,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-05-01T01:47:44.394825Z","caller":"traceutil/trace.go:171","msg":"trace[1053168225] linearizableReadLoop","detail":"{readStateIndex:26596; appliedIndex:26594; }","duration":"210.02357ms","start":"2026-05-01T01:47:44.184775Z","end":"2026-05-01T01:47:44.394799Z","steps":["trace[1053168225] 'read index received' (duration: 198.142969ms)","trace[1053168225] 'applied index is now lower than readState.Index' (duration: 11.87647ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:44.394855Z","caller":"traceutil/trace.go:171","msg":"trace[710074214] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"328.821802ms","start":"2026-05-01T01:47:44.065992Z","end":"2026-05-01T01:47:44.394814Z","steps":["trace[710074214] 'process raft request' (duration: 316.942301ms)","trace[710074214] 'compare' (duration: 11.537162ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:44.394877Z","caller":"traceutil/trace.go:171","msg":"trace[1392383127] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"265.905521ms","start":"2026-05-01T01:47:44.128937Z","end":"2026-05-01T01:47:44.394842Z","steps":["trace[1392383127] 'process raft request' (duration: 265.766937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:44.395021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:44.065967Z","time spent":"328.965745ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:47:44.395027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.71682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-01T01:47:44.395088Z","caller":"traceutil/trace.go:171","msg":"trace[1452723085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25744; }","duration":"341.843324ms","start":"2026-05-01T01:47:44.053229Z","end":"2026-05-01T01:47:44.395072Z","steps":["trace[1452723085] 'agreement among raft nodes before linearized reading' (duration: 341.69841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:44.395085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.850065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"warn","ts":"2026-05-01T01:47:44.395116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:47:44.053193Z","time spent":"341.915816ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-01T01:47:44.395124Z","caller":"traceutil/trace.go:171","msg":"trace[2092572198] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25744; }","duration":"202.916407ms","start":"2026-05-01T01:47:44.192195Z","end":"2026-05-01T01:47:44.395111Z","steps":["trace[2092572198] 'agreement among raft nodes before linearized reading' (duration: 202.749292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:44.395213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.34852ms","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:8"} {"level":"info","ts":"2026-05-01T01:47:44.395237Z","caller":"traceutil/trace.go:171","msg":"trace[408768795] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25744; }","duration":"155.409102ms","start":"2026-05-01T01:47:44.23982Z","end":"2026-05-01T01:47:44.395229Z","steps":["trace[408768795] 'agreement among raft nodes before linearized reading' (duration: 155.363611ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:46.168507Z","caller":"traceutil/trace.go:171","msg":"trace[1361740313] linearizableReadLoop","detail":"{readStateIndex:26614; appliedIndex:26613; }","duration":"172.498774ms","start":"2026-05-01T01:47:45.995985Z","end":"2026-05-01T01:47:46.168484Z","steps":["trace[1361740313] 'read index received' (duration: 172.425582ms)","trace[1361740313] 'applied index is now lower than readState.Index' (duration: 71.652µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:46.168571Z","caller":"traceutil/trace.go:171","msg":"trace[1208786178] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"213.622636ms","start":"2026-05-01T01:47:45.954905Z","end":"2026-05-01T01:47:46.168528Z","steps":["trace[1208786178] 'process raft request' (duration: 213.281117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:46.168673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.652919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:47:46.168862Z","caller":"traceutil/trace.go:171","msg":"trace[1697641329] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25761; }","duration":"172.866684ms","start":"2026-05-01T01:47:45.99598Z","end":"2026-05-01T01:47:46.168847Z","steps":["trace[1697641329] 'agreement among raft nodes before linearized reading' (duration: 172.611748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:46.178177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.591906ms","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:6"} {"level":"info","ts":"2026-05-01T01:47:46.178303Z","caller":"traceutil/trace.go:171","msg":"trace[40628617] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25762; }","duration":"115.74968ms","start":"2026-05-01T01:47:46.062536Z","end":"2026-05-01T01:47:46.178285Z","steps":["trace[40628617] 'agreement among raft nodes before linearized reading' (duration: 115.539355ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:46.178197Z","caller":"traceutil/trace.go:171","msg":"trace[1877025171] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"134.333102ms","start":"2026-05-01T01:47:46.043834Z","end":"2026-05-01T01:47:46.178167Z","steps":["trace[1877025171] 'process raft request' (duration: 134.139107ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.160358Z","caller":"traceutil/trace.go:171","msg":"trace[315815002] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"259.713182ms","start":"2026-05-01T01:47:49.90061Z","end":"2026-05-01T01:47:50.160324Z","steps":["trace[315815002] 'process raft request' (duration: 259.420845ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.160563Z","caller":"traceutil/trace.go:171","msg":"trace[931239829] linearizableReadLoop","detail":"{readStateIndex:26668; appliedIndex:26666; }","duration":"162.968727ms","start":"2026-05-01T01:47:49.997562Z","end":"2026-05-01T01:47:50.160531Z","steps":["trace[931239829] 'read index received' (duration: 162.439863ms)","trace[931239829] 'applied index is now lower than readState.Index' (duration: 527.694µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:50.160734Z","caller":"traceutil/trace.go:171","msg":"trace[1481784903] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"258.544411ms","start":"2026-05-01T01:47:49.902179Z","end":"2026-05-01T01:47:50.160724Z","steps":["trace[1481784903] 'process raft request' (duration: 258.075539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:50.160998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.421909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10362"} {"level":"info","ts":"2026-05-01T01:47:50.161027Z","caller":"traceutil/trace.go:171","msg":"trace[267146797] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25813; }","duration":"163.521872ms","start":"2026-05-01T01:47:49.997495Z","end":"2026-05-01T01:47:50.161017Z","steps":["trace[267146797] 'agreement among raft nodes before linearized reading' (duration: 163.364777ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.444783Z","caller":"traceutil/trace.go:171","msg":"trace[184805944] linearizableReadLoop","detail":"{readStateIndex:26669; appliedIndex:26668; }","duration":"266.65795ms","start":"2026-05-01T01:47:50.178096Z","end":"2026-05-01T01:47:50.444754Z","steps":["trace[184805944] 'read index received' (duration: 223.487732ms)","trace[184805944] 'applied index is now lower than readState.Index' (duration: 43.168948ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:50.444925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.807665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-01T01:47:50.44491Z","caller":"traceutil/trace.go:171","msg":"trace[1672492662] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"270.806593ms","start":"2026-05-01T01:47:50.174068Z","end":"2026-05-01T01:47:50.444875Z","steps":["trace[1672492662] 'process raft request' (duration: 228.50126ms)","trace[1672492662] 'compare' (duration: 42.002255ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:50.444971Z","caller":"traceutil/trace.go:171","msg":"trace[1559904673] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:25814; }","duration":"266.917198ms","start":"2026-05-01T01:47:50.178041Z","end":"2026-05-01T01:47:50.444958Z","steps":["trace[1559904673] 'agreement among raft nodes before linearized reading' (duration: 266.817795ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.590382Z","caller":"traceutil/trace.go:171","msg":"trace[1485044359] linearizableReadLoop","detail":"{readStateIndex:26670; appliedIndex:26669; }","duration":"143.165972ms","start":"2026-05-01T01:47:50.447157Z","end":"2026-05-01T01:47:50.590323Z","steps":["trace[1485044359] 'read index received' (duration: 98.793441ms)","trace[1485044359] 'applied index is now lower than readState.Index' (duration: 44.370751ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:47:50.590478Z","caller":"traceutil/trace.go:171","msg":"trace[719434262] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"176.38961ms","start":"2026-05-01T01:47:50.414058Z","end":"2026-05-01T01:47:50.590447Z","steps":["trace[719434262] 'process raft request' (duration: 131.815594ms)","trace[719434262] 'compare' (duration: 43.96019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:50.590733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.551722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-01T01:47:50.590831Z","caller":"traceutil/trace.go:171","msg":"trace[1438316340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25815; }","duration":"143.702596ms","start":"2026-05-01T01:47:50.447107Z","end":"2026-05-01T01:47:50.59081Z","steps":["trace[1438316340] 'agreement among raft nodes before linearized reading' (duration: 143.401608ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.599131Z","caller":"traceutil/trace.go:171","msg":"trace[112828867] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"147.016766ms","start":"2026-05-01T01:47:50.452095Z","end":"2026-05-01T01:47:50.599112Z","steps":["trace[112828867] 'process raft request' (duration: 146.835241ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.59938Z","caller":"traceutil/trace.go:171","msg":"trace[99185456] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"146.598174ms","start":"2026-05-01T01:47:50.45277Z","end":"2026-05-01T01:47:50.599368Z","steps":["trace[99185456] 'process raft request' (duration: 146.285445ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.599668Z","caller":"traceutil/trace.go:171","msg":"trace[2026428090] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"145.565156ms","start":"2026-05-01T01:47:50.45408Z","end":"2026-05-01T01:47:50.599645Z","steps":["trace[2026428090] 'process raft request' (duration: 145.382871ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.599853Z","caller":"traceutil/trace.go:171","msg":"trace[1495515295] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"146.929253ms","start":"2026-05-01T01:47:50.452879Z","end":"2026-05-01T01:47:50.599808Z","steps":["trace[1495515295] 'process raft request' (duration: 146.413959ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.79764Z","caller":"traceutil/trace.go:171","msg":"trace[13086533] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"248.007347ms","start":"2026-05-01T01:47:50.549589Z","end":"2026-05-01T01:47:50.797596Z","steps":["trace[13086533] 'process raft request' (duration: 247.500693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:50.797778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.471772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-01T01:47:50.797778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.143825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-01T01:47:50.797858Z","caller":"traceutil/trace.go:171","msg":"trace[1933738378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25822; }","duration":"271.603525ms","start":"2026-05-01T01:47:50.526237Z","end":"2026-05-01T01:47:50.79784Z","steps":["trace[1933738378] 'agreement among raft nodes before linearized reading' (duration: 271.366499ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.797834Z","caller":"traceutil/trace.go:171","msg":"trace[675711412] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"202.210248ms","start":"2026-05-01T01:47:50.595582Z","end":"2026-05-01T01:47:50.797792Z","steps":["trace[675711412] 'process raft request' (duration: 201.943831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:47:50.79786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.625178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-01T01:47:50.797912Z","caller":"traceutil/trace.go:171","msg":"trace[505371814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25822; }","duration":"267.312069ms","start":"2026-05-01T01:47:50.53058Z","end":"2026-05-01T01:47:50.797892Z","steps":["trace[505371814] 'agreement among raft nodes before linearized reading' (duration: 267.038482ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.797938Z","caller":"traceutil/trace.go:171","msg":"trace[60790665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25822; }","duration":"267.756691ms","start":"2026-05-01T01:47:50.530164Z","end":"2026-05-01T01:47:50.797921Z","steps":["trace[60790665] 'agreement among raft nodes before linearized reading' (duration: 267.511025ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:47:50.797899Z","caller":"traceutil/trace.go:171","msg":"trace[128890760] linearizableReadLoop","detail":"{readStateIndex:26676; appliedIndex:26670; }","duration":"207.129171ms","start":"2026-05-01T01:47:50.590468Z","end":"2026-05-01T01:47:50.797597Z","steps":["trace[128890760] 'read index received' (duration: 8.485829ms)","trace[128890760] 'applied index is now lower than readState.Index' (duration: 198.641872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:47:50.798029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.555122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-01T01:47:50.798134Z","caller":"traceutil/trace.go:171","msg":"trace[543300779] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25822; }","duration":"268.700696ms","start":"2026-05-01T01:47:50.529411Z","end":"2026-05-01T01:47:50.798111Z","steps":["trace[543300779] 'agreement among raft nodes before linearized reading' (duration: 268.436229ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:19.757766Z","caller":"traceutil/trace.go:171","msg":"trace[1135470605] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"144.064696ms","start":"2026-05-01T01:48:19.613672Z","end":"2026-05-01T01:48:19.757736Z","steps":["trace[1135470605] 'process raft request' (duration: 143.86796ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:20.917363Z","caller":"traceutil/trace.go:171","msg":"trace[766304672] transaction","detail":"{read_only:false; response_revision:26060; number_of_response:1; }","duration":"212.988679ms","start":"2026-05-01T01:48:20.704057Z","end":"2026-05-01T01:48:20.917046Z","steps":["trace[766304672] 'process raft request' (duration: 212.786924ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:20.996088Z","caller":"traceutil/trace.go:171","msg":"trace[1740896457] transaction","detail":"{read_only:false; response_revision:26061; number_of_response:1; }","duration":"291.424921ms","start":"2026-05-01T01:48:20.704632Z","end":"2026-05-01T01:48:20.996057Z","steps":["trace[1740896457] 'process raft request' (duration: 259.672812ms)","trace[1740896457] 'compare' (duration: 31.603715ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:21.099796Z","caller":"traceutil/trace.go:171","msg":"trace[743423118] linearizableReadLoop","detail":"{readStateIndex:26923; appliedIndex:26921; }","duration":"171.400795ms","start":"2026-05-01T01:48:20.928374Z","end":"2026-05-01T01:48:21.099775Z","steps":["trace[743423118] 'read index received' (duration: 35.965252ms)","trace[743423118] 'applied index is now lower than readState.Index' (duration: 135.434343ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:21.100073Z","caller":"traceutil/trace.go:171","msg":"trace[1174821652] transaction","detail":"{read_only:false; response_revision:26062; number_of_response:1; }","duration":"175.690211ms","start":"2026-05-01T01:48:20.92437Z","end":"2026-05-01T01:48:21.10006Z","steps":["trace[1174821652] 'process raft request' (duration: 173.944383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:21.100266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.878079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:21.100299Z","caller":"traceutil/trace.go:171","msg":"trace[1846125483] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26062; }","duration":"171.957121ms","start":"2026-05-01T01:48:20.928331Z","end":"2026-05-01T01:48:21.100288Z","steps":["trace[1846125483] 'agreement among raft nodes before linearized reading' (duration: 171.884629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:21.100507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.85895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-01T01:48:21.100535Z","caller":"traceutil/trace.go:171","msg":"trace[2128655890] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:26062; }","duration":"123.91264ms","start":"2026-05-01T01:48:20.976613Z","end":"2026-05-01T01:48:21.100526Z","steps":["trace[2128655890] 'agreement among raft nodes before linearized reading' (duration: 123.807457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:21.100692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.994672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-01T01:48:21.100718Z","caller":"traceutil/trace.go:171","msg":"trace[962677290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26062; }","duration":"107.070155ms","start":"2026-05-01T01:48:20.993641Z","end":"2026-05-01T01:48:21.100711Z","steps":["trace[962677290] 'agreement among raft nodes before linearized reading' (duration: 107.007453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:21.100843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.235807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-01T01:48:21.100867Z","caller":"traceutil/trace.go:171","msg":"trace[798833616] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26062; }","duration":"111.283339ms","start":"2026-05-01T01:48:20.989577Z","end":"2026-05-01T01:48:21.10086Z","steps":["trace[798833616] 'agreement among raft nodes before linearized reading' (duration: 111.222797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:21.100975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.089531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-01T01:48:21.100997Z","caller":"traceutil/trace.go:171","msg":"trace[1204885486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26062; }","duration":"112.131972ms","start":"2026-05-01T01:48:20.988859Z","end":"2026-05-01T01:48:21.100991Z","steps":["trace[1204885486] 'agreement among raft nodes before linearized reading' (duration: 112.08023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:21.101091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.260442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-01T01:48:21.101111Z","caller":"traceutil/trace.go:171","msg":"trace[1125121597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26062; }","duration":"113.323644ms","start":"2026-05-01T01:48:20.987782Z","end":"2026-05-01T01:48:21.101106Z","steps":["trace[1125121597] 'agreement among raft nodes before linearized reading' (duration: 113.264192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:21.1017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.049118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-01T01:48:21.101795Z","caller":"traceutil/trace.go:171","msg":"trace[1228372599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26062; }","duration":"116.476668ms","start":"2026-05-01T01:48:20.9853Z","end":"2026-05-01T01:48:21.101776Z","steps":["trace[1228372599] 'agreement among raft nodes before linearized reading' (duration: 115.974405ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:22.210314Z","caller":"traceutil/trace.go:171","msg":"trace[395909590] transaction","detail":"{read_only:false; response_revision:26073; number_of_response:1; }","duration":"152.254457ms","start":"2026-05-01T01:48:22.058036Z","end":"2026-05-01T01:48:22.210291Z","steps":["trace[395909590] 'process raft request' (duration: 152.050821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:22.499203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.362285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:48:22.499434Z","caller":"traceutil/trace.go:171","msg":"trace[71182484] transaction","detail":"{read_only:false; response_revision:26075; number_of_response:1; }","duration":"204.90462ms","start":"2026-05-01T01:48:22.294509Z","end":"2026-05-01T01:48:22.499414Z","steps":["trace[71182484] 'process raft request' (duration: 204.825228ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:22.499444Z","caller":"traceutil/trace.go:171","msg":"trace[1177484897] transaction","detail":"{read_only:false; response_revision:26074; number_of_response:1; }","duration":"215.025504ms","start":"2026-05-01T01:48:22.284381Z","end":"2026-05-01T01:48:22.499406Z","steps":["trace[1177484897] 'process raft request' (duration: 23.347912ms)","trace[1177484897] 'compare' (duration: 191.018675ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:23.077003Z","caller":"traceutil/trace.go:171","msg":"trace[1865245683] transaction","detail":"{read_only:false; response_revision:26076; number_of_response:1; }","duration":"143.676585ms","start":"2026-05-01T01:48:22.933293Z","end":"2026-05-01T01:48:23.07697Z","steps":["trace[1865245683] 'process raft request' (duration: 143.527511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:23.23932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.679756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:48:23.239438Z","caller":"traceutil/trace.go:171","msg":"trace[1179309521] linearizableReadLoop","detail":"{readStateIndex:26938; appliedIndex:26937; }","duration":"128.433543ms","start":"2026-05-01T01:48:23.110986Z","end":"2026-05-01T01:48:23.239419Z","steps":["trace[1179309521] 'read index received' (duration: 52.362µs)","trace[1179309521] 'applied index is now lower than readState.Index' (duration: 128.380051ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:23.239642Z","caller":"traceutil/trace.go:171","msg":"trace[812950261] transaction","detail":"{read_only:false; response_revision:26077; number_of_response:1; }","duration":"237.564123ms","start":"2026-05-01T01:48:23.002064Z","end":"2026-05-01T01:48:23.239628Z","steps":["trace[812950261] 'process raft request' (duration: 107.467356ms)","trace[812950261] 'compare' (duration: 129.013329ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:23.239924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.940277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-01T01:48:23.239968Z","caller":"traceutil/trace.go:171","msg":"trace[353761119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26077; }","duration":"129.007609ms","start":"2026-05-01T01:48:23.110945Z","end":"2026-05-01T01:48:23.239953Z","steps":["trace[353761119] 'agreement among raft nodes before linearized reading' (duration: 128.874975ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:23.396845Z","caller":"traceutil/trace.go:171","msg":"trace[1698563043] linearizableReadLoop","detail":"{readStateIndex:26939; appliedIndex:26938; }","duration":"157.348536ms","start":"2026-05-01T01:48:23.239467Z","end":"2026-05-01T01:48:23.396816Z","steps":["trace[1698563043] 'read index received' (duration: 139.322948ms)","trace[1698563043] 'applied index is now lower than readState.Index' (duration: 18.024768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:23.397178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.860559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-01T01:48:23.397207Z","caller":"traceutil/trace.go:171","msg":"trace[1487237251] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26078; }","duration":"282.923311ms","start":"2026-05-01T01:48:23.114276Z","end":"2026-05-01T01:48:23.397199Z","steps":["trace[1487237251] 'agreement among raft nodes before linearized reading' (duration: 282.782927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:23.397378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.756318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-01T01:48:23.397404Z","caller":"traceutil/trace.go:171","msg":"trace[1770253213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26078; }","duration":"285.794579ms","start":"2026-05-01T01:48:23.111605Z","end":"2026-05-01T01:48:23.3974Z","steps":["trace[1770253213] 'agreement among raft nodes before linearized reading' (duration: 285.738637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:23.397302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.49375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-01T01:48:23.397494Z","caller":"traceutil/trace.go:171","msg":"trace[208670589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26078; }","duration":"282.735786ms","start":"2026-05-01T01:48:23.114751Z","end":"2026-05-01T01:48:23.397487Z","steps":["trace[208670589] 'agreement among raft nodes before linearized reading' (duration: 282.294864ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:23.397447Z","caller":"traceutil/trace.go:171","msg":"trace[1925992905] transaction","detail":"{read_only:false; response_revision:26078; number_of_response:1; }","duration":"282.805628ms","start":"2026-05-01T01:48:23.11461Z","end":"2026-05-01T01:48:23.397416Z","steps":["trace[1925992905] 'process raft request' (duration: 264.161543ms)","trace[1925992905] 'compare' (duration: 17.906705ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:23.397579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.544741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-01T01:48:23.397627Z","caller":"traceutil/trace.go:171","msg":"trace[393484625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26078; }","duration":"282.597832ms","start":"2026-05-01T01:48:23.115017Z","end":"2026-05-01T01:48:23.397615Z","steps":["trace[393484625] 'agreement among raft nodes before linearized reading' (duration: 282.466859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:23.397941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.927431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-01T01:48:23.397967Z","caller":"traceutil/trace.go:171","msg":"trace[640041297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26078; }","duration":"283.016703ms","start":"2026-05-01T01:48:23.114944Z","end":"2026-05-01T01:48:23.397961Z","steps":["trace[640041297] 'agreement among raft nodes before linearized reading' (duration: 282.87651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:23.444858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.944198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:23.444963Z","caller":"traceutil/trace.go:171","msg":"trace[1638668581] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:26079; }","duration":"156.123352ms","start":"2026-05-01T01:48:23.288816Z","end":"2026-05-01T01:48:23.44494Z","steps":["trace[1638668581] 'agreement among raft nodes before linearized reading' (duration: 155.931997ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:23.445353Z","caller":"traceutil/trace.go:171","msg":"trace[1737627789] transaction","detail":"{read_only:false; response_revision:26079; number_of_response:1; }","duration":"197.192713ms","start":"2026-05-01T01:48:23.248143Z","end":"2026-05-01T01:48:23.445336Z","steps":["trace[1737627789] 'process raft request' (duration: 196.411062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:23.95593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.159205ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:23.956045Z","caller":"traceutil/trace.go:171","msg":"trace[1469227152] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26079; }","duration":"243.307169ms","start":"2026-05-01T01:48:23.712723Z","end":"2026-05-01T01:48:23.95603Z","steps":["trace[1469227152] 'range keys from in-memory index tree' (duration: 243.133825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:23.956208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.115855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:48:23.956709Z","caller":"traceutil/trace.go:171","msg":"trace[502470481] transaction","detail":"{read_only:false; response_revision:26085; number_of_response:1; }","duration":"169.099082ms","start":"2026-05-01T01:48:23.787586Z","end":"2026-05-01T01:48:23.956685Z","steps":["trace[502470481] 'process raft request' (duration: 169.039461ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:23.957023Z","caller":"traceutil/trace.go:171","msg":"trace[1938397927] transaction","detail":"{read_only:false; response_revision:26080; number_of_response:1; }","duration":"555.978865ms","start":"2026-05-01T01:48:23.40102Z","end":"2026-05-01T01:48:23.956999Z","steps":["trace[1938397927] 'process raft request' (duration: 254.968955ms)","trace[1938397927] 'compare' (duration: 299.897979ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:23.957137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:48:23.401Z","time spent":"556.079978ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:48:23.957253Z","caller":"traceutil/trace.go:171","msg":"trace[1163201474] transaction","detail":"{read_only:false; response_revision:26083; number_of_response:1; }","duration":"553.999491ms","start":"2026-05-01T01:48:23.403237Z","end":"2026-05-01T01:48:23.957237Z","steps":["trace[1163201474] 'process raft request' (duration: 553.26203ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:23.95731Z","caller":"traceutil/trace.go:171","msg":"trace[1209430004] linearizableReadLoop","detail":"{readStateIndex:26945; appliedIndex:26940; }","duration":"319.619543ms","start":"2026-05-01T01:48:23.637663Z","end":"2026-05-01T01:48:23.957283Z","steps":["trace[1209430004] 'read index received' (duration: 18.281905ms)","trace[1209430004] 'applied index is now lower than readState.Index' (duration: 301.335778ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:23.957363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:48:23.403232Z","time spent":"554.055682ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:48:23.957437Z","caller":"traceutil/trace.go:171","msg":"trace[603452848] transaction","detail":"{read_only:false; response_revision:26084; number_of_response:1; }","duration":"552.564612ms","start":"2026-05-01T01:48:23.4048Z","end":"2026-05-01T01:48:23.957364Z","steps":["trace[603452848] 'process raft request' (duration: 551.76147ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:23.957551Z","caller":"traceutil/trace.go:171","msg":"trace[1990980513] transaction","detail":"{read_only:false; response_revision:26082; number_of_response:1; }","duration":"554.372051ms","start":"2026-05-01T01:48:23.403161Z","end":"2026-05-01T01:48:23.957533Z","steps":["trace[1990980513] 'process raft request' (duration: 553.284731ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:23.957526Z","caller":"traceutil/trace.go:171","msg":"trace[1124383666] transaction","detail":"{read_only:false; response_revision:26081; number_of_response:1; }","duration":"554.794593ms","start":"2026-05-01T01:48:23.402687Z","end":"2026-05-01T01:48:23.957482Z","steps":["trace[1124383666] 'process raft request' (duration: 553.670041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:23.957646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:48:23.40314Z","time spent":"554.458814ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:48:23.957461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.785427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-01T01:48:23.957732Z","caller":"traceutil/trace.go:171","msg":"trace[148818267] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26085; }","duration":"320.102426ms","start":"2026-05-01T01:48:23.637614Z","end":"2026-05-01T01:48:23.957717Z","steps":["trace[148818267] 'agreement among raft nodes before linearized reading' (duration: 319.767637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:23.957734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:48:23.402679Z","time spent":"554.945986ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:48:23.957764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:48:23.637595Z","time spent":"320.159837ms","remote":"127.0.0.1:59862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-01T01:48:23.95774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:48:23.404792Z","time spent":"552.781228ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:48:24.438583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.881848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:24.438689Z","caller":"traceutil/trace.go:171","msg":"trace[1069362970] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:26085; }","duration":"344.028933ms","start":"2026-05-01T01:48:24.094637Z","end":"2026-05-01T01:48:24.438666Z","steps":["trace[1069362970] 'count revisions from in-memory index tree' (duration: 343.812216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:24.438741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:48:24.094623Z","time spent":"344.105735ms","remote":"127.0.0.1:60202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-05-01T01:48:24.43885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.321294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-01T01:48:24.438911Z","caller":"traceutil/trace.go:171","msg":"trace[635695770] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26085; }","duration":"350.434327ms","start":"2026-05-01T01:48:24.088463Z","end":"2026-05-01T01:48:24.438897Z","steps":["trace[635695770] 'range keys from in-memory index tree' (duration: 350.112998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:24.43894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:48:24.088409Z","time spent":"350.52535ms","remote":"127.0.0.1:59750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-01T01:48:24.438971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.378915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:24.439082Z","caller":"traceutil/trace.go:171","msg":"trace[470435075] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:26085; }","duration":"262.510429ms","start":"2026-05-01T01:48:24.176538Z","end":"2026-05-01T01:48:24.439048Z","steps":["trace[470435075] 'count revisions from in-memory index tree' (duration: 262.283662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:24.439283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.420478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:24.439585Z","caller":"traceutil/trace.go:171","msg":"trace[2021646376] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26085; }","duration":"231.775787ms","start":"2026-05-01T01:48:24.207785Z","end":"2026-05-01T01:48:24.439561Z","steps":["trace[2021646376] 'count revisions from in-memory index tree' (duration: 231.280724ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:24.439632Z","caller":"traceutil/trace.go:171","msg":"trace[817470361] linearizableReadLoop","detail":"{readStateIndex:26947; appliedIndex:26946; }","duration":"164.871699ms","start":"2026-05-01T01:48:24.274739Z","end":"2026-05-01T01:48:24.43961Z","steps":["trace[817470361] 'read index received' (duration: 81.34532ms)","trace[817470361] 'applied index is now lower than readState.Index' (duration: 83.524849ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:24.439691Z","caller":"traceutil/trace.go:171","msg":"trace[95673947] transaction","detail":"{read_only:false; response_revision:26086; number_of_response:1; }","duration":"221.947261ms","start":"2026-05-01T01:48:24.217714Z","end":"2026-05-01T01:48:24.439661Z","steps":["trace[95673947] 'process raft request' (duration: 138.403302ms)","trace[95673947] 'compare' (duration: 82.407618ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:24.43981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.049463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:24.439871Z","caller":"traceutil/trace.go:171","msg":"trace[1713921248] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:26086; }","duration":"165.165256ms","start":"2026-05-01T01:48:24.274686Z","end":"2026-05-01T01:48:24.439851Z","steps":["trace[1713921248] 'agreement among raft nodes before linearized reading' (duration: 165.052853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:24.439965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.147191ms","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:8"} {"level":"info","ts":"2026-05-01T01:48:24.440017Z","caller":"traceutil/trace.go:171","msg":"trace[1579531417] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26086; }","duration":"143.206452ms","start":"2026-05-01T01:48:24.296796Z","end":"2026-05-01T01:48:24.440002Z","steps":["trace[1579531417] 'agreement among raft nodes before linearized reading' (duration: 143.10939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:24.682267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.894997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:48:24.683762Z","caller":"traceutil/trace.go:171","msg":"trace[1572170190] linearizableReadLoop","detail":"{readStateIndex:26949; appliedIndex:26948; }","duration":"150.861079ms","start":"2026-05-01T01:48:24.532874Z","end":"2026-05-01T01:48:24.683736Z","steps":["trace[1572170190] 'read index received' (duration: 41.396679ms)","trace[1572170190] 'applied index is now lower than readState.Index' (duration: 109.4619ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:24.683779Z","caller":"traceutil/trace.go:171","msg":"trace[1819657168] transaction","detail":"{read_only:false; response_revision:26088; number_of_response:1; }","duration":"177.467328ms","start":"2026-05-01T01:48:24.506278Z","end":"2026-05-01T01:48:24.683745Z","steps":["trace[1819657168] 'process raft request' (duration: 67.957027ms)","trace[1819657168] 'compare' (duration: 107.734613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:24.683914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.009543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-01T01:48:24.684056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.039554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:24.684048Z","caller":"traceutil/trace.go:171","msg":"trace[375848791] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:26088; }","duration":"151.186108ms","start":"2026-05-01T01:48:24.532833Z","end":"2026-05-01T01:48:24.684019Z","steps":["trace[375848791] 'agreement among raft nodes before linearized reading' (duration: 150.997423ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:24.684085Z","caller":"traceutil/trace.go:171","msg":"trace[996801233] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26088; }","duration":"148.085934ms","start":"2026-05-01T01:48:24.535992Z","end":"2026-05-01T01:48:24.684078Z","steps":["trace[996801233] 'agreement among raft nodes before linearized reading' (duration: 148.036763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:24.684248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.506494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:24.684269Z","caller":"traceutil/trace.go:171","msg":"trace[1060887096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26088; }","duration":"122.546294ms","start":"2026-05-01T01:48:24.561717Z","end":"2026-05-01T01:48:24.684263Z","steps":["trace[1060887096] 'agreement among raft nodes before linearized reading' (duration: 122.508253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:25.605491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.160204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:48:25.605596Z","caller":"traceutil/trace.go:171","msg":"trace[498341354] linearizableReadLoop","detail":"{readStateIndex:26953; appliedIndex:26952; }","duration":"158.879677ms","start":"2026-05-01T01:48:25.4467Z","end":"2026-05-01T01:48:25.60558Z","steps":["trace[498341354] 'read index received' (duration: 26.471237ms)","trace[498341354] 'applied index is now lower than readState.Index' (duration: 132.40716ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:25.605679Z","caller":"traceutil/trace.go:171","msg":"trace[1771760331] transaction","detail":"{read_only:false; response_revision:26091; number_of_response:1; }","duration":"201.611832ms","start":"2026-05-01T01:48:25.404058Z","end":"2026-05-01T01:48:25.605669Z","steps":["trace[1771760331] 'process raft request' (duration: 69.185491ms)","trace[1771760331] 'compare' (duration: 131.790344ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:25.605878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.911958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-01T01:48:25.60597Z","caller":"traceutil/trace.go:171","msg":"trace[464745245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26091; }","duration":"152.056171ms","start":"2026-05-01T01:48:25.453888Z","end":"2026-05-01T01:48:25.605945Z","steps":["trace[464745245] 'agreement among raft nodes before linearized reading' (duration: 151.827295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:25.606135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.423901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:25.606193Z","caller":"traceutil/trace.go:171","msg":"trace[1434710492] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26091; }","duration":"159.531954ms","start":"2026-05-01T01:48:25.446643Z","end":"2026-05-01T01:48:25.606175Z","steps":["trace[1434710492] 'agreement among raft nodes before linearized reading' (duration: 159.415761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:25.878613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.27865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-01T01:48:25.87873Z","caller":"traceutil/trace.go:171","msg":"trace[55531260] linearizableReadLoop","detail":"{readStateIndex:26954; appliedIndex:26953; }","duration":"207.986315ms","start":"2026-05-01T01:48:25.670726Z","end":"2026-05-01T01:48:25.878712Z","steps":["trace[55531260] 'read index received' (duration: 73.54186ms)","trace[55531260] 'applied index is now lower than readState.Index' (duration: 134.443225ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:25.878825Z","caller":"traceutil/trace.go:171","msg":"trace[2144275330] transaction","detail":"{read_only:false; response_revision:26092; number_of_response:1; }","duration":"267.678959ms","start":"2026-05-01T01:48:25.611132Z","end":"2026-05-01T01:48:25.878811Z","steps":["trace[2144275330] 'process raft request' (duration: 133.11325ms)","trace[2144275330] 'compare' (duration: 134.138096ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:25.878961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.942302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:25.879022Z","caller":"traceutil/trace.go:171","msg":"trace[1577247761] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26092; }","duration":"134.050275ms","start":"2026-05-01T01:48:25.744957Z","end":"2026-05-01T01:48:25.879007Z","steps":["trace[1577247761] 'agreement among raft nodes before linearized reading' (duration: 133.915701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:25.879032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.259902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:25.879144Z","caller":"traceutil/trace.go:171","msg":"trace[528687918] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26092; }","duration":"208.431177ms","start":"2026-05-01T01:48:25.670685Z","end":"2026-05-01T01:48:25.879116Z","steps":["trace[528687918] 'agreement among raft nodes before linearized reading' (duration: 208.221641ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:26.211773Z","caller":"traceutil/trace.go:171","msg":"trace[2092535469] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"243.916975ms","start":"2026-05-01T01:48:25.96782Z","end":"2026-05-01T01:48:26.211737Z","steps":["trace[2092535469] 'process raft request' (duration: 243.769632ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:26.211803Z","caller":"traceutil/trace.go:171","msg":"trace[1044041028] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"244.535802ms","start":"2026-05-01T01:48:25.967234Z","end":"2026-05-01T01:48:26.21177Z","steps":["trace[1044041028] 'process raft request' (duration: 183.40242ms)","trace[1044041028] 'compare' (duration: 60.698971ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:26.212025Z","caller":"traceutil/trace.go:171","msg":"trace[1735527199] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"243.935756ms","start":"2026-05-01T01:48:25.968042Z","end":"2026-05-01T01:48:26.211977Z","steps":["trace[1735527199] 'process raft request' (duration: 243.631048ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:26.212115Z","caller":"traceutil/trace.go:171","msg":"trace[1702160429] linearizableReadLoop","detail":"{readStateIndex:26958; appliedIndex:26955; }","duration":"198.794006ms","start":"2026-05-01T01:48:26.013309Z","end":"2026-05-01T01:48:26.212103Z","steps":["trace[1702160429] 'read index received' (duration: 137.403866ms)","trace[1702160429] 'applied index is now lower than readState.Index' (duration: 61.388ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:26.212103Z","caller":"traceutil/trace.go:171","msg":"trace[1697488287] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"194.11675ms","start":"2026-05-01T01:48:26.017955Z","end":"2026-05-01T01:48:26.212072Z","steps":["trace[1697488287] 'process raft request' (duration: 193.957365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:26.21219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.526176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:26.212246Z","caller":"traceutil/trace.go:171","msg":"trace[1652194971] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26097; }","duration":"226.641429ms","start":"2026-05-01T01:48:25.98559Z","end":"2026-05-01T01:48:26.212231Z","steps":["trace[1652194971] 'agreement among raft nodes before linearized reading' (duration: 226.518595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:26.212295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.151391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-01T01:48:26.212318Z","caller":"traceutil/trace.go:171","msg":"trace[2025990130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26097; }","duration":"248.227853ms","start":"2026-05-01T01:48:25.964083Z","end":"2026-05-01T01:48:26.212311Z","steps":["trace[2025990130] 'agreement among raft nodes before linearized reading' (duration: 248.069729ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:26.663557Z","caller":"traceutil/trace.go:171","msg":"trace[156123163] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"175.097185ms","start":"2026-05-01T01:48:26.488427Z","end":"2026-05-01T01:48:26.663524Z","steps":["trace[156123163] 'process raft request' (duration: 174.862849ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:26.748721Z","caller":"traceutil/trace.go:171","msg":"trace[1595473913] linearizableReadLoop","detail":"{readStateIndex:26963; appliedIndex:26961; }","duration":"188.846206ms","start":"2026-05-01T01:48:26.559851Z","end":"2026-05-01T01:48:26.748697Z","steps":["trace[1595473913] 'read index received' (duration: 103.445937ms)","trace[1595473913] 'applied index is now lower than readState.Index' (duration: 85.399089ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:48:26.748818Z","caller":"traceutil/trace.go:171","msg":"trace[1028456993] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"229.299041ms","start":"2026-05-01T01:48:26.519444Z","end":"2026-05-01T01:48:26.748743Z","steps":["trace[1028456993] 'process raft request' (duration: 207.925673ms)","trace[1028456993] 'compare' (duration: 21.202644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:48:26.748919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.032432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:26.749017Z","caller":"traceutil/trace.go:171","msg":"trace[602399122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26101; }","duration":"189.189786ms","start":"2026-05-01T01:48:26.559806Z","end":"2026-05-01T01:48:26.748995Z","steps":["trace[602399122] 'agreement among raft nodes before linearized reading' (duration: 189.010141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:48:26.815252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.489133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-01T01:48:26.815406Z","caller":"traceutil/trace.go:171","msg":"trace[903719432] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"125.388731ms","start":"2026-05-01T01:48:26.689954Z","end":"2026-05-01T01:48:26.815343Z","steps":["trace[903719432] 'process raft request' (duration: 124.891848ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:26.81544Z","caller":"traceutil/trace.go:171","msg":"trace[1181138834] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26102; }","duration":"159.74419ms","start":"2026-05-01T01:48:26.65567Z","end":"2026-05-01T01:48:26.815415Z","steps":["trace[1181138834] 'agreement among raft nodes before linearized reading' (duration: 159.492173ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:27.431816Z","caller":"traceutil/trace.go:171","msg":"trace[195032492] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"102.228125ms","start":"2026-05-01T01:48:27.32956Z","end":"2026-05-01T01:48:27.431788Z","steps":["trace[195032492] 'process raft request' (duration: 98.444193ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:28.334121Z","caller":"traceutil/trace.go:171","msg":"trace[769367882] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"110.711173ms","start":"2026-05-01T01:48:28.223388Z","end":"2026-05-01T01:48:28.334099Z","steps":["trace[769367882] 'process raft request' (duration: 110.528228ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:28.334439Z","caller":"traceutil/trace.go:171","msg":"trace[179059602] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"111.023211ms","start":"2026-05-01T01:48:28.223392Z","end":"2026-05-01T01:48:28.334415Z","steps":["trace[179059602] 'process raft request' (duration: 110.661572ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:48:28.334586Z","caller":"traceutil/trace.go:171","msg":"trace[779658475] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"111.125275ms","start":"2026-05-01T01:48:28.22345Z","end":"2026-05-01T01:48:28.334575Z","steps":["trace[779658475] 'process raft request' (duration: 110.822837ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:50:14.838163Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006dbb.wal"} {"level":"info","ts":"2026-05-01T01:52:06.138685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25418} {"level":"info","ts":"2026-05-01T01:52:06.328713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25418,"took":"185.363752ms","hash":1879826414,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":35504128,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-01T01:52:06.328799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1879826414,"revision":25418,"compact-revision":22038} {"level":"info","ts":"2026-05-01T01:53:38.545635Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-01T01:53:38.553086Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-01T01:53:38.553352Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-01T01:57:06.147371Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28354} {"level":"info","ts":"2026-05-01T01:57:06.295404Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28354,"took":"142.284411ms","hash":4099877988,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31117312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-01T01:57:06.295484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4099877988,"revision":28354,"compact-revision":25418} {"level":"info","ts":"2026-05-01T02:02:06.154482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30611} {"level":"info","ts":"2026-05-01T02:02:06.278033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30611,"took":"118.640926ms","hash":1299819274,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":32010240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-01T02:02:06.278091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1299819274,"revision":30611,"compact-revision":28354} {"level":"info","ts":"2026-05-01T02:07:06.161765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32933} {"level":"info","ts":"2026-05-01T02:07:06.317933Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32933,"took":"150.367575ms","hash":1862061338,"current-db-size-bytes":59768832,"current-db-size":"60 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-01T02:07:06.318011Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1862061338,"revision":32933,"compact-revision":30611} {"level":"info","ts":"2026-05-01T02:12:06.170483Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35260} {"level":"info","ts":"2026-05-01T02:12:06.331635Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35260,"took":"155.626015ms","hash":1343802714,"current-db-size-bytes":61091840,"current-db-size":"61 MB","current-db-size-in-use-bytes":31113216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-01T02:12:06.331725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1343802714,"revision":35260,"compact-revision":32933} {"level":"info","ts":"2026-05-01T02:14:55.859128Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-01T02:14:55.864046Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-01T02:14:55.864634Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-01T02:17:06.175932Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37492} {"level":"info","ts":"2026-05-01T02:17:06.302691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37492,"took":"121.554733ms","hash":2008365931,"current-db-size-bytes":61513728,"current-db-size":"62 MB","current-db-size-in-use-bytes":31064064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-01T02:17:06.30279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2008365931,"revision":37492,"compact-revision":35260}