{"level":"warn","ts":"2026-09-07T18:19:56.921778Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:19:56.921895Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.144: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://162.253.55.144:2380","--initial-cluster=instance=https://162.253.55.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.144: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-09-07T18:19:56.922167Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:19:56.922222Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.144:2380"]} {"level":"info","ts":"2026-09-07T18:19:56.922315Z","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-09-07T18:19:56.923251Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"]} {"level":"info","ts":"2026-09-07T18:19:56.92347Z","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://162.253.55.144:2380"],"listen-peer-urls":["https://162.253.55.144:2380"],"advertise-client-urls":["https://162.253.55.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.144: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-09-07T18:19:56.926303Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.451065ms"} {"level":"info","ts":"2026-09-07T18:19:56.939206Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"873be3731fd7a3eb","cluster-id":"9ca43c00a45c1826"} {"level":"info","ts":"2026-09-07T18:19:56.939307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T18:19:56.939342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became follower at term 0"} {"level":"info","ts":"2026-09-07T18:19:56.939356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 873be3731fd7a3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T18:19:56.939375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became follower at term 1"} {"level":"info","ts":"2026-09-07T18:19:56.939424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=(9744632302341170155)"} {"level":"warn","ts":"2026-09-07T18:19:56.94536Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T18:19:56.947419Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T18:19:56.94905Z","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-09-07T18:19:56.952376Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"873be3731fd7a3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T18:19:56.952569Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"873be3731fd7a3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T18:19:56.952625Z","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-09-07T18:19:56.952874Z","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-09-07T18:19:56.952953Z","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-09-07T18:19:56.954638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=(9744632302341170155)"} {"level":"info","ts":"2026-09-07T18:19:56.95482Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ca43c00a45c1826","local-member-id":"873be3731fd7a3eb","added-peer-id":"873be3731fd7a3eb","added-peer-peer-urls":["https://162.253.55.144:2380"]} {"level":"info","ts":"2026-09-07T18:19:56.956742Z","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-09-07T18:19:56.957003Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"873be3731fd7a3eb","initial-advertise-peer-urls":["https://162.253.55.144:2380"],"listen-peer-urls":["https://162.253.55.144:2380"],"advertise-client-urls":["https://162.253.55.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T18:19:56.957065Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T18:19:56.956896Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.144:2380"} {"level":"info","ts":"2026-09-07T18:19:56.957209Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.144:2380"} {"level":"info","ts":"2026-09-07T18:19:57.240497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T18:19:57.240587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T18:19:57.240637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb received MsgPreVoteResp from 873be3731fd7a3eb at term 1"} {"level":"info","ts":"2026-09-07T18:19:57.240661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became candidate at term 2"} {"level":"info","ts":"2026-09-07T18:19:57.240674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb received MsgVoteResp from 873be3731fd7a3eb at term 2"} {"level":"info","ts":"2026-09-07T18:19:57.240698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became leader at term 2"} {"level":"info","ts":"2026-09-07T18:19:57.240713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 873be3731fd7a3eb elected leader 873be3731fd7a3eb at term 2"} {"level":"info","ts":"2026-09-07T18:19:57.242099Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:19:57.243125Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"873be3731fd7a3eb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.144:2379]}","request-path":"/0/members/873be3731fd7a3eb/attributes","cluster-id":"9ca43c00a45c1826","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T18:19:57.243163Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:19:57.243194Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:19:57.243533Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T18:19:57.243643Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T18:19:57.243832Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ca43c00a45c1826","local-member-id":"873be3731fd7a3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:19:57.243999Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:19:57.244066Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:19:57.246299Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.144:2379"} {"level":"info","ts":"2026-09-07T18:19:57.247051Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T18:20:10.386899Z","caller":"traceutil/trace.go:171","msg":"trace[330352120] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"119.524672ms","start":"2026-09-07T18:20:10.267341Z","end":"2026-09-07T18:20:10.386866Z","steps":["trace[330352120] 'process raft request' (duration: 119.351027ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:20:10.615695Z","caller":"traceutil/trace.go:171","msg":"trace[1552829401] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"219.006256ms","start":"2026-09-07T18:20:10.396659Z","end":"2026-09-07T18:20:10.615665Z","steps":["trace[1552829401] 'process raft request' (duration: 218.737208ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:20:39.546512Z","caller":"traceutil/trace.go:171","msg":"trace[2032039086] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"262.028595ms","start":"2026-09-07T18:20:39.284448Z","end":"2026-09-07T18:20:39.546477Z","steps":["trace[2032039086] 'process raft request' (duration: 261.85867ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:20:44.058627Z","caller":"traceutil/trace.go:171","msg":"trace[1217271109] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"327.213917ms","start":"2026-09-07T18:20:43.73138Z","end":"2026-09-07T18:20:44.058593Z","steps":["trace[1217271109] 'process raft request' (duration: 327.137015ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:20:44.058688Z","caller":"traceutil/trace.go:171","msg":"trace[319139547] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"497.661017ms","start":"2026-09-07T18:20:43.560943Z","end":"2026-09-07T18:20:44.058604Z","steps":["trace[319139547] 'read index received' (duration: 495.798631ms)","trace[319139547] 'applied index is now lower than readState.Index' (duration: 1.858556ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:20:44.058884Z","caller":"traceutil/trace.go:171","msg":"trace[1027348742] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"509.656753ms","start":"2026-09-07T18:20:43.549191Z","end":"2026-09-07T18:20:44.058848Z","steps":["trace[1027348742] 'process raft request' (duration: 507.525168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:44.059147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.874481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lknlq\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-09-07T18:20:44.059113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.519572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:20:44.059276Z","caller":"traceutil/trace.go:171","msg":"trace[750118756] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lknlq; range_end:; response_count:1; response_revision:642; }","duration":"139.039698ms","start":"2026-09-07T18:20:43.920208Z","end":"2026-09-07T18:20:44.059247Z","steps":["trace[750118756] 'agreement among raft nodes before linearized reading' (duration: 138.857111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:44.05935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:20:43.549167Z","time spent":"509.793746ms","remote":"127.0.0.1:49756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T18:20:44.059291Z","caller":"traceutil/trace.go:171","msg":"trace[664379707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"268.733539ms","start":"2026-09-07T18:20:43.790537Z","end":"2026-09-07T18:20:44.05927Z","steps":["trace[664379707] 'agreement among raft nodes before linearized reading' (duration: 268.524712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:44.059635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:20:43.73135Z","time spent":"327.373892ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:20:44.060139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.186114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-07T18:20:44.060193Z","caller":"traceutil/trace.go:171","msg":"trace[2067416528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"499.301727ms","start":"2026-09-07T18:20:43.56088Z","end":"2026-09-07T18:20:44.060182Z","steps":["trace[2067416528] 'agreement among raft nodes before linearized reading' (duration: 498.025578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:44.060218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:20:43.560858Z","time spent":"499.353838ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T18:21:00.997369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.217991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-07T18:21:00.997406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.335214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-07T18:21:00.997453Z","caller":"traceutil/trace.go:171","msg":"trace[1166704695] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:811; }","duration":"136.362325ms","start":"2026-09-07T18:21:00.861073Z","end":"2026-09-07T18:21:00.997435Z","steps":["trace[1166704695] 'count revisions from in-memory index tree' (duration: 136.117007ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:00.997466Z","caller":"traceutil/trace.go:171","msg":"trace[579415550] range","detail":"{range_begin:/registry/masterleases/162.253.55.144; range_end:; response_count:1; response_revision:811; }","duration":"136.430367ms","start":"2026-09-07T18:21:00.861022Z","end":"2026-09-07T18:21:00.997452Z","steps":["trace[579415550] 'range keys from in-memory index tree' (duration: 136.187ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:29.751755Z","caller":"traceutil/trace.go:171","msg":"trace[28292112] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"173.973557ms","start":"2026-09-07T18:21:29.577749Z","end":"2026-09-07T18:21:29.751722Z","steps":["trace[28292112] 'read index received' (duration: 173.806341ms)","trace[28292112] 'applied index is now lower than readState.Index' (duration: 165.716µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:21:29.751827Z","caller":"traceutil/trace.go:171","msg":"trace[2088177495] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"213.682647ms","start":"2026-09-07T18:21:29.538098Z","end":"2026-09-07T18:21:29.75178Z","steps":["trace[2088177495] 'process raft request' (duration: 213.435419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:21:29.7522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.377809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T18:21:29.75231Z","caller":"traceutil/trace.go:171","msg":"trace[1661188557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1207; }","duration":"174.564875ms","start":"2026-09-07T18:21:29.577722Z","end":"2026-09-07T18:21:29.752287Z","steps":["trace[1661188557] 'agreement among raft nodes before linearized reading' (duration: 174.205424ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:14.775253Z","caller":"traceutil/trace.go:171","msg":"trace[763176949] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"379.350292ms","start":"2026-09-07T18:22:14.395869Z","end":"2026-09-07T18:22:14.775219Z","steps":["trace[763176949] 'process raft request' (duration: 379.129986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:22:14.775529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:22:14.395857Z","time spent":"379.519468ms","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:22:14.899635Z","caller":"traceutil/trace.go:171","msg":"trace[874348232] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1930; }","duration":"303.207183ms","start":"2026-09-07T18:22:14.596392Z","end":"2026-09-07T18:22:14.899599Z","steps":["trace[874348232] 'read index received' (duration: 178.559506ms)","trace[874348232] 'applied index is now lower than readState.Index' (duration: 124.646417ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:22:14.89967Z","caller":"traceutil/trace.go:171","msg":"trace[480841462] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"325.196307ms","start":"2026-09-07T18:22:14.574424Z","end":"2026-09-07T18:22:14.899621Z","steps":["trace[480841462] 'process raft request' (duration: 271.861476ms)","trace[480841462] 'compare' (duration: 53.175646ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:22:14.899962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.534562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-07T18:22:14.900003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.782848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T18:22:14.900058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.414203ms","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-09-07T18:22:14.90006Z","caller":"traceutil/trace.go:171","msg":"trace[626328592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1864; }","duration":"303.677427ms","start":"2026-09-07T18:22:14.596358Z","end":"2026-09-07T18:22:14.900036Z","steps":["trace[626328592] 'agreement among raft nodes before linearized reading' (duration: 303.360697ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:14.900078Z","caller":"traceutil/trace.go:171","msg":"trace[1978618438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1864; }","duration":"109.919702ms","start":"2026-09-07T18:22:14.790136Z","end":"2026-09-07T18:22:14.900055Z","steps":["trace[1978618438] 'agreement among raft nodes before linearized reading' (duration: 109.808299ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:14.900111Z","caller":"traceutil/trace.go:171","msg":"trace[819239728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1864; }","duration":"279.63717ms","start":"2026-09-07T18:22:14.620458Z","end":"2026-09-07T18:22:14.900095Z","steps":["trace[819239728] 'agreement among raft nodes before linearized reading' (duration: 279.306691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:22:14.900123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.813237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e11e4c8a-9410-459d-8641-b391b548513c\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-09-07T18:22:14.900137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:22:14.596345Z","time spent":"303.773479ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T18:22:14.900136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:22:14.574398Z","time spent":"325.364373ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:22:14.900225Z","caller":"traceutil/trace.go:171","msg":"trace[1177919030] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e11e4c8a-9410-459d-8641-b391b548513c; range_end:; response_count:1; response_revision:1864; }","duration":"197.949611ms","start":"2026-09-07T18:22:14.702258Z","end":"2026-09-07T18:22:14.900208Z","steps":["trace[1177919030] 'agreement among raft nodes before linearized reading' (duration: 197.750325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:22:15.209995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.871286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-07T18:22:15.210079Z","caller":"traceutil/trace.go:171","msg":"trace[25308473] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1867; }","duration":"182.010229ms","start":"2026-09-07T18:22:15.02805Z","end":"2026-09-07T18:22:15.210061Z","steps":["trace[25308473] 'range keys from in-memory index tree' (duration: 181.66599ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:29.1357Z","caller":"traceutil/trace.go:171","msg":"trace[317415445] linearizableReadLoop","detail":"{readStateIndex:2054; appliedIndex:2053; }","duration":"126.91875ms","start":"2026-09-07T18:22:29.008753Z","end":"2026-09-07T18:22:29.135672Z","steps":["trace[317415445] 'read index received' (duration: 126.771046ms)","trace[317415445] 'applied index is now lower than readState.Index' (duration: 146.304µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:22:29.135741Z","caller":"traceutil/trace.go:171","msg":"trace[1233588137] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"132.823619ms","start":"2026-09-07T18:22:29.002886Z","end":"2026-09-07T18:22:29.13571Z","steps":["trace[1233588137] 'process raft request' (duration: 132.649523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:22:29.135917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.142477ms","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-09-07T18:22:29.135959Z","caller":"traceutil/trace.go:171","msg":"trace[1090214129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1982; }","duration":"127.21886ms","start":"2026-09-07T18:22:29.008729Z","end":"2026-09-07T18:22:29.135948Z","steps":["trace[1090214129] 'agreement among raft nodes before linearized reading' (duration: 127.083466ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:29.149972Z","caller":"traceutil/trace.go:171","msg":"trace[216328681] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"137.228972ms","start":"2026-09-07T18:22:29.012732Z","end":"2026-09-07T18:22:29.149961Z","steps":["trace[216328681] 'process raft request' (duration: 137.17923ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:05.200791Z","caller":"traceutil/trace.go:171","msg":"trace[119889312] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"105.196763ms","start":"2026-09-07T18:23:05.095557Z","end":"2026-09-07T18:23:05.200754Z","steps":["trace[119889312] 'process raft request' (duration: 104.994417ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:51.809421Z","caller":"traceutil/trace.go:171","msg":"trace[1184193280] linearizableReadLoop","detail":"{readStateIndex:2709; appliedIndex:2708; }","duration":"108.495772ms","start":"2026-09-07T18:23:51.7009Z","end":"2026-09-07T18:23:51.809396Z","steps":["trace[1184193280] 'read index received' (duration: 108.327147ms)","trace[1184193280] 'applied index is now lower than readState.Index' (duration: 167.845µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:23:51.809482Z","caller":"traceutil/trace.go:171","msg":"trace[658757848] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"116.821795ms","start":"2026-09-07T18:23:51.692631Z","end":"2026-09-07T18:23:51.809452Z","steps":["trace[658757848] 'process raft request' (duration: 116.648969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:51.809737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.827963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-07T18:23:51.809812Z","caller":"traceutil/trace.go:171","msg":"trace[1044508781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2613; }","duration":"108.956297ms","start":"2026-09-07T18:23:51.700837Z","end":"2026-09-07T18:23:51.809794Z","steps":["trace[1044508781] 'agreement among raft nodes before linearized reading' (duration: 108.677609ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:55.600291Z","caller":"traceutil/trace.go:171","msg":"trace[1147918809] transaction","detail":"{read_only:false; response_revision:2637; number_of_response:1; }","duration":"219.209552ms","start":"2026-09-07T18:23:55.381054Z","end":"2026-09-07T18:23:55.600264Z","steps":["trace[1147918809] 'process raft request' (duration: 218.998016ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:28.804009Z","caller":"traceutil/trace.go:171","msg":"trace[1001078532] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"184.090058ms","start":"2026-09-07T18:24:28.619878Z","end":"2026-09-07T18:24:28.803968Z","steps":["trace[1001078532] 'process raft request' (duration: 178.677986ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:28.81164Z","caller":"traceutil/trace.go:171","msg":"trace[2023077391] linearizableReadLoop","detail":"{readStateIndex:2944; appliedIndex:2942; }","duration":"178.920423ms","start":"2026-09-07T18:24:28.63269Z","end":"2026-09-07T18:24:28.81161Z","steps":["trace[2023077391] 'read index received' (duration: 165.91163ms)","trace[2023077391] 'applied index is now lower than readState.Index' (duration: 13.007333ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:24:28.81172Z","caller":"traceutil/trace.go:171","msg":"trace[1723374243] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"186.324697ms","start":"2026-09-07T18:24:28.625351Z","end":"2026-09-07T18:24:28.811676Z","steps":["trace[1723374243] 'process raft request' (duration: 186.135392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:24:28.811936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.216252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T18:24:28.811979Z","caller":"traceutil/trace.go:171","msg":"trace[715665427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2840; }","duration":"179.316265ms","start":"2026-09-07T18:24:28.63265Z","end":"2026-09-07T18:24:28.811966Z","steps":["trace[715665427] 'agreement among raft nodes before linearized reading' (duration: 179.103909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:27:25.167388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.736163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:27:25.16748Z","caller":"traceutil/trace.go:171","msg":"trace[322870634] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3976; }","duration":"122.886888ms","start":"2026-09-07T18:27:25.044576Z","end":"2026-09-07T18:27:25.167463Z","steps":["trace[322870634] 'count revisions from in-memory index tree' (duration: 122.640891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:27:25.168133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.607077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-07T18:27:25.168302Z","caller":"traceutil/trace.go:171","msg":"trace[340278836] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3976; }","duration":"232.875895ms","start":"2026-09-07T18:27:24.935402Z","end":"2026-09-07T18:27:25.168278Z","steps":["trace[340278836] 'range keys from in-memory index tree' (duration: 232.224915ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:28:52.79096Z","caller":"traceutil/trace.go:171","msg":"trace[583861742] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"209.751845ms","start":"2026-09-07T18:28:52.581188Z","end":"2026-09-07T18:28:52.79094Z","steps":["trace[583861742] 'process raft request' (duration: 130.256551ms)","trace[583861742] 'compare' (duration: 79.045861ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:28:52.791091Z","caller":"traceutil/trace.go:171","msg":"trace[640645380] linearizableReadLoop","detail":"{readStateIndex:5223; appliedIndex:5222; }","duration":"207.543621ms","start":"2026-09-07T18:28:52.58354Z","end":"2026-09-07T18:28:52.791084Z","steps":["trace[640645380] 'read index received' (duration: 127.86588ms)","trace[640645380] 'applied index is now lower than readState.Index' (duration: 79.677031ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:28:52.791459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.906921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-q8hq2-tls\" ","response":"range_response_count:1 size:2130"} {"level":"info","ts":"2026-09-07T18:28:52.791489Z","caller":"traceutil/trace.go:171","msg":"trace[625405868] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-q8hq2-tls; range_end:; response_count:1; response_revision:5038; }","duration":"207.967943ms","start":"2026-09-07T18:28:52.583513Z","end":"2026-09-07T18:28:52.791481Z","steps":["trace[625405868] 'agreement among raft nodes before linearized reading' (duration: 207.871971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:28:52.791674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.356573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-07T18:28:52.791697Z","caller":"traceutil/trace.go:171","msg":"trace[1358731796] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5038; }","duration":"203.422385ms","start":"2026-09-07T18:28:52.588268Z","end":"2026-09-07T18:28:52.79169Z","steps":["trace[1358731796] 'agreement among raft nodes before linearized reading' (duration: 203.362583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:28:56.82692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.289798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:28:56.827234Z","caller":"traceutil/trace.go:171","msg":"trace[600935099] linearizableReadLoop","detail":"{readStateIndex:5386; appliedIndex:5385; }","duration":"329.712925ms","start":"2026-09-07T18:28:56.497468Z","end":"2026-09-07T18:28:56.82718Z","steps":["trace[600935099] 'read index received' (duration: 100.901673ms)","trace[600935099] 'applied index is now lower than readState.Index' (duration: 228.809682ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:28:56.827242Z","caller":"traceutil/trace.go:171","msg":"trace[448138514] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"393.167906ms","start":"2026-09-07T18:28:56.434046Z","end":"2026-09-07T18:28:56.827214Z","steps":["trace[448138514] 'process raft request' (duration: 164.320393ms)","trace[448138514] 'compare' (duration: 228.128542ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:28:56.82735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.870621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1cec0bbb-500e-41e8-ac27-0f2f6c8da013\" ","response":"range_response_count:1 size:3718"} {"level":"warn","ts":"2026-09-07T18:28:56.82739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:28:56.434022Z","time spent":"393.2795ms","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3703,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:28:56.827439Z","caller":"traceutil/trace.go:171","msg":"trace[844441020] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1cec0bbb-500e-41e8-ac27-0f2f6c8da013; range_end:; response_count:1; response_revision:5196; }","duration":"329.957943ms","start":"2026-09-07T18:28:56.497427Z","end":"2026-09-07T18:28:56.827385Z","steps":["trace[844441020] 'agreement among raft nodes before linearized reading' (duration: 329.872181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:28:56.827496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:28:56.497407Z","time spent":"330.073716ms","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3742,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1cec0bbb-500e-41e8-ac27-0f2f6c8da013\" "} {"level":"info","ts":"2026-09-07T18:29:10.71654Z","caller":"traceutil/trace.go:171","msg":"trace[1703390301] linearizableReadLoop","detail":"{readStateIndex:5652; appliedIndex:5651; }","duration":"135.556382ms","start":"2026-09-07T18:29:10.580955Z","end":"2026-09-07T18:29:10.716511Z","steps":["trace[1703390301] 'read index received' (duration: 57.881713ms)","trace[1703390301] 'applied index is now lower than readState.Index' (duration: 77.672149ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:10.716605Z","caller":"traceutil/trace.go:171","msg":"trace[1084996100] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"239.376142ms","start":"2026-09-07T18:29:10.477083Z","end":"2026-09-07T18:29:10.716459Z","steps":["trace[1084996100] 'process raft request' (duration: 161.866598ms)","trace[1084996100] 'compare' (duration: 77.335299ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:29:10.716733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.969114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-tkcxv\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-09-07T18:29:10.716765Z","caller":"traceutil/trace.go:171","msg":"trace[380121229] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-tkcxv; range_end:; response_count:1; response_revision:5453; }","duration":"136.056976ms","start":"2026-09-07T18:29:10.580698Z","end":"2026-09-07T18:29:10.716755Z","steps":["trace[380121229] 'agreement among raft nodes before linearized reading' (duration: 135.903522ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:10.967648Z","caller":"traceutil/trace.go:171","msg":"trace[2074090053] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"166.940801ms","start":"2026-09-07T18:29:10.800687Z","end":"2026-09-07T18:29:10.967628Z","steps":["trace[2074090053] 'process raft request' (duration: 80.387353ms)","trace[2074090053] 'compare' (duration: 86.355102ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:10.982687Z","caller":"traceutil/trace.go:171","msg":"trace[749118297] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"173.776317ms","start":"2026-09-07T18:29:10.808897Z","end":"2026-09-07T18:29:10.982674Z","steps":["trace[749118297] 'process raft request' (duration: 173.731836ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:10.982931Z","caller":"traceutil/trace.go:171","msg":"trace[2122286155] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"181.370787ms","start":"2026-09-07T18:29:10.80153Z","end":"2026-09-07T18:29:10.982901Z","steps":["trace[2122286155] 'process raft request' (duration: 181.064938ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:10.983145Z","caller":"traceutil/trace.go:171","msg":"trace[1363162500] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"182.258374ms","start":"2026-09-07T18:29:10.800879Z","end":"2026-09-07T18:29:10.983138Z","steps":["trace[1363162500] 'process raft request' (duration: 181.592124ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:10.983161Z","caller":"traceutil/trace.go:171","msg":"trace[1143810200] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"181.82041ms","start":"2026-09-07T18:29:10.801331Z","end":"2026-09-07T18:29:10.983151Z","steps":["trace[1143810200] 'process raft request' (duration: 181.225743ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:10.995004Z","caller":"traceutil/trace.go:171","msg":"trace[137785927] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"103.672097ms","start":"2026-09-07T18:29:10.891312Z","end":"2026-09-07T18:29:10.994984Z","steps":["trace[137785927] 'process raft request' (duration: 103.537623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:11.320678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.841477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-09-07T18:29:11.321001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:10.995888Z","time spent":"325.110077ms","remote":"127.0.0.1:49304","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-07T18:29:11.32109Z","caller":"traceutil/trace.go:171","msg":"trace[921217187] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"333.996067ms","start":"2026-09-07T18:29:10.987069Z","end":"2026-09-07T18:29:11.321065Z","steps":["trace[921217187] 'process raft request' (duration: 91.687135ms)","trace[921217187] 'compare' (duration: 241.634471ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:11.321091Z","caller":"traceutil/trace.go:171","msg":"trace[2021322173] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"329.81804ms","start":"2026-09-07T18:29:10.991248Z","end":"2026-09-07T18:29:11.321066Z","steps":["trace[2021322173] 'process raft request' (duration: 329.623944ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:11.321086Z","caller":"traceutil/trace.go:171","msg":"trace[463494753] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"332.8146ms","start":"2026-09-07T18:29:10.988242Z","end":"2026-09-07T18:29:11.321056Z","steps":["trace[463494753] 'process raft request' (duration: 332.530362ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:11.321136Z","caller":"traceutil/trace.go:171","msg":"trace[504490626] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5661; }","duration":"326.132707ms","start":"2026-09-07T18:29:10.994961Z","end":"2026-09-07T18:29:11.321093Z","steps":["trace[504490626] 'read index received' (duration: 83.789585ms)","trace[504490626] 'applied index is now lower than readState.Index' (duration: 242.335722ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:29:11.321213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:10.987051Z","time spent":"334.089669ms","remote":"127.0.0.1:49420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1329,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:29:11.321225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:10.991239Z","time spent":"329.908552ms","remote":"127.0.0.1:49726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:29:11.321249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:10.988228Z","time spent":"332.931774ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1951,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:29:11.321295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.932801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zzxxq\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-09-07T18:29:11.321345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.471134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:29:11.321356Z","caller":"traceutil/trace.go:171","msg":"trace[941290265] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zzxxq; range_end:; response_count:1; response_revision:5464; }","duration":"360.033184ms","start":"2026-09-07T18:29:10.961308Z","end":"2026-09-07T18:29:11.321341Z","steps":["trace[941290265] 'agreement among raft nodes before linearized reading' (duration: 359.858709ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:11.321384Z","caller":"traceutil/trace.go:171","msg":"trace[2077717658] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:5464; }","duration":"117.548887ms","start":"2026-09-07T18:29:11.203824Z","end":"2026-09-07T18:29:11.321373Z","steps":["trace[2077717658] 'agreement among raft nodes before linearized reading' (duration: 117.488015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:11.321406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.608369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-07T18:29:11.321437Z","caller":"traceutil/trace.go:171","msg":"trace[1406272260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5464; }","duration":"195.66277ms","start":"2026-09-07T18:29:11.125766Z","end":"2026-09-07T18:29:11.321429Z","steps":["trace[1406272260] 'agreement among raft nodes before linearized reading' (duration: 195.576718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:11.32143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.695673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-07T18:29:11.321386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:10.961289Z","time spent":"360.092205ms","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-zzxxq\" "} {"level":"info","ts":"2026-09-07T18:29:11.321488Z","caller":"traceutil/trace.go:171","msg":"trace[1470943726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5464; }","duration":"204.793646ms","start":"2026-09-07T18:29:11.116678Z","end":"2026-09-07T18:29:11.321472Z","steps":["trace[1470943726] 'agreement among raft nodes before linearized reading' (duration: 204.61606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:36.518069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.999618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:29:36.518158Z","caller":"traceutil/trace.go:171","msg":"trace[1151810026] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5788; }","duration":"108.176764ms","start":"2026-09-07T18:29:36.409964Z","end":"2026-09-07T18:29:36.518141Z","steps":["trace[1151810026] 'count revisions from in-memory index tree' (duration: 107.852303ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:37.010065Z","caller":"traceutil/trace.go:171","msg":"trace[1306913590] transaction","detail":"{read_only:false; response_revision:5790; number_of_response:1; }","duration":"164.880209ms","start":"2026-09-07T18:29:36.84516Z","end":"2026-09-07T18:29:37.010041Z","steps":["trace[1306913590] 'process raft request' (duration: 164.693154ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:37.319842Z","caller":"traceutil/trace.go:171","msg":"trace[1533795000] linearizableReadLoop","detail":"{readStateIndex:6000; appliedIndex:5998; }","duration":"202.058743ms","start":"2026-09-07T18:29:37.117758Z","end":"2026-09-07T18:29:37.319817Z","steps":["trace[1533795000] 'read index received' (duration: 184.233415ms)","trace[1533795000] 'applied index is now lower than readState.Index' (duration: 17.824408ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:37.319902Z","caller":"traceutil/trace.go:171","msg":"trace[1261280471] transaction","detail":"{read_only:false; response_revision:5791; number_of_response:1; }","duration":"215.732457ms","start":"2026-09-07T18:29:37.104154Z","end":"2026-09-07T18:29:37.319886Z","steps":["trace[1261280471] 'process raft request' (duration: 197.828647ms)","trace[1261280471] 'compare' (duration: 17.327583ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:37.319989Z","caller":"traceutil/trace.go:171","msg":"trace[702446219] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"211.744897ms","start":"2026-09-07T18:29:37.108196Z","end":"2026-09-07T18:29:37.319941Z","steps":["trace[702446219] 'process raft request' (duration: 211.564392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:37.320109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.324972ms","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-09-07T18:29:37.320171Z","caller":"traceutil/trace.go:171","msg":"trace[513854989] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5792; }","duration":"202.430395ms","start":"2026-09-07T18:29:37.117725Z","end":"2026-09-07T18:29:37.320155Z","steps":["trace[513854989] 'agreement among raft nodes before linearized reading' (duration: 202.226398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:47.792255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.273307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:29:47.792417Z","caller":"traceutil/trace.go:171","msg":"trace[408061473] linearizableReadLoop","detail":"{readStateIndex:6083; appliedIndex:6081; }","duration":"173.777448ms","start":"2026-09-07T18:29:47.618615Z","end":"2026-09-07T18:29:47.792392Z","steps":["trace[408061473] 'read index received' (duration: 38.686441ms)","trace[408061473] 'applied index is now lower than readState.Index' (duration: 135.089227ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:47.792532Z","caller":"traceutil/trace.go:171","msg":"trace[1083963435] transaction","detail":"{read_only:false; response_revision:5872; number_of_response:1; }","duration":"174.83198ms","start":"2026-09-07T18:29:47.617686Z","end":"2026-09-07T18:29:47.792518Z","steps":["trace[1083963435] 'process raft request' (duration: 66.12323ms)","trace[1083963435] 'compare' (duration: 108.096012ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:29:47.792704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.414011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zzxxq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T18:29:47.792782Z","caller":"traceutil/trace.go:171","msg":"trace[1471708130] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zzxxq; range_end:; response_count:1; response_revision:5872; }","duration":"153.546585ms","start":"2026-09-07T18:29:47.639218Z","end":"2026-09-07T18:29:47.792764Z","steps":["trace[1471708130] 'agreement among raft nodes before linearized reading' (duration: 153.37059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:47.793104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.610341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:29:47.793235Z","caller":"traceutil/trace.go:171","msg":"trace[674191414] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5872; }","duration":"129.762166ms","start":"2026-09-07T18:29:47.66342Z","end":"2026-09-07T18:29:47.793182Z","steps":["trace[674191414] 'agreement among raft nodes before linearized reading' (duration: 129.559799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:47.793303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.677625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T18:29:47.793357Z","caller":"traceutil/trace.go:171","msg":"trace[1461446546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5872; }","duration":"174.762368ms","start":"2026-09-07T18:29:47.618579Z","end":"2026-09-07T18:29:47.793341Z","steps":["trace[1461446546] 'agreement among raft nodes before linearized reading' (duration: 174.529581ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:48.282451Z","caller":"traceutil/trace.go:171","msg":"trace[72651587] linearizableReadLoop","detail":"{readStateIndex:6085; appliedIndex:6084; }","duration":"364.22437ms","start":"2026-09-07T18:29:47.9182Z","end":"2026-09-07T18:29:48.282424Z","steps":["trace[72651587] 'read index received' (duration: 278.435285ms)","trace[72651587] 'applied index is now lower than readState.Index' (duration: 85.788025ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:48.282613Z","caller":"traceutil/trace.go:171","msg":"trace[3950625] transaction","detail":"{read_only:false; response_revision:5874; number_of_response:1; }","duration":"421.822912ms","start":"2026-09-07T18:29:47.86075Z","end":"2026-09-07T18:29:48.282573Z","steps":["trace[3950625] 'process raft request' (duration: 335.939064ms)","trace[3950625] 'compare' (duration: 85.188468ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:29:48.282717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.509379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"info","ts":"2026-09-07T18:29:48.282759Z","caller":"traceutil/trace.go:171","msg":"trace[2144826757] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5874; }","duration":"364.619372ms","start":"2026-09-07T18:29:47.918127Z","end":"2026-09-07T18:29:48.282747Z","steps":["trace[2144826757] 'agreement among raft nodes before linearized reading' (duration: 364.451818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:48.282795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:47.918099Z","time spent":"364.688435ms","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10419,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-07T18:29:48.282912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.689084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-07T18:29:48.282991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:47.860694Z","time spent":"422.003058ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:29:48.283049Z","caller":"traceutil/trace.go:171","msg":"trace[673785528] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5874; }","duration":"326.88145ms","start":"2026-09-07T18:29:47.956138Z","end":"2026-09-07T18:29:48.28302Z","steps":["trace[673785528] 'agreement among raft nodes before linearized reading' (duration: 326.55874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:48.283261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.010646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-07T18:29:48.283308Z","caller":"traceutil/trace.go:171","msg":"trace[956417984] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5874; }","duration":"295.129921ms","start":"2026-09-07T18:29:47.988162Z","end":"2026-09-07T18:29:48.283292Z","steps":["trace[956417984] 'agreement among raft nodes before linearized reading' (duration: 294.997557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:48.284239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:47.956117Z","time spent":"328.011094ms","remote":"127.0.0.1:49530","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":"info","ts":"2026-09-07T18:29:48.47876Z","caller":"traceutil/trace.go:171","msg":"trace[873936478] linearizableReadLoop","detail":"{readStateIndex:6087; appliedIndex:6086; }","duration":"141.935194ms","start":"2026-09-07T18:29:48.336789Z","end":"2026-09-07T18:29:48.478724Z","steps":["trace[873936478] 'read index received' (duration: 96.644434ms)","trace[873936478] 'applied index is now lower than readState.Index' (duration: 45.28904ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:48.478995Z","caller":"traceutil/trace.go:171","msg":"trace[1647884197] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"183.43153ms","start":"2026-09-07T18:29:48.295529Z","end":"2026-09-07T18:29:48.478961Z","steps":["trace[1647884197] 'process raft request' (duration: 138.018407ms)","trace[1647884197] 'compare' (duration: 45.014792ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:29:48.479174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.030162ms","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-09-07T18:29:48.479261Z","caller":"traceutil/trace.go:171","msg":"trace[376843960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5876; }","duration":"121.175627ms","start":"2026-09-07T18:29:48.358068Z","end":"2026-09-07T18:29:48.479244Z","steps":["trace[376843960] 'agreement among raft nodes before linearized reading' (duration: 120.99504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:48.479159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.350226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T18:29:48.479392Z","caller":"traceutil/trace.go:171","msg":"trace[1492762652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5876; }","duration":"142.654126ms","start":"2026-09-07T18:29:48.336717Z","end":"2026-09-07T18:29:48.479372Z","steps":["trace[1492762652] 'agreement among raft nodes before linearized reading' (duration: 142.229143ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:57.357731Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2997} {"level":"info","ts":"2026-09-07T18:29:57.427579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2997,"took":"68.72375ms","hash":521036636,"current-db-size-bytes":56877056,"current-db-size":"57 MB","current-db-size-in-use-bytes":32862208,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-07T18:29:57.427635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":521036636,"revision":2997,"compact-revision":-1} {"level":"info","ts":"2026-09-07T18:34:57.363165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5936} {"level":"info","ts":"2026-09-07T18:34:57.448849Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5936,"took":"84.368986ms","hash":521883936,"current-db-size-bytes":56877056,"current-db-size":"57 MB","current-db-size-in-use-bytes":26275840,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T18:34:57.448922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":521883936,"revision":5936,"compact-revision":2997} {"level":"info","ts":"2026-09-07T18:35:38.015595Z","caller":"traceutil/trace.go:171","msg":"trace[482877930] linearizableReadLoop","detail":"{readStateIndex:8816; appliedIndex:8815; }","duration":"107.661515ms","start":"2026-09-07T18:35:37.907913Z","end":"2026-09-07T18:35:38.015574Z","steps":["trace[482877930] 'read index received' (duration: 107.477528ms)","trace[482877930] 'applied index is now lower than readState.Index' (duration: 183.407µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:35:38.015784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.82869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:35:38.015834Z","caller":"traceutil/trace.go:171","msg":"trace[1989406008] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8519; }","duration":"107.920642ms","start":"2026-09-07T18:35:37.907899Z","end":"2026-09-07T18:35:38.01582Z","steps":["trace[1989406008] 'agreement among raft nodes before linearized reading' (duration: 107.780958ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:38.016016Z","caller":"traceutil/trace.go:171","msg":"trace[138394394] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"122.271228ms","start":"2026-09-07T18:35:37.893709Z","end":"2026-09-07T18:35:38.015981Z","steps":["trace[138394394] 'process raft request' (duration: 121.729691ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:38.226805Z","caller":"traceutil/trace.go:171","msg":"trace[499846927] linearizableReadLoop","detail":"{readStateIndex:8817; appliedIndex:8816; }","duration":"130.003731ms","start":"2026-09-07T18:35:38.096752Z","end":"2026-09-07T18:35:38.226756Z","steps":["trace[499846927] 'read index received' (duration: 85.079989ms)","trace[499846927] 'applied index is now lower than readState.Index' (duration: 44.922832ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:35:38.226822Z","caller":"traceutil/trace.go:171","msg":"trace[1589131710] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"143.13699ms","start":"2026-09-07T18:35:38.083659Z","end":"2026-09-07T18:35:38.226796Z","steps":["trace[1589131710] 'process raft request' (duration: 98.224738ms)","trace[1589131710] 'compare' (duration: 44.759768ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:35:38.226951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.170937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T18:35:38.227016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.154247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:35:38.227036Z","caller":"traceutil/trace.go:171","msg":"trace[588817752] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8520; }","duration":"130.290931ms","start":"2026-09-07T18:35:38.096725Z","end":"2026-09-07T18:35:38.227016Z","steps":["trace[588817752] 'agreement among raft nodes before linearized reading' (duration: 130.157546ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:38.22708Z","caller":"traceutil/trace.go:171","msg":"trace[2084867973] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8520; }","duration":"130.287781ms","start":"2026-09-07T18:35:38.096776Z","end":"2026-09-07T18:35:38.227064Z","steps":["trace[2084867973] 'agreement among raft nodes before linearized reading' (duration: 130.178218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:38.227425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.25323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:35:38.227452Z","caller":"traceutil/trace.go:171","msg":"trace[1016808902] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8520; }","duration":"130.300532ms","start":"2026-09-07T18:35:38.097143Z","end":"2026-09-07T18:35:38.227443Z","steps":["trace[1016808902] 'agreement among raft nodes before linearized reading' (duration: 130.26225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:38.227456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.573303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T18:35:38.227497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.073458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:35:38.227553Z","caller":"traceutil/trace.go:171","msg":"trace[1193023686] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8520; }","duration":"108.668465ms","start":"2026-09-07T18:35:38.118833Z","end":"2026-09-07T18:35:38.227501Z","steps":["trace[1193023686] 'agreement among raft nodes before linearized reading' (duration: 108.546872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:38.2276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.647735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:35:38.227592Z","caller":"traceutil/trace.go:171","msg":"trace[571120762] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8520; }","duration":"108.193641ms","start":"2026-09-07T18:35:38.119382Z","end":"2026-09-07T18:35:38.227576Z","steps":["trace[571120762] 'agreement among raft nodes before linearized reading' (duration: 108.072657ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:38.22762Z","caller":"traceutil/trace.go:171","msg":"trace[577890284] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8520; }","duration":"108.706457ms","start":"2026-09-07T18:35:38.118908Z","end":"2026-09-07T18:35:38.227615Z","steps":["trace[577890284] 'agreement among raft nodes before linearized reading' (duration: 108.670146ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:49.891675Z","caller":"traceutil/trace.go:171","msg":"trace[1462624577] linearizableReadLoop","detail":"{readStateIndex:8877; appliedIndex:8876; }","duration":"376.840079ms","start":"2026-09-07T18:35:49.514816Z","end":"2026-09-07T18:35:49.891656Z","steps":["trace[1462624577] 'read index received' (duration: 376.658952ms)","trace[1462624577] 'applied index is now lower than readState.Index' (duration: 180.517µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:35:49.89194Z","caller":"traceutil/trace.go:171","msg":"trace[625575446] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"495.235109ms","start":"2026-09-07T18:35:49.396696Z","end":"2026-09-07T18:35:49.891931Z","steps":["trace[625575446] 'process raft request' (duration: 494.853977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:49.892019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:35:49.396669Z","time spent":"495.291611ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:35:49.892246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.419317ms","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-09-07T18:35:49.892308Z","caller":"traceutil/trace.go:171","msg":"trace[388503863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8578; }","duration":"377.521659ms","start":"2026-09-07T18:35:49.51477Z","end":"2026-09-07T18:35:49.892291Z","steps":["trace[388503863] 'agreement among raft nodes before linearized reading' (duration: 377.338964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:49.892348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:35:49.514751Z","time spent":"377.587131ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-07T18:35:49.923995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.609522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T18:35:49.924024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.049988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zzxxq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T18:35:49.924052Z","caller":"traceutil/trace.go:171","msg":"trace[734813208] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zzxxq; range_end:; response_count:1; response_revision:8579; }","duration":"170.11612ms","start":"2026-09-07T18:35:49.753929Z","end":"2026-09-07T18:35:49.924045Z","steps":["trace[734813208] 'agreement among raft nodes before linearized reading' (duration: 170.045228ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:49.924051Z","caller":"traceutil/trace.go:171","msg":"trace[1714681921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8579; }","duration":"131.711074ms","start":"2026-09-07T18:35:49.792326Z","end":"2026-09-07T18:35:49.924037Z","steps":["trace[1714681921] 'agreement among raft nodes before linearized reading' (duration: 131.627242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:49.924232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.116427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T18:35:49.924277Z","caller":"traceutil/trace.go:171","msg":"trace[450794617] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"358.193293ms","start":"2026-09-07T18:35:49.566051Z","end":"2026-09-07T18:35:49.924244Z","steps":["trace[450794617] 'process raft request' (duration: 357.697468ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:49.924312Z","caller":"traceutil/trace.go:171","msg":"trace[1924583747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8579; }","duration":"345.253801ms","start":"2026-09-07T18:35:49.579039Z","end":"2026-09-07T18:35:49.924293Z","steps":["trace[1924583747] 'agreement among raft nodes before linearized reading' (duration: 344.990713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:49.924358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:35:49.57902Z","time spent":"345.330513ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T18:35:49.924443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:35:49.566027Z","time spent":"358.321277ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:35:50.051175Z","caller":"traceutil/trace.go:171","msg":"trace[880333181] linearizableReadLoop","detail":"{readStateIndex:8879; appliedIndex:8878; }","duration":"127.2708ms","start":"2026-09-07T18:35:49.923882Z","end":"2026-09-07T18:35:50.051153Z","steps":["trace[880333181] 'read index received' (duration: 101.716144ms)","trace[880333181] 'applied index is now lower than readState.Index' (duration: 25.553876ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:35:50.05124Z","caller":"traceutil/trace.go:171","msg":"trace[1233088956] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"155.277209ms","start":"2026-09-07T18:35:49.895916Z","end":"2026-09-07T18:35:50.051194Z","steps":["trace[1233088956] 'process raft request' (duration: 129.665681ms)","trace[1233088956] 'compare' (duration: 25.455292ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:35:50.051376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.400849ms","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-09-07T18:35:50.051437Z","caller":"traceutil/trace.go:171","msg":"trace[634978269] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8580; }","duration":"145.484433ms","start":"2026-09-07T18:35:49.905939Z","end":"2026-09-07T18:35:50.051423Z","steps":["trace[634978269] 'agreement among raft nodes before linearized reading' (duration: 145.302937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:50.060908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.541624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7b5f99c977-zfstr.18d31db29b635646\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-09-07T18:35:50.060966Z","caller":"traceutil/trace.go:171","msg":"trace[935789324] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7b5f99c977-zfstr.18d31db29b635646; range_end:; response_count:1; response_revision:8581; }","duration":"116.647358ms","start":"2026-09-07T18:35:49.944304Z","end":"2026-09-07T18:35:50.060951Z","steps":["trace[935789324] 'agreement among raft nodes before linearized reading' (duration: 116.40571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:50.060977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.041376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223824"} {"level":"info","ts":"2026-09-07T18:35:50.060974Z","caller":"traceutil/trace.go:171","msg":"trace[18897689] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"132.314474ms","start":"2026-09-07T18:35:49.928635Z","end":"2026-09-07T18:35:50.06095Z","steps":["trace[18897689] 'process raft request' (duration: 131.956492ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:50.061011Z","caller":"traceutil/trace.go:171","msg":"trace[1849245396] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8581; }","duration":"133.079637ms","start":"2026-09-07T18:35:49.927923Z","end":"2026-09-07T18:35:50.061003Z","steps":["trace[1849245396] 'agreement among raft nodes before linearized reading' (duration: 132.791068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:50.060996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.727117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:35:50.061077Z","caller":"traceutil/trace.go:171","msg":"trace[1513594258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8581; }","duration":"135.814908ms","start":"2026-09-07T18:35:49.925227Z","end":"2026-09-07T18:35:50.061042Z","steps":["trace[1513594258] 'agreement among raft nodes before linearized reading' (duration: 135.678223ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:50.547714Z","caller":"traceutil/trace.go:171","msg":"trace[719746143] linearizableReadLoop","detail":"{readStateIndex:8883; appliedIndex:8882; }","duration":"256.22158ms","start":"2026-09-07T18:35:50.291476Z","end":"2026-09-07T18:35:50.547697Z","steps":["trace[719746143] 'read index received' (duration: 256.108527ms)","trace[719746143] 'applied index is now lower than readState.Index' (duration: 112.433µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:35:50.547794Z","caller":"traceutil/trace.go:171","msg":"trace[97724774] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"259.133698ms","start":"2026-09-07T18:35:50.288635Z","end":"2026-09-07T18:35:50.547769Z","steps":["trace[97724774] 'process raft request' (duration: 258.947113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:50.547865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.376235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-07T18:35:50.547892Z","caller":"traceutil/trace.go:171","msg":"trace[1500585638] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8583; }","duration":"256.422997ms","start":"2026-09-07T18:35:50.291455Z","end":"2026-09-07T18:35:50.547878Z","steps":["trace[1500585638] 'agreement among raft nodes before linearized reading' (duration: 256.328194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:50.548042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.867358ms","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-09-07T18:35:50.548112Z","caller":"traceutil/trace.go:171","msg":"trace[1577793040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8583; }","duration":"251.970291ms","start":"2026-09-07T18:35:50.296124Z","end":"2026-09-07T18:35:50.548095Z","steps":["trace[1577793040] 'agreement among raft nodes before linearized reading' (duration: 251.735174ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:50.785354Z","caller":"traceutil/trace.go:171","msg":"trace[1002178497] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"233.79221ms","start":"2026-09-07T18:35:50.551543Z","end":"2026-09-07T18:35:50.785335Z","steps":["trace[1002178497] 'process raft request' (duration: 172.689587ms)","trace[1002178497] 'compare' (duration: 60.832145ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:35:50.78571Z","caller":"traceutil/trace.go:171","msg":"trace[239344799] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"232.344347ms","start":"2026-09-07T18:35:50.55298Z","end":"2026-09-07T18:35:50.785325Z","steps":["trace[239344799] 'process raft request' (duration: 232.262224ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:51.664932Z","caller":"traceutil/trace.go:171","msg":"trace[290145838] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"139.398417ms","start":"2026-09-07T18:35:51.52551Z","end":"2026-09-07T18:35:51.664908Z","steps":["trace[290145838] 'process raft request' (duration: 139.217751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:51.889885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.079272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:35:51.889974Z","caller":"traceutil/trace.go:171","msg":"trace[1255039596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8589; }","duration":"120.205776ms","start":"2026-09-07T18:35:51.769747Z","end":"2026-09-07T18:35:51.889952Z","steps":["trace[1255039596] 'range keys from in-memory index tree' (duration: 120.008309ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:52.052884Z","caller":"traceutil/trace.go:171","msg":"trace[1933212528] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"153.814395ms","start":"2026-09-07T18:35:51.899038Z","end":"2026-09-07T18:35:52.052852Z","steps":["trace[1933212528] 'process raft request' (duration: 153.66502ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:52.193456Z","caller":"traceutil/trace.go:171","msg":"trace[1169831003] linearizableReadLoop","detail":"{readStateIndex:8892; appliedIndex:8891; }","duration":"138.455879ms","start":"2026-09-07T18:35:52.054975Z","end":"2026-09-07T18:35:52.193431Z","steps":["trace[1169831003] 'read index received' (duration: 84.748529ms)","trace[1169831003] 'applied index is now lower than readState.Index' (duration: 53.7064ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:35:52.193499Z","caller":"traceutil/trace.go:171","msg":"trace[6345701] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"264.513152ms","start":"2026-09-07T18:35:51.928953Z","end":"2026-09-07T18:35:52.193466Z","steps":["trace[6345701] 'process raft request' (duration: 210.73148ms)","trace[6345701] 'compare' (duration: 53.620506ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:35:52.193708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.130304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zzxxq\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-09-07T18:35:52.193714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.718157ms","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-09-07T18:35:52.193735Z","caller":"traceutil/trace.go:171","msg":"trace[77609785] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zzxxq; range_end:; response_count:1; response_revision:8591; }","duration":"123.189146ms","start":"2026-09-07T18:35:52.07054Z","end":"2026-09-07T18:35:52.193729Z","steps":["trace[77609785] 'agreement among raft nodes before linearized reading' (duration: 123.105794ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:35:52.193759Z","caller":"traceutil/trace.go:171","msg":"trace[1841117542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8591; }","duration":"138.81584ms","start":"2026-09-07T18:35:52.054927Z","end":"2026-09-07T18:35:52.193743Z","steps":["trace[1841117542] 'agreement among raft nodes before linearized reading' (duration: 138.655435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:35:52.193779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.765556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T18:35:52.193839Z","caller":"traceutil/trace.go:171","msg":"trace[1583278594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8591; }","duration":"129.844078ms","start":"2026-09-07T18:35:52.063973Z","end":"2026-09-07T18:35:52.193817Z","steps":["trace[1583278594] 'agreement among raft nodes before linearized reading' (duration: 129.673133ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:20.135582Z","caller":"traceutil/trace.go:171","msg":"trace[2141975327] linearizableReadLoop","detail":"{readStateIndex:9101; appliedIndex:9100; }","duration":"102.676423ms","start":"2026-09-07T18:36:20.032882Z","end":"2026-09-07T18:36:20.135559Z","steps":["trace[2141975327] 'read index received' (duration: 102.498718ms)","trace[2141975327] 'applied index is now lower than readState.Index' (duration: 176.865µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:36:20.135733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.836619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:36:20.135778Z","caller":"traceutil/trace.go:171","msg":"trace[177333222] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:8793; }","duration":"102.930531ms","start":"2026-09-07T18:36:20.032835Z","end":"2026-09-07T18:36:20.135766Z","steps":["trace[177333222] 'agreement among raft nodes before linearized reading' (duration: 102.819538ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:20.136101Z","caller":"traceutil/trace.go:171","msg":"trace[1068789983] transaction","detail":"{read_only:false; response_revision:8793; number_of_response:1; }","duration":"187.96366ms","start":"2026-09-07T18:36:19.948127Z","end":"2026-09-07T18:36:20.13609Z","steps":["trace[1068789983] 'process raft request' (duration: 187.27946ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:20.426275Z","caller":"traceutil/trace.go:171","msg":"trace[256634540] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"121.589678ms","start":"2026-09-07T18:36:20.304622Z","end":"2026-09-07T18:36:20.426211Z","steps":["trace[256634540] 'process raft request' (duration: 104.216001ms)","trace[256634540] 'compare' (duration: 16.967964ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:36:26.395013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.414111ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-07T18:36:26.395586Z","caller":"traceutil/trace.go:171","msg":"trace[2001164503] linearizableReadLoop","detail":"{readStateIndex:9137; appliedIndex:9136; }","duration":"132.272795ms","start":"2026-09-07T18:36:26.263177Z","end":"2026-09-07T18:36:26.39545Z","steps":["trace[2001164503] 'read index received' (duration: 31.792µs)","trace[2001164503] 'applied index is now lower than readState.Index' (duration: 132.238653ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:36:26.395764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.710116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:36:26.395857Z","caller":"traceutil/trace.go:171","msg":"trace[405257846] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:8827; }","duration":"105.847111ms","start":"2026-09-07T18:36:26.28999Z","end":"2026-09-07T18:36:26.395837Z","steps":["trace[405257846] 'agreement among raft nodes before linearized reading' (duration: 105.704167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:26.395829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.614246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-07T18:36:26.395947Z","caller":"traceutil/trace.go:171","msg":"trace[1078374434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8827; }","duration":"132.786721ms","start":"2026-09-07T18:36:26.263135Z","end":"2026-09-07T18:36:26.395922Z","steps":["trace[1078374434] 'agreement among raft nodes before linearized reading' (duration: 132.445461ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:27.131939Z","caller":"traceutil/trace.go:171","msg":"trace[1389655659] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"188.666712ms","start":"2026-09-07T18:36:26.943242Z","end":"2026-09-07T18:36:27.131908Z","steps":["trace[1389655659] 'process raft request' (duration: 188.524408ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:27.139403Z","caller":"traceutil/trace.go:171","msg":"trace[1444634000] linearizableReadLoop","detail":"{readStateIndex:9144; appliedIndex:9142; }","duration":"142.179016ms","start":"2026-09-07T18:36:26.997193Z","end":"2026-09-07T18:36:27.139372Z","steps":["trace[1444634000] 'read index received' (duration: 134.598716ms)","trace[1444634000] 'applied index is now lower than readState.Index' (duration: 7.57863ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:27.139791Z","caller":"traceutil/trace.go:171","msg":"trace[374898314] transaction","detail":"{read_only:false; response_revision:8834; number_of_response:1; }","duration":"181.163293ms","start":"2026-09-07T18:36:26.958612Z","end":"2026-09-07T18:36:27.139775Z","steps":["trace[374898314] 'process raft request' (duration: 180.531604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:27.140105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.899108ms","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-09-07T18:36:27.140143Z","caller":"traceutil/trace.go:171","msg":"trace[11328222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8834; }","duration":"143.017692ms","start":"2026-09-07T18:36:26.997114Z","end":"2026-09-07T18:36:27.140132Z","steps":["trace[11328222] 'agreement among raft nodes before linearized reading' (duration: 142.860167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:27.140371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.628538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-07T18:36:27.140497Z","caller":"traceutil/trace.go:171","msg":"trace[662983130] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8834; }","duration":"137.793853ms","start":"2026-09-07T18:36:27.002684Z","end":"2026-09-07T18:36:27.140477Z","steps":["trace[662983130] 'agreement among raft nodes before linearized reading' (duration: 137.607388ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:33.588478Z","caller":"traceutil/trace.go:171","msg":"trace[833132842] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8909; }","duration":"181.817174ms","start":"2026-09-07T18:36:33.406619Z","end":"2026-09-07T18:36:33.588436Z","steps":["trace[833132842] 'process raft request' (duration: 175.418529ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:33.653129Z","caller":"traceutil/trace.go:171","msg":"trace[1146622814] linearizableReadLoop","detail":"{readStateIndex:9224; appliedIndex:9222; }","duration":"161.258727ms","start":"2026-09-07T18:36:33.491839Z","end":"2026-09-07T18:36:33.653097Z","steps":["trace[1146622814] 'read index received' (duration: 90.230626ms)","trace[1146622814] 'applied index is now lower than readState.Index' (duration: 71.026831ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:36:33.653325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.452965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:36:33.653379Z","caller":"traceutil/trace.go:171","msg":"trace[703933195] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8910; }","duration":"161.572408ms","start":"2026-09-07T18:36:33.491793Z","end":"2026-09-07T18:36:33.653365Z","steps":["trace[703933195] 'agreement among raft nodes before linearized reading' (duration: 161.423444ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:33.653851Z","caller":"traceutil/trace.go:171","msg":"trace[656018838] transaction","detail":"{read_only:false; response_revision:8910; number_of_response:1; }","duration":"245.369507ms","start":"2026-09-07T18:36:33.408463Z","end":"2026-09-07T18:36:33.653833Z","steps":["trace[656018838] 'process raft request' (duration: 244.475549ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:34.368705Z","caller":"traceutil/trace.go:171","msg":"trace[1066655571] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"234.465305ms","start":"2026-09-07T18:36:34.134203Z","end":"2026-09-07T18:36:34.368668Z","steps":["trace[1066655571] 'process raft request' (duration: 234.199517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:34.566565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.485088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:36:34.566634Z","caller":"traceutil/trace.go:171","msg":"trace[107519228] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8912; }","duration":"124.638013ms","start":"2026-09-07T18:36:34.441981Z","end":"2026-09-07T18:36:34.566619Z","steps":["trace[107519228] 'count revisions from in-memory index tree' (duration: 124.358375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:34.791093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.48146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:36:34.791303Z","caller":"traceutil/trace.go:171","msg":"trace[1395627549] transaction","detail":"{read_only:false; response_revision:8914; number_of_response:1; }","duration":"217.354095ms","start":"2026-09-07T18:36:34.573922Z","end":"2026-09-07T18:36:34.791276Z","steps":["trace[1395627549] 'process raft request' (duration: 112.532465ms)","trace[1395627549] 'compare' (duration: 104.271433ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:34.791438Z","caller":"traceutil/trace.go:171","msg":"trace[1374943832] linearizableReadLoop","detail":"{readStateIndex:9229; appliedIndex:9227; }","duration":"155.845343ms","start":"2026-09-07T18:36:34.635562Z","end":"2026-09-07T18:36:34.791407Z","steps":["trace[1374943832] 'read index received' (duration: 50.911269ms)","trace[1374943832] 'applied index is now lower than readState.Index' (duration: 104.932593ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:34.791463Z","caller":"traceutil/trace.go:171","msg":"trace[1964242095] transaction","detail":"{read_only:false; response_revision:8915; number_of_response:1; }","duration":"169.709934ms","start":"2026-09-07T18:36:34.621746Z","end":"2026-09-07T18:36:34.791456Z","steps":["trace[1964242095] 'process raft request' (duration: 169.517298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:34.791661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.07863ms","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-09-07T18:36:34.791748Z","caller":"traceutil/trace.go:171","msg":"trace[474458908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8915; }","duration":"156.243385ms","start":"2026-09-07T18:36:34.635496Z","end":"2026-09-07T18:36:34.791739Z","steps":["trace[474458908] 'agreement among raft nodes before linearized reading' (duration: 156.05873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:34.791706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.664926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zzxxq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T18:36:34.791791Z","caller":"traceutil/trace.go:171","msg":"trace[1012486784] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zzxxq; range_end:; response_count:1; response_revision:8915; }","duration":"151.777809ms","start":"2026-09-07T18:36:34.639997Z","end":"2026-09-07T18:36:34.791775Z","steps":["trace[1012486784] 'agreement among raft nodes before linearized reading' (duration: 151.626715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:34.791862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.970003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T18:36:34.791924Z","caller":"traceutil/trace.go:171","msg":"trace[2078157099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8915; }","duration":"124.078896ms","start":"2026-09-07T18:36:34.66783Z","end":"2026-09-07T18:36:34.791909Z","steps":["trace[2078157099] 'agreement among raft nodes before linearized reading' (duration: 123.842569ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:49.379921Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249b.wal"} {"level":"warn","ts":"2026-09-07T18:37:53.62453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.438049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:37:53.624698Z","caller":"traceutil/trace.go:171","msg":"trace[1452698843] linearizableReadLoop","detail":"{readStateIndex:9952; appliedIndex:9951; }","duration":"130.252074ms","start":"2026-09-07T18:37:53.494425Z","end":"2026-09-07T18:37:53.624677Z","steps":["trace[1452698843] 'read index received' (duration: 1.430413ms)","trace[1452698843] 'applied index is now lower than readState.Index' (duration: 128.819791ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:37:53.624774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.350326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:37:53.625124Z","caller":"traceutil/trace.go:171","msg":"trace[842445752] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:9617; }","duration":"130.728978ms","start":"2026-09-07T18:37:53.494377Z","end":"2026-09-07T18:37:53.625106Z","steps":["trace[842445752] 'agreement among raft nodes before linearized reading' (duration: 130.354406ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:37:53.625044Z","caller":"traceutil/trace.go:171","msg":"trace[61299752] transaction","detail":"{read_only:false; response_revision:9617; number_of_response:1; }","duration":"173.085557ms","start":"2026-09-07T18:37:53.451934Z","end":"2026-09-07T18:37:53.62502Z","steps":["trace[61299752] 'process raft request' (duration: 43.958528ms)","trace[61299752] 'compare' (duration: 128.19063ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:37:53.888998Z","caller":"traceutil/trace.go:171","msg":"trace[551393308] linearizableReadLoop","detail":"{readStateIndex:9953; appliedIndex:9952; }","duration":"203.35533ms","start":"2026-09-07T18:37:53.685609Z","end":"2026-09-07T18:37:53.888964Z","steps":["trace[551393308] 'read index received' (duration: 133.307478ms)","trace[551393308] 'applied index is now lower than readState.Index' (duration: 70.046112ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:37:53.889227Z","caller":"traceutil/trace.go:171","msg":"trace[1071234234] transaction","detail":"{read_only:false; response_revision:9618; number_of_response:1; }","duration":"217.164669ms","start":"2026-09-07T18:37:53.672029Z","end":"2026-09-07T18:37:53.889194Z","steps":["trace[1071234234] 'process raft request' (duration: 146.963452ms)","trace[1071234234] 'compare' (duration: 69.738042ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:37:53.889355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.716641ms","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-09-07T18:37:53.889428Z","caller":"traceutil/trace.go:171","msg":"trace[1612002413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9618; }","duration":"203.816373ms","start":"2026-09-07T18:37:53.685594Z","end":"2026-09-07T18:37:53.88941Z","steps":["trace[1612002413] 'agreement among raft nodes before linearized reading' (duration: 203.561766ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:37:56.246105Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"873be3731fd7a3eb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T18:37:56.254289Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T18:37:56.254426Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-07T18:38:29.34842Z","caller":"traceutil/trace.go:171","msg":"trace[685323471] transaction","detail":"{read_only:false; response_revision:9945; number_of_response:1; }","duration":"117.860227ms","start":"2026-09-07T18:38:29.230534Z","end":"2026-09-07T18:38:29.348394Z","steps":["trace[685323471] 'process raft request' (duration: 106.907233ms)","trace[685323471] 'compare' (duration: 10.82764ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:38:29.366337Z","caller":"traceutil/trace.go:171","msg":"trace[493412297] transaction","detail":"{read_only:false; response_revision:9946; number_of_response:1; }","duration":"127.593572ms","start":"2026-09-07T18:38:29.238501Z","end":"2026-09-07T18:38:29.366095Z","steps":["trace[493412297] 'process raft request' (duration: 127.027545ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:39:57.373519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8109} {"level":"info","ts":"2026-09-07T18:39:57.438846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8109,"took":"63.303927ms","hash":1271827536,"current-db-size-bytes":56877056,"current-db-size":"57 MB","current-db-size-in-use-bytes":28925952,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-07T18:39:57.438911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1271827536,"revision":8109,"compact-revision":5936} {"level":"info","ts":"2026-09-07T18:41:35.323339Z","caller":"traceutil/trace.go:171","msg":"trace[236007337] transaction","detail":"{read_only:false; response_revision:11550; number_of_response:1; }","duration":"218.972464ms","start":"2026-09-07T18:41:35.104311Z","end":"2026-09-07T18:41:35.323283Z","steps":["trace[236007337] 'process raft request' (duration: 162.699824ms)","trace[236007337] 'compare' (duration: 56.069964ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:44:57.381894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10601} {"level":"info","ts":"2026-09-07T18:44:57.466999Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10601,"took":"82.870132ms","hash":3921674794,"current-db-size-bytes":56877056,"current-db-size":"57 MB","current-db-size-in-use-bytes":27320320,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-07T18:44:57.467063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3921674794,"revision":10601,"compact-revision":8109} {"level":"info","ts":"2026-09-07T18:47:27.648064Z","caller":"traceutil/trace.go:171","msg":"trace[1860218793] transaction","detail":"{read_only:false; response_revision:13956; number_of_response:1; }","duration":"167.582672ms","start":"2026-09-07T18:47:27.480454Z","end":"2026-09-07T18:47:27.648037Z","steps":["trace[1860218793] 'process raft request' (duration: 165.545399ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:47:47.474167Z","caller":"traceutil/trace.go:171","msg":"trace[1197646346] transaction","detail":"{read_only:false; response_revision:14055; number_of_response:1; }","duration":"131.192359ms","start":"2026-09-07T18:47:47.342939Z","end":"2026-09-07T18:47:47.474131Z","steps":["trace[1197646346] 'process raft request' (duration: 130.998483ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:47:47.829285Z","caller":"traceutil/trace.go:171","msg":"trace[1764861926] transaction","detail":"{read_only:false; response_revision:14056; number_of_response:1; }","duration":"100.913443ms","start":"2026-09-07T18:47:47.728347Z","end":"2026-09-07T18:47:47.829261Z","steps":["trace[1764861926] 'process raft request' (duration: 100.78304ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:47:47.850072Z","caller":"traceutil/trace.go:171","msg":"trace[115812188] transaction","detail":"{read_only:false; response_revision:14057; number_of_response:1; }","duration":"100.274354ms","start":"2026-09-07T18:47:47.749778Z","end":"2026-09-07T18:47:47.850052Z","steps":["trace[115812188] 'process raft request' (duration: 100.15221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:48:02.791591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.951166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:48:02.791701Z","caller":"traceutil/trace.go:171","msg":"trace[1254250839] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:14153; }","duration":"139.127821ms","start":"2026-09-07T18:48:02.652535Z","end":"2026-09-07T18:48:02.791663Z","steps":["trace[1254250839] 'count revisions from in-memory index tree' (duration: 138.78933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:48:02.791788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.399466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-sxsmr\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-09-07T18:48:02.791931Z","caller":"traceutil/trace.go:171","msg":"trace[2024415177] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-sxsmr; range_end:; response_count:1; response_revision:14153; }","duration":"170.615933ms","start":"2026-09-07T18:48:02.62129Z","end":"2026-09-07T18:48:02.791906Z","steps":["trace[2024415177] 'range keys from in-memory index tree' (duration: 170.139769ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:48:03.620354Z","caller":"traceutil/trace.go:171","msg":"trace[1825273837] transaction","detail":"{read_only:false; response_revision:14157; number_of_response:1; }","duration":"204.123217ms","start":"2026-09-07T18:48:03.416197Z","end":"2026-09-07T18:48:03.62032Z","steps":["trace[1825273837] 'process raft request' (duration: 203.916779ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:48:03.718904Z","caller":"traceutil/trace.go:171","msg":"trace[708834906] linearizableReadLoop","detail":"{readStateIndex:14644; appliedIndex:14642; }","duration":"150.52515ms","start":"2026-09-07T18:48:03.568347Z","end":"2026-09-07T18:48:03.718872Z","steps":["trace[708834906] 'read index received' (duration: 51.799132ms)","trace[708834906] 'applied index is now lower than readState.Index' (duration: 98.724488ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:48:03.719015Z","caller":"traceutil/trace.go:171","msg":"trace[203976392] transaction","detail":"{read_only:false; response_revision:14158; number_of_response:1; }","duration":"192.7632ms","start":"2026-09-07T18:48:03.526228Z","end":"2026-09-07T18:48:03.718991Z","steps":["trace[203976392] 'process raft request' (duration: 192.43025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:48:03.719108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.721735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:48:03.719139Z","caller":"traceutil/trace.go:171","msg":"trace[1639790628] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:14158; }","duration":"150.821368ms","start":"2026-09-07T18:48:03.568308Z","end":"2026-09-07T18:48:03.71913Z","steps":["trace[1639790628] 'agreement among raft nodes before linearized reading' (duration: 150.681754ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:48:06.009186Z","caller":"traceutil/trace.go:171","msg":"trace[770305708] transaction","detail":"{read_only:false; response_revision:14176; number_of_response:1; }","duration":"102.981777ms","start":"2026-09-07T18:48:05.906146Z","end":"2026-09-07T18:48:06.009128Z","steps":["trace[770305708] 'process raft request' (duration: 47.78385ms)","trace[770305708] 'compare' (duration: 54.891997ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:48:13.162697Z","caller":"traceutil/trace.go:171","msg":"trace[1406684828] transaction","detail":"{read_only:false; response_revision:14319; number_of_response:1; }","duration":"104.178974ms","start":"2026-09-07T18:48:13.058468Z","end":"2026-09-07T18:48:13.162647Z","steps":["trace[1406684828] 'process raft request' (duration: 103.996709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:48:13.49251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.832824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T18:48:13.492615Z","caller":"traceutil/trace.go:171","msg":"trace[991005042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14319; }","duration":"250.976208ms","start":"2026-09-07T18:48:13.241612Z","end":"2026-09-07T18:48:13.492588Z","steps":["trace[991005042] 'range keys from in-memory index tree' (duration: 250.640968ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:49:54.233842Z","caller":"traceutil/trace.go:171","msg":"trace[2113119283] transaction","detail":"{read_only:false; response_revision:15411; number_of_response:1; }","duration":"167.141757ms","start":"2026-09-07T18:49:54.06667Z","end":"2026-09-07T18:49:54.233812Z","steps":["trace[2113119283] 'process raft request' (duration: 166.91628ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:49:54.484393Z","caller":"traceutil/trace.go:171","msg":"trace[1888439992] linearizableReadLoop","detail":"{readStateIndex:15936; appliedIndex:15935; }","duration":"242.036423ms","start":"2026-09-07T18:49:54.242324Z","end":"2026-09-07T18:49:54.484361Z","steps":["trace[1888439992] 'read index received' (duration: 152.817818ms)","trace[1888439992] 'applied index is now lower than readState.Index' (duration: 89.217435ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:49:54.484369Z","caller":"traceutil/trace.go:171","msg":"trace[1327538848] transaction","detail":"{read_only:false; response_revision:15412; number_of_response:1; }","duration":"299.707055ms","start":"2026-09-07T18:49:54.184618Z","end":"2026-09-07T18:49:54.484326Z","steps":["trace[1327538848] 'process raft request' (duration: 210.582103ms)","trace[1327538848] 'compare' (duration: 88.73894ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:49:54.485017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.295266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-07T18:49:54.485055Z","caller":"traceutil/trace.go:171","msg":"trace[179547230] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15414; }","duration":"198.366899ms","start":"2026-09-07T18:49:54.286679Z","end":"2026-09-07T18:49:54.485045Z","steps":["trace[179547230] 'agreement among raft nodes before linearized reading' (duration: 198.227025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:49:54.485207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.206092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-09-07T18:49:54.485233Z","caller":"traceutil/trace.go:171","msg":"trace[869348259] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:15414; }","duration":"137.255062ms","start":"2026-09-07T18:49:54.34797Z","end":"2026-09-07T18:49:54.485225Z","steps":["trace[869348259] 'agreement among raft nodes before linearized reading' (duration: 137.18964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:49:54.485472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.359367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-07T18:49:54.485539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.207189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769659"} {"level":"info","ts":"2026-09-07T18:49:54.485553Z","caller":"traceutil/trace.go:171","msg":"trace[1626880603] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15414; }","duration":"138.47282ms","start":"2026-09-07T18:49:54.347058Z","end":"2026-09-07T18:49:54.485531Z","steps":["trace[1626880603] 'agreement among raft nodes before linearized reading' (duration: 138.14902ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:49:54.485592Z","caller":"traceutil/trace.go:171","msg":"trace[50131602] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15413; }","duration":"243.290632ms","start":"2026-09-07T18:49:54.242288Z","end":"2026-09-07T18:49:54.485578Z","steps":["trace[50131602] 'agreement among raft nodes before linearized reading' (duration: 242.215719ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:49:57.38854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12864} {"level":"info","ts":"2026-09-07T18:49:57.472525Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12864,"took":"81.239931ms","hash":1708862688,"current-db-size-bytes":56877056,"current-db-size":"57 MB","current-db-size-in-use-bytes":30998528,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T18:49:57.472596Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1708862688,"revision":12864,"compact-revision":10601} {"level":"info","ts":"2026-09-07T18:54:57.398054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15430} {"level":"info","ts":"2026-09-07T18:54:57.498893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15430,"took":"97.91223ms","hash":506749739,"current-db-size-bytes":56877056,"current-db-size":"57 MB","current-db-size-in-use-bytes":31752192,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T18:54:57.498952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":506749739,"revision":15430,"compact-revision":12864} {"level":"info","ts":"2026-09-07T18:55:36.997194Z","caller":"traceutil/trace.go:171","msg":"trace[616689089] transaction","detail":"{read_only:false; response_revision:18005; number_of_response:1; }","duration":"126.232186ms","start":"2026-09-07T18:55:36.870942Z","end":"2026-09-07T18:55:36.997174Z","steps":["trace[616689089] 'process raft request' (duration: 126.102062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:55:37.702106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710906863013514,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-07T18:55:37.793098Z","caller":"traceutil/trace.go:171","msg":"trace[1677308239] linearizableReadLoop","detail":"{readStateIndex:18617; appliedIndex:18616; }","duration":"591.342656ms","start":"2026-09-07T18:55:37.201728Z","end":"2026-09-07T18:55:37.79307Z","steps":["trace[1677308239] 'read index received' (duration: 591.020586ms)","trace[1677308239] 'applied index is now lower than readState.Index' (duration: 320.89µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:55:37.793151Z","caller":"traceutil/trace.go:171","msg":"trace[671242601] transaction","detail":"{read_only:false; response_revision:18006; number_of_response:1; }","duration":"599.892907ms","start":"2026-09-07T18:55:37.193239Z","end":"2026-09-07T18:55:37.793132Z","steps":["trace[671242601] 'process raft request' (duration: 599.654279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:55:37.793267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:55:37.193219Z","time spent":"599.98446ms","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20871,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:55:37.793295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.535842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T18:55:37.793414Z","caller":"traceutil/trace.go:171","msg":"trace[981439231] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:18006; }","duration":"591.670596ms","start":"2026-09-07T18:55:37.201712Z","end":"2026-09-07T18:55:37.793383Z","steps":["trace[981439231] 'agreement among raft nodes before linearized reading' (duration: 591.46062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:55:37.793504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:55:37.201657Z","time spent":"591.805549ms","remote":"127.0.0.1:48300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-09-07T18:55:37.892873Z","caller":"traceutil/trace.go:171","msg":"trace[1635220860] transaction","detail":"{read_only:false; response_revision:18007; number_of_response:1; }","duration":"177.122567ms","start":"2026-09-07T18:55:37.715719Z","end":"2026-09-07T18:55:37.892841Z","steps":["trace[1635220860] 'process raft request' (duration: 163.496772ms)","trace[1635220860] 'compare' (duration: 13.371118ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:55:37.892986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.558282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T18:55:37.893049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.090253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-07T18:55:37.893078Z","caller":"traceutil/trace.go:171","msg":"trace[1173111972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18009; }","duration":"104.690486ms","start":"2026-09-07T18:55:37.788364Z","end":"2026-09-07T18:55:37.893054Z","steps":["trace[1173111972] 'agreement among raft nodes before linearized reading' (duration: 104.557772ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:55:37.893104Z","caller":"traceutil/trace.go:171","msg":"trace[453739799] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:18009; }","duration":"433.178477ms","start":"2026-09-07T18:55:37.459905Z","end":"2026-09-07T18:55:37.893083Z","steps":["trace[453739799] 'agreement among raft nodes before linearized reading' (duration: 433.054472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:55:37.89313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:55:37.459889Z","time spent":"433.236349ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":557,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-07T18:55:37.893125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.366527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-c5kph\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-09-07T18:55:37.893185Z","caller":"traceutil/trace.go:171","msg":"trace[1045907455] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-c5kph; range_end:; response_count:1; response_revision:18009; }","duration":"686.472821ms","start":"2026-09-07T18:55:37.206694Z","end":"2026-09-07T18:55:37.893167Z","steps":["trace[1045907455] 'agreement among raft nodes before linearized reading' (duration: 686.273474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:55:37.893218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.067344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-07T18:55:37.893235Z","caller":"traceutil/trace.go:171","msg":"trace[1226680170] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18009; }","duration":"302.116475ms","start":"2026-09-07T18:55:37.591112Z","end":"2026-09-07T18:55:37.893229Z","steps":["trace[1226680170] 'agreement among raft nodes before linearized reading' (duration: 302.073834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:55:37.893246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:55:37.591093Z","time spent":"302.150595ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":583,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-07T18:55:37.893229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:55:37.206671Z","time spent":"686.545674ms","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22149,"request content":"key:\"/registry/pods/openstack/nova-compute-default-c5kph\" "} {"level":"info","ts":"2026-09-07T18:55:37.893264Z","caller":"traceutil/trace.go:171","msg":"trace[1267308303] transaction","detail":"{read_only:false; response_revision:18009; number_of_response:1; }","duration":"171.658411ms","start":"2026-09-07T18:55:37.721585Z","end":"2026-09-07T18:55:37.893244Z","steps":["trace[1267308303] 'process raft request' (duration: 171.211017ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:55:37.893385Z","caller":"traceutil/trace.go:171","msg":"trace[2146704195] transaction","detail":"{read_only:false; response_revision:18008; number_of_response:1; }","duration":"173.544248ms","start":"2026-09-07T18:55:37.719831Z","end":"2026-09-07T18:55:37.893375Z","steps":["trace[2146704195] 'process raft request' (duration: 172.897608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:55:37.895148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.917744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:130 size:1557917"} {"level":"info","ts":"2026-09-07T18:55:37.89524Z","caller":"traceutil/trace.go:171","msg":"trace[709888248] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:130; response_revision:18009; }","duration":"426.059448ms","start":"2026-09-07T18:55:37.469161Z","end":"2026-09-07T18:55:37.89522Z","steps":["trace[709888248] 'agreement among raft nodes before linearized reading' (duration: 424.104879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:55:37.895289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:55:37.469141Z","time spent":"426.133691ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":130,"response size":1557941,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-09-07T18:58:13.044037Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"873be3731fd7a3eb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T18:58:13.049725Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-07T18:58:13.049825Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-07T18:59:57.406252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17675} {"level":"info","ts":"2026-09-07T18:59:57.502935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17675,"took":"93.293859ms","hash":1652152017,"current-db-size-bytes":56877056,"current-db-size":"57 MB","current-db-size-in-use-bytes":33828864,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-07T18:59:57.502999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1652152017,"revision":17675,"compact-revision":15430} {"level":"info","ts":"2026-09-07T19:00:13.064098Z","caller":"traceutil/trace.go:171","msg":"trace[901157391] transaction","detail":"{read_only:false; response_revision:20258; number_of_response:1; }","duration":"183.50684ms","start":"2026-09-07T19:00:12.880571Z","end":"2026-09-07T19:00:13.064078Z","steps":["trace[901157391] 'process raft request' (duration: 183.376796ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:00:13.065315Z","caller":"traceutil/trace.go:171","msg":"trace[631021477] transaction","detail":"{read_only:false; response_revision:20259; number_of_response:1; }","duration":"104.299928ms","start":"2026-09-07T19:00:12.960997Z","end":"2026-09-07T19:00:13.065297Z","steps":["trace[631021477] 'process raft request' (duration: 103.892306ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:00:15.180123Z","caller":"traceutil/trace.go:171","msg":"trace[1926651691] transaction","detail":"{read_only:false; response_revision:20269; number_of_response:1; }","duration":"112.208571ms","start":"2026-09-07T19:00:15.067893Z","end":"2026-09-07T19:00:15.180101Z","steps":["trace[1926651691] 'process raft request' (duration: 112.068656ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:00:15.426557Z","caller":"traceutil/trace.go:171","msg":"trace[474757442] transaction","detail":"{read_only:false; response_revision:20270; number_of_response:1; }","duration":"357.002003ms","start":"2026-09-07T19:00:15.069524Z","end":"2026-09-07T19:00:15.426526Z","steps":["trace[474757442] 'process raft request' (duration: 315.991889ms)","trace[474757442] 'compare' (duration: 40.54683ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:00:15.426707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:15.069518Z","time spent":"357.110777ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:00:15.426689Z","caller":"traceutil/trace.go:171","msg":"trace[431787759] linearizableReadLoop","detail":"{readStateIndex:20955; appliedIndex:20952; }","duration":"352.894439ms","start":"2026-09-07T19:00:15.073774Z","end":"2026-09-07T19:00:15.426668Z","steps":["trace[431787759] 'read index received' (duration: 106.239597ms)","trace[431787759] 'applied index is now lower than readState.Index' (duration: 246.653782ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:00:15.426861Z","caller":"traceutil/trace.go:171","msg":"trace[1305012561] transaction","detail":"{read_only:false; response_revision:20271; number_of_response:1; }","duration":"357.154248ms","start":"2026-09-07T19:00:15.069695Z","end":"2026-09-07T19:00:15.42685Z","steps":["trace[1305012561] 'process raft request' (duration: 356.773166ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:15.42694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:15.06969Z","time spent":"357.20101ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:00:15.427131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.351822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-09-07T19:00:15.427184Z","caller":"traceutil/trace.go:171","msg":"trace[1846779308] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:20271; }","duration":"353.433635ms","start":"2026-09-07T19:00:15.073733Z","end":"2026-09-07T19:00:15.427167Z","steps":["trace[1846779308] 'agreement among raft nodes before linearized reading' (duration: 353.29144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:15.427228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:15.073718Z","time spent":"353.500176ms","remote":"127.0.0.1:60374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5708,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-07T19:00:15.582103Z","caller":"traceutil/trace.go:171","msg":"trace[2083936545] linearizableReadLoop","detail":"{readStateIndex:20956; appliedIndex:20955; }","duration":"155.322979ms","start":"2026-09-07T19:00:15.426758Z","end":"2026-09-07T19:00:15.582081Z","steps":["trace[2083936545] 'read index received' (duration: 151.276395ms)","trace[2083936545] 'applied index is now lower than readState.Index' (duration: 4.045834ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:00:15.582237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.767215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:00:15.582275Z","caller":"traceutil/trace.go:171","msg":"trace[1328102022] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:20272; }","duration":"474.856667ms","start":"2026-09-07T19:00:15.107406Z","end":"2026-09-07T19:00:15.582262Z","steps":["trace[1328102022] 'agreement among raft nodes before linearized reading' (duration: 474.760344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:15.582311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:15.107391Z","time spent":"474.911268ms","remote":"127.0.0.1:33636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-09-07T19:00:15.582627Z","caller":"traceutil/trace.go:171","msg":"trace[19026497] transaction","detail":"{read_only:false; response_revision:20272; number_of_response:1; }","duration":"252.980864ms","start":"2026-09-07T19:00:15.329635Z","end":"2026-09-07T19:00:15.582616Z","steps":["trace[19026497] 'process raft request' (duration: 248.357463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:15.582762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.245709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:00:15.582795Z","caller":"traceutil/trace.go:171","msg":"trace[271035500] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:20272; }","duration":"229.309491ms","start":"2026-09-07T19:00:15.353475Z","end":"2026-09-07T19:00:15.582785Z","steps":["trace[271035500] 'agreement among raft nodes before linearized reading' (duration: 229.255919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:15.583009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.622143ms","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-09-07T19:00:15.583044Z","caller":"traceutil/trace.go:171","msg":"trace[628844866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20272; }","duration":"272.670965ms","start":"2026-09-07T19:00:15.310363Z","end":"2026-09-07T19:00:15.583034Z","steps":["trace[628844866] 'agreement among raft nodes before linearized reading' (duration: 272.553682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:15.58317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.494292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-07T19:00:15.583241Z","caller":"traceutil/trace.go:171","msg":"trace[1592178682] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20272; }","duration":"276.601896ms","start":"2026-09-07T19:00:15.306617Z","end":"2026-09-07T19:00:15.583219Z","steps":["trace[1592178682] 'agreement among raft nodes before linearized reading' (duration: 276.39841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:15.584452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.949703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-07T19:00:15.584488Z","caller":"traceutil/trace.go:171","msg":"trace[213589599] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20272; }","duration":"428.988794ms","start":"2026-09-07T19:00:15.15549Z","end":"2026-09-07T19:00:15.584479Z","steps":["trace[213589599] 'agreement among raft nodes before linearized reading' (duration: 427.84769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:15.584517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:15.15545Z","time spent":"429.057736ms","remote":"127.0.0.1:49772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2207,"response size":33,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-09-07T19:00:15.757304Z","caller":"traceutil/trace.go:171","msg":"trace[1542422200] transaction","detail":"{read_only:false; response_revision:20274; number_of_response:1; }","duration":"162.22367ms","start":"2026-09-07T19:00:15.595057Z","end":"2026-09-07T19:00:15.75728Z","steps":["trace[1542422200] 'process raft request' (duration: 95.885721ms)","trace[1542422200] 'compare' (duration: 66.195275ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:00:17.724735Z","caller":"traceutil/trace.go:171","msg":"trace[754334866] linearizableReadLoop","detail":"{readStateIndex:20968; appliedIndex:20967; }","duration":"108.643222ms","start":"2026-09-07T19:00:17.616061Z","end":"2026-09-07T19:00:17.724704Z","steps":["trace[754334866] 'read index received' (duration: 108.283041ms)","trace[754334866] 'applied index is now lower than readState.Index' (duration: 358.56µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:00:17.724863Z","caller":"traceutil/trace.go:171","msg":"trace[441278369] transaction","detail":"{read_only:false; response_revision:20283; number_of_response:1; }","duration":"288.335165ms","start":"2026-09-07T19:00:17.436447Z","end":"2026-09-07T19:00:17.724782Z","steps":["trace[441278369] 'process raft request' (duration: 288.039376ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:17.725058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.964642ms","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-09-07T19:00:17.725132Z","caller":"traceutil/trace.go:171","msg":"trace[487076709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20283; }","duration":"109.094696ms","start":"2026-09-07T19:00:17.616016Z","end":"2026-09-07T19:00:17.72511Z","steps":["trace[487076709] 'agreement among raft nodes before linearized reading' (duration: 108.839648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:18.101284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.852886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:00:18.101389Z","caller":"traceutil/trace.go:171","msg":"trace[117005728] linearizableReadLoop","detail":"{readStateIndex:20970; appliedIndex:20969; }","duration":"307.271645ms","start":"2026-09-07T19:00:17.794093Z","end":"2026-09-07T19:00:18.101365Z","steps":["trace[117005728] 'read index received' (duration: 151.174262ms)","trace[117005728] 'applied index is now lower than readState.Index' (duration: 156.096653ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:00:18.101589Z","caller":"traceutil/trace.go:171","msg":"trace[905867599] transaction","detail":"{read_only:false; response_revision:20285; number_of_response:1; }","duration":"308.272244ms","start":"2026-09-07T19:00:17.793173Z","end":"2026-09-07T19:00:18.101446Z","steps":["trace[905867599] 'process raft request' (duration: 152.181051ms)","trace[905867599] 'compare' (duration: 155.742273ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:00:18.101654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.547302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:00:18.101645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:17.793157Z","time spent":"308.46365ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:00:18.101755Z","caller":"traceutil/trace.go:171","msg":"trace[1266621110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20285; }","duration":"307.738788ms","start":"2026-09-07T19:00:17.793993Z","end":"2026-09-07T19:00:18.101732Z","steps":["trace[1266621110] 'agreement among raft nodes before linearized reading' (duration: 307.539753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:18.101835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:17.793963Z","time spent":"307.860872ms","remote":"127.0.0.1:49286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-07T19:00:18.36608Z","caller":"traceutil/trace.go:171","msg":"trace[1652115420] linearizableReadLoop","detail":"{readStateIndex:20971; appliedIndex:20970; }","duration":"264.65103ms","start":"2026-09-07T19:00:18.101405Z","end":"2026-09-07T19:00:18.366056Z","steps":["trace[1652115420] 'read index received' (duration: 250.790266ms)","trace[1652115420] 'applied index is now lower than readState.Index' (duration: 13.859874ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:00:18.366235Z","caller":"traceutil/trace.go:171","msg":"trace[2046841823] transaction","detail":"{read_only:false; response_revision:20286; number_of_response:1; }","duration":"368.796875ms","start":"2026-09-07T19:00:17.997407Z","end":"2026-09-07T19:00:18.366204Z","steps":["trace[2046841823] 'process raft request' (duration: 354.887449ms)","trace[2046841823] 'compare' (duration: 13.651558ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:00:18.366277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.255865ms","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-09-07T19:00:18.366314Z","caller":"traceutil/trace.go:171","msg":"trace[519777811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20286; }","duration":"451.341858ms","start":"2026-09-07T19:00:17.914962Z","end":"2026-09-07T19:00:18.366304Z","steps":["trace[519777811] 'agreement among raft nodes before linearized reading' (duration: 451.183243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:18.366338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:17.914943Z","time spent":"451.389709ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T19:00:18.366366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.107627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-07T19:00:18.366381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:17.997389Z","time spent":"368.913748ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5531,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:00:18.366397Z","caller":"traceutil/trace.go:171","msg":"trace[1392202559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20286; }","duration":"551.19353ms","start":"2026-09-07T19:00:17.815195Z","end":"2026-09-07T19:00:18.366388Z","steps":["trace[1392202559] 'agreement among raft nodes before linearized reading' (duration: 550.940163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:18.366465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:17.815168Z","time spent":"551.289593ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-07T19:00:18.366644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.926525ms","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-09-07T19:00:18.36693Z","caller":"traceutil/trace.go:171","msg":"trace[2128836391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20286; }","duration":"345.239425ms","start":"2026-09-07T19:00:18.021679Z","end":"2026-09-07T19:00:18.366918Z","steps":["trace[2128836391] 'agreement among raft nodes before linearized reading' (duration: 344.43194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:18.366959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:18.021661Z","time spent":"345.290666ms","remote":"127.0.0.1:49530","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-09-07T19:00:18.366974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.624738ms","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-09-07T19:00:18.367058Z","caller":"traceutil/trace.go:171","msg":"trace[681878486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20286; }","duration":"365.732541ms","start":"2026-09-07T19:00:18.001303Z","end":"2026-09-07T19:00:18.367035Z","steps":["trace[681878486] 'agreement among raft nodes before linearized reading' (duration: 364.830643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:18.367102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:00:18.001274Z","time spent":"365.818274ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-07T19:04:10.833485Z","caller":"traceutil/trace.go:171","msg":"trace[1837132143] transaction","detail":"{read_only:false; response_revision:22717; number_of_response:1; }","duration":"269.250862ms","start":"2026-09-07T19:04:10.564211Z","end":"2026-09-07T19:04:10.833461Z","steps":["trace[1837132143] 'process raft request' (duration: 269.067206ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:04:10.842443Z","caller":"traceutil/trace.go:171","msg":"trace[160432627] transaction","detail":"{read_only:false; response_revision:22718; number_of_response:1; }","duration":"173.747576ms","start":"2026-09-07T19:04:10.668652Z","end":"2026-09-07T19:04:10.842399Z","steps":["trace[160432627] 'process raft request' (duration: 172.718034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:04:10.843137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.659929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T19:04:10.843192Z","caller":"traceutil/trace.go:171","msg":"trace[1463125387] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:22719; }","duration":"121.808803ms","start":"2026-09-07T19:04:10.72137Z","end":"2026-09-07T19:04:10.843179Z","steps":["trace[1463125387] 'agreement among raft nodes before linearized reading' (duration: 121.642188ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:04:10.841668Z","caller":"traceutil/trace.go:171","msg":"trace[1285493843] linearizableReadLoop","detail":"{readStateIndex:23476; appliedIndex:23474; }","duration":"119.936086ms","start":"2026-09-07T19:04:10.7217Z","end":"2026-09-07T19:04:10.841636Z","steps":["trace[1285493843] 'read index received' (duration: 111.679613ms)","trace[1285493843] 'applied index is now lower than readState.Index' (duration: 8.254603ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:04:57.413125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20106} {"level":"info","ts":"2026-09-07T19:04:57.527639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20106,"took":"111.507379ms","hash":1057484602,"current-db-size-bytes":56877056,"current-db-size":"57 MB","current-db-size-in-use-bytes":42500096,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-07T19:04:57.527713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1057484602,"revision":20106,"compact-revision":17675} {"level":"info","ts":"2026-09-07T19:09:57.419552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23171} {"level":"info","ts":"2026-09-07T19:09:57.562902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23171,"took":"139.492457ms","hash":4148631759,"current-db-size-bytes":56877056,"current-db-size":"57 MB","current-db-size-in-use-bytes":31117312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T19:09:57.563546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4148631759,"revision":23171,"compact-revision":20106} {"level":"info","ts":"2026-09-07T19:12:34.246174Z","caller":"traceutil/trace.go:171","msg":"trace[1207630760] linearizableReadLoop","detail":"{readStateIndex:27676; appliedIndex:27675; }","duration":"100.499371ms","start":"2026-09-07T19:12:34.145656Z","end":"2026-09-07T19:12:34.246155Z","steps":["trace[1207630760] 'read index received' (duration: 20.594288ms)","trace[1207630760] 'applied index is now lower than readState.Index' (duration: 79.904143ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:34.246265Z","caller":"traceutil/trace.go:171","msg":"trace[339023077] transaction","detail":"{read_only:false; response_revision:26805; number_of_response:1; }","duration":"103.823974ms","start":"2026-09-07T19:12:34.142407Z","end":"2026-09-07T19:12:34.246231Z","steps":["trace[339023077] 'process raft request' (duration: 101.792171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:12:34.246426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.7588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/valkey-node-0\" ","response":"range_response_count:1 size:16872"} {"level":"info","ts":"2026-09-07T19:12:34.246518Z","caller":"traceutil/trace.go:171","msg":"trace[960284774] range","detail":"{range_begin:/registry/pods/openstack/valkey-node-0; range_end:; response_count:1; response_revision:26805; }","duration":"100.876633ms","start":"2026-09-07T19:12:34.145621Z","end":"2026-09-07T19:12:34.246498Z","steps":["trace[960284774] 'agreement among raft nodes before linearized reading' (duration: 100.624715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:12:44.534988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.039979ms","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-09-07T19:12:44.535077Z","caller":"traceutil/trace.go:171","msg":"trace[25117548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26881; }","duration":"102.145782ms","start":"2026-09-07T19:12:44.432913Z","end":"2026-09-07T19:12:44.535059Z","steps":["trace[25117548] 'range keys from in-memory index tree' (duration: 101.877834ms)"],"step_count":1}