{"level":"warn","ts":"2026-04-07T00:01:27.68513Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:01:27.685214Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T00:01:27.685287Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:01:27.6853Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-07T00:01:27.68532Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:01:27.686008Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-07T00:01:27.686083Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T00:01:27.690906Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.591485ms"} {"level":"info","ts":"2026-04-07T00:01:27.706919Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-07T00:01:27.707044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:01:27.707093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-07T00:01:27.707114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:01:27.707131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-07T00:01:27.707182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-07T00:01:27.714419Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:01:27.715832Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:01:27.716955Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T00:01:27.720322Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:01:27.720775Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:01:27.720924Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:01:27.720974Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:01:27.720986Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:01:27.722451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-07T00:01:27.723082Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-07T00:01:27.724933Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:01:27.72517Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:01:27.725207Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:01:27.725342Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-07T00:01:27.725412Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-07T00:01:27.80831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:01:27.808419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:01:27.808481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-07T00:01:27.808529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:01:27.808561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-07T00:01:27.808598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-07T00:01:27.808668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-07T00:01:27.809965Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:27.810138Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:01:27.809931Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:01:27.813555Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:01:27.813596Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:01:27.814243Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:01:27.813534Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:01:27.817974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-04-07T00:01:27.826456Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:27.826816Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:27.829575Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:29.989509Z","caller":"traceutil/trace.go:171","msg":"trace[77871826] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"163.755497ms","start":"2026-04-07T00:01:29.825722Z","end":"2026-04-07T00:01:29.989477Z","steps":["trace[77871826] 'read index received' (duration: 163.535954ms)","trace[77871826] 'applied index is now lower than readState.Index' (duration: 218.083µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:29.989544Z","caller":"traceutil/trace.go:171","msg":"trace[684457805] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"166.159641ms","start":"2026-04-07T00:01:29.823347Z","end":"2026-04-07T00:01:29.989507Z","steps":["trace[684457805] 'process raft request' (duration: 165.81142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:29.989963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.207734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:01:29.990043Z","caller":"traceutil/trace.go:171","msg":"trace[540409145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:2; }","duration":"164.320121ms","start":"2026-04-07T00:01:29.825706Z","end":"2026-04-07T00:01:29.990026Z","steps":["trace[540409145] 'agreement among raft nodes before linearized reading' (duration: 163.96483ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:30.198645Z","caller":"traceutil/trace.go:171","msg":"trace[602447381] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"305.904623ms","start":"2026-04-07T00:01:29.892711Z","end":"2026-04-07T00:01:30.198615Z","steps":["trace[602447381] 'process raft request' (duration: 305.566493ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:30.198885Z","caller":"traceutil/trace.go:171","msg":"trace[1802444312] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"305.993857ms","start":"2026-04-07T00:01:29.892845Z","end":"2026-04-07T00:01:30.198839Z","steps":["trace[1802444312] 'process raft request' (duration: 305.466386ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:30.199089Z","caller":"traceutil/trace.go:171","msg":"trace[1371637796] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"306.156468ms","start":"2026-04-07T00:01:29.892923Z","end":"2026-04-07T00:01:30.19908Z","steps":["trace[1371637796] 'process raft request' (duration: 305.411393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.199377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.892916Z","time spent":"306.19536ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:01:30.199412Z","caller":"traceutil/trace.go:171","msg":"trace[1615873488] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"209.744652ms","start":"2026-04-07T00:01:29.989628Z","end":"2026-04-07T00:01:30.199372Z","steps":["trace[1615873488] 'read index received' (duration: 208.363059ms)","trace[1615873488] 'applied index is now lower than readState.Index' (duration: 1.380013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:30.199451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.892685Z","time spent":"306.035131ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:01:30.199515Z","caller":"traceutil/trace.go:171","msg":"trace[427572179] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"287.36228ms","start":"2026-04-07T00:01:29.912094Z","end":"2026-04-07T00:01:30.199456Z","steps":["trace[427572179] 'process raft request' (duration: 287.226522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.1994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.892828Z","time spent":"306.147176ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:01:30.199652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.080182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T00:01:30.199694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.916714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:01:30.199703Z","caller":"traceutil/trace.go:171","msg":"trace[1004050369] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"256.170808ms","start":"2026-04-07T00:01:29.943517Z","end":"2026-04-07T00:01:30.199687Z","steps":["trace[1004050369] 'agreement among raft nodes before linearized reading' (duration: 256.02945ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:30.199728Z","caller":"traceutil/trace.go:171","msg":"trace[1300214395] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"321.998539ms","start":"2026-04-07T00:01:29.877717Z","end":"2026-04-07T00:01:30.199715Z","steps":["trace[1300214395] 'agreement among raft nodes before linearized reading' (duration: 321.950066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.199758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.877689Z","time spent":"322.062953ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T00:01:30.199895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.01727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-04-07T00:01:30.19993Z","caller":"traceutil/trace.go:171","msg":"trace[616765155] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"367.060683ms","start":"2026-04-07T00:01:29.832858Z","end":"2026-04-07T00:01:30.199919Z","steps":["trace[616765155] 'agreement among raft nodes before linearized reading' (duration: 366.991409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.199963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.832854Z","time spent":"367.100625ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-07T00:01:30.199965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.656989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T00:01:30.199978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.974596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:01:30.200013Z","caller":"traceutil/trace.go:171","msg":"trace[2097444637] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"315.728463ms","start":"2026-04-07T00:01:29.884271Z","end":"2026-04-07T00:01:30.2Z","steps":["trace[2097444637] 'agreement among raft nodes before linearized reading' (duration: 315.651588ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:30.200022Z","caller":"traceutil/trace.go:171","msg":"trace[918316995] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:0; response_revision:10; }","duration":"310.078063ms","start":"2026-04-07T00:01:29.889932Z","end":"2026-04-07T00:01:30.20001Z","steps":["trace[918316995] 'agreement among raft nodes before linearized reading' (duration: 309.987348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.200056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.889794Z","time spent":"310.254614ms","remote":"127.0.0.1:44272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-04-07T00:01:30.200052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.88426Z","time spent":"315.781826ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T00:01:30.200183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.392513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:01:30.20022Z","caller":"traceutil/trace.go:171","msg":"trace[1558847033] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"367.438215ms","start":"2026-04-07T00:01:29.83277Z","end":"2026-04-07T00:01:30.200209Z","steps":["trace[1558847033] 'agreement among raft nodes before linearized reading' (duration: 367.380282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.200224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.693929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:01:30.200262Z","caller":"traceutil/trace.go:171","msg":"trace[473636050] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"306.748603ms","start":"2026-04-07T00:01:29.893503Z","end":"2026-04-07T00:01:30.200251Z","steps":["trace[473636050] 'agreement among raft nodes before linearized reading' (duration: 306.695979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.200267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.832765Z","time spent":"367.483578ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-07T00:01:30.200288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.89349Z","time spent":"306.791855ms","remote":"127.0.0.1:44322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-07T00:01:30.200455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.823207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:01:30.200481Z","caller":"traceutil/trace.go:171","msg":"trace[1276101722] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"308.869ms","start":"2026-04-07T00:01:29.891604Z","end":"2026-04-07T00:01:30.200473Z","steps":["trace[1276101722] 'agreement among raft nodes before linearized reading' (duration: 308.829918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.200505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.891594Z","time spent":"308.905232ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T00:01:30.200522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.070495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:01:30.200566Z","caller":"traceutil/trace.go:171","msg":"trace[1949963036] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"154.139829ms","start":"2026-04-07T00:01:30.046413Z","end":"2026-04-07T00:01:30.200553Z","steps":["trace[1949963036] 'agreement among raft nodes before linearized reading' (duration: 154.071395ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:30.20103Z","caller":"traceutil/trace.go:171","msg":"trace[625627140] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"308.088914ms","start":"2026-04-07T00:01:29.892924Z","end":"2026-04-07T00:01:30.201013Z","steps":["trace[625627140] 'process raft request' (duration: 305.436215ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:30.201073Z","caller":"traceutil/trace.go:171","msg":"trace[1338162399] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"308.094294ms","start":"2026-04-07T00:01:29.892966Z","end":"2026-04-07T00:01:30.20106Z","steps":["trace[1338162399] 'process raft request' (duration: 306.214861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.201135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.892917Z","time spent":"308.174469ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:01:30.201349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.89296Z","time spent":"308.144418ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:01:30.201559Z","caller":"traceutil/trace.go:171","msg":"trace[1249688539] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"294.953386ms","start":"2026-04-07T00:01:29.906587Z","end":"2026-04-07T00:01:30.20154Z","steps":["trace[1249688539] 'process raft request' (duration: 292.678629ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:30.199051Z","caller":"traceutil/trace.go:171","msg":"trace[1669975074] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"306.748994ms","start":"2026-04-07T00:01:29.892279Z","end":"2026-04-07T00:01:30.199028Z","steps":["trace[1669975074] 'process raft request' (duration: 305.840089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:30.201699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:29.892253Z","time spent":"309.388443ms","remote":"127.0.0.1:44676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:01:58.837244Z","caller":"traceutil/trace.go:171","msg":"trace[1845260288] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"124.220216ms","start":"2026-04-07T00:01:58.713004Z","end":"2026-04-07T00:01:58.837224Z","steps":["trace[1845260288] 'process raft request' (duration: 123.898052ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:58.837246Z","caller":"traceutil/trace.go:171","msg":"trace[1673509497] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"135.714572ms","start":"2026-04-07T00:01:58.701505Z","end":"2026-04-07T00:01:58.83722Z","steps":["trace[1673509497] 'process raft request' (duration: 121.520391ms)","trace[1673509497] 'compare' (duration: 13.644521ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:05.572044Z","caller":"traceutil/trace.go:171","msg":"trace[285942825] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"631.852054ms","start":"2026-04-07T00:02:04.940132Z","end":"2026-04-07T00:02:05.571985Z","steps":["trace[285942825] 'process raft request' (duration: 631.164393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:05.572876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:04.940099Z","time spent":"632.13022ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:02:06.385142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.145771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:02:06.385278Z","caller":"traceutil/trace.go:171","msg":"trace[2061178086] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"716.228336ms","start":"2026-04-07T00:02:05.669018Z","end":"2026-04-07T00:02:06.385247Z","steps":["trace[2061178086] 'read index received' (duration: 442.589341ms)","trace[2061178086] 'applied index is now lower than readState.Index' (duration: 273.637415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:06.385438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.413514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:06.385481Z","caller":"traceutil/trace.go:171","msg":"trace[926488357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"716.491932ms","start":"2026-04-07T00:02:05.668979Z","end":"2026-04-07T00:02:06.385471Z","steps":["trace[926488357] 'agreement among raft nodes before linearized reading' (duration: 716.380544ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:06.385435Z","caller":"traceutil/trace.go:171","msg":"trace[765380842] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"746.053938ms","start":"2026-04-07T00:02:05.639293Z","end":"2026-04-07T00:02:06.385347Z","steps":["trace[765380842] 'process raft request' (duration: 472.420753ms)","trace[765380842] 'compare' (duration: 272.35198ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:02:06.385516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:05.668964Z","time spent":"716.543692ms","remote":"127.0.0.1:44246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:02:06.385632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.642736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T00:02:06.385672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:05.639261Z","time spent":"746.301694ms","remote":"127.0.0.1:44484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:02:06.385754Z","caller":"traceutil/trace.go:171","msg":"trace[252716772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"632.819964ms","start":"2026-04-07T00:02:05.752898Z","end":"2026-04-07T00:02:06.385718Z","steps":["trace[252716772] 'agreement among raft nodes before linearized reading' (duration: 632.513307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:06.385804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:05.752874Z","time spent":"632.919843ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T00:02:07.822935Z","caller":"traceutil/trace.go:171","msg":"trace[156425947] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"116.067987ms","start":"2026-04-07T00:02:07.706831Z","end":"2026-04-07T00:02:07.822899Z","steps":["trace[156425947] 'process raft request' (duration: 115.870429ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:52.368292Z","caller":"traceutil/trace.go:171","msg":"trace[846443877] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"115.152043ms","start":"2026-04-07T00:02:52.253098Z","end":"2026-04-07T00:02:52.36825Z","steps":["trace[846443877] 'process raft request' (duration: 114.909548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:03:14.150748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.917243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T00:03:14.150887Z","caller":"traceutil/trace.go:171","msg":"trace[1469221750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1403; }","duration":"116.109309ms","start":"2026-04-07T00:03:14.03475Z","end":"2026-04-07T00:03:14.150859Z","steps":["trace[1469221750] 'range keys from in-memory index tree' (duration: 115.687406ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:37.44344Z","caller":"traceutil/trace.go:171","msg":"trace[1661018496] linearizableReadLoop","detail":"{readStateIndex:1857; appliedIndex:1856; }","duration":"275.454163ms","start":"2026-04-07T00:03:37.167964Z","end":"2026-04-07T00:03:37.443418Z","steps":["trace[1661018496] 'read index received' (duration: 275.219104ms)","trace[1661018496] 'applied index is now lower than readState.Index' (duration: 234.198µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:03:37.443557Z","caller":"traceutil/trace.go:171","msg":"trace[1558698297] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"356.967834ms","start":"2026-04-07T00:03:37.086578Z","end":"2026-04-07T00:03:37.443546Z","steps":["trace[1558698297] 'process raft request' (duration: 356.680524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:03:37.443663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:03:37.086557Z","time spent":"357.026916ms","remote":"127.0.0.1:44406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:03:37.443952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.989842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T00:03:37.444002Z","caller":"traceutil/trace.go:171","msg":"trace[1556012023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1799; }","duration":"276.078856ms","start":"2026-04-07T00:03:37.167909Z","end":"2026-04-07T00:03:37.443987Z","steps":["trace[1556012023] 'agreement among raft nodes before linearized reading' (duration: 275.942311ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:37.736479Z","caller":"traceutil/trace.go:171","msg":"trace[1077922723] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"267.002341ms","start":"2026-04-07T00:03:37.46945Z","end":"2026-04-07T00:03:37.736453Z","steps":["trace[1077922723] 'read index received' (duration: 198.804875ms)","trace[1077922723] 'applied index is now lower than readState.Index' (duration: 68.196706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:03:37.736722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.249131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T00:03:37.73678Z","caller":"traceutil/trace.go:171","msg":"trace[1817733829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1801; }","duration":"267.360385ms","start":"2026-04-07T00:03:37.469406Z","end":"2026-04-07T00:03:37.736766Z","steps":["trace[1817733829] 'agreement among raft nodes before linearized reading' (duration: 267.168858ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:37.736702Z","caller":"traceutil/trace.go:171","msg":"trace[183069168] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"287.771802ms","start":"2026-04-07T00:03:37.448874Z","end":"2026-04-07T00:03:37.736646Z","steps":["trace[183069168] 'process raft request' (duration: 219.42835ms)","trace[183069168] 'compare' (duration: 68.049401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:03:37.737127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.340017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-015dab06-e735-4a96-ac42-a28bc9990840\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-04-07T00:03:37.737216Z","caller":"traceutil/trace.go:171","msg":"trace[102055255] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-015dab06-e735-4a96-ac42-a28bc9990840; range_end:; response_count:1; response_revision:1801; }","duration":"229.472022ms","start":"2026-04-07T00:03:37.507723Z","end":"2026-04-07T00:03:37.737195Z","steps":["trace[102055255] 'agreement among raft nodes before linearized reading' (duration: 228.87559ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:46.694835Z","caller":"traceutil/trace.go:171","msg":"trace[1336697156] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"150.063099ms","start":"2026-04-07T00:03:46.54475Z","end":"2026-04-07T00:03:46.694813Z","steps":["trace[1336697156] 'process raft request' (duration: 149.649903ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:10.046771Z","caller":"traceutil/trace.go:171","msg":"trace[80324626] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"101.043506ms","start":"2026-04-07T00:04:09.945708Z","end":"2026-04-07T00:04:10.046751Z","steps":["trace[80324626] 'process raft request' (duration: 100.590216ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:10.243631Z","caller":"traceutil/trace.go:171","msg":"trace[2037724096] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"124.236392ms","start":"2026-04-07T00:04:10.119371Z","end":"2026-04-07T00:04:10.243607Z","steps":["trace[2037724096] 'process raft request' (duration: 124.082825ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:10.243712Z","caller":"traceutil/trace.go:171","msg":"trace[522504593] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"190.406056ms","start":"2026-04-07T00:04:10.053232Z","end":"2026-04-07T00:04:10.243638Z","steps":["trace[522504593] 'process raft request' (duration: 142.48915ms)","trace[522504593] 'compare' (duration: 47.518359ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:04:21.661712Z","caller":"traceutil/trace.go:171","msg":"trace[357210078] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"124.153811ms","start":"2026-04-07T00:04:21.537535Z","end":"2026-04-07T00:04:21.661689Z","steps":["trace[357210078] 'process raft request' (duration: 123.996474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:27.386525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.148539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:04:27.386685Z","caller":"traceutil/trace.go:171","msg":"trace[819622609] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2262; }","duration":"102.017338ms","start":"2026-04-07T00:04:27.284644Z","end":"2026-04-07T00:04:27.386661Z","steps":["trace[819622609] 'read index received' (duration: 70.964µs)","trace[819622609] 'applied index is now lower than readState.Index' (duration: 101.944534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:04:27.386955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.341553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-07T00:04:27.386998Z","caller":"traceutil/trace.go:171","msg":"trace[1506575528] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2185; }","duration":"102.440798ms","start":"2026-04-07T00:04:27.284539Z","end":"2026-04-07T00:04:27.38698Z","steps":["trace[1506575528] 'agreement among raft nodes before linearized reading' (duration: 102.182066ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:27.387038Z","caller":"traceutil/trace.go:171","msg":"trace[1525149226] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"174.25928ms","start":"2026-04-07T00:04:27.212707Z","end":"2026-04-07T00:04:27.386966Z","steps":["trace[1525149226] 'process raft request' (duration: 49.534564ms)","trace[1525149226] 'compare' (duration: 123.926279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:04:27.81504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.183942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:04:27.815235Z","caller":"traceutil/trace.go:171","msg":"trace[1117561853] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2264; }","duration":"368.699329ms","start":"2026-04-07T00:04:27.446511Z","end":"2026-04-07T00:04:27.81521Z","steps":["trace[1117561853] 'read index received' (duration: 51.14856ms)","trace[1117561853] 'applied index is now lower than readState.Index' (duration: 317.547759ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:04:27.815364Z","caller":"traceutil/trace.go:171","msg":"trace[315686038] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"396.594287ms","start":"2026-04-07T00:04:27.418756Z","end":"2026-04-07T00:04:27.81535Z","steps":["trace[315686038] 'process raft request' (duration: 78.994246ms)","trace[315686038] 'compare' (duration: 316.978863ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:04:27.815437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:04:27.418724Z","time spent":"396.666761ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:04:27.815515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.763764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-07T00:04:27.815559Z","caller":"traceutil/trace.go:171","msg":"trace[738025374] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2188; }","duration":"359.843247ms","start":"2026-04-07T00:04:27.455703Z","end":"2026-04-07T00:04:27.815546Z","steps":["trace[738025374] 'agreement among raft nodes before linearized reading' (duration: 359.69245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:27.815596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:04:27.455684Z","time spent":"359.90518ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4615,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T00:04:27.815604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.863178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:04:27.815695Z","caller":"traceutil/trace.go:171","msg":"trace[255461672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"146.018185ms","start":"2026-04-07T00:04:27.669656Z","end":"2026-04-07T00:04:27.815674Z","steps":["trace[255461672] 'agreement among raft nodes before linearized reading' (duration: 145.854418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:27.815782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.229234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-07T00:04:27.81588Z","caller":"traceutil/trace.go:171","msg":"trace[1672774687] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2188; }","duration":"369.413402ms","start":"2026-04-07T00:04:27.446446Z","end":"2026-04-07T00:04:27.81586Z","steps":["trace[1672774687] 'agreement among raft nodes before linearized reading' (duration: 369.083307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:27.815943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:04:27.446421Z","time spent":"369.510107ms","remote":"127.0.0.1:44334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T00:04:27.815389Z","caller":"traceutil/trace.go:171","msg":"trace[1892564002] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"178.958379ms","start":"2026-04-07T00:04:27.636318Z","end":"2026-04-07T00:04:27.815277Z","steps":["trace[1892564002] 'process raft request' (duration: 178.831573ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:27.944132Z","caller":"traceutil/trace.go:171","msg":"trace[1821126159] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"121.150368ms","start":"2026-04-07T00:04:27.822948Z","end":"2026-04-07T00:04:27.944099Z","steps":["trace[1821126159] 'process raft request' (duration: 97.408123ms)","trace[1821126159] 'compare' (duration: 23.510365ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:05:06.235838Z","caller":"traceutil/trace.go:171","msg":"trace[1062702716] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"142.649222ms","start":"2026-04-07T00:05:06.093168Z","end":"2026-04-07T00:05:06.235817Z","steps":["trace[1062702716] 'process raft request' (duration: 142.368369ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:33.698747Z","caller":"traceutil/trace.go:171","msg":"trace[332456413] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"100.090646ms","start":"2026-04-07T00:05:33.598635Z","end":"2026-04-07T00:05:33.698726Z","steps":["trace[332456413] 'process raft request' (duration: 99.959009ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:03.566102Z","caller":"traceutil/trace.go:171","msg":"trace[285832246] transaction","detail":"{read_only:false; response_revision:4502; number_of_response:1; }","duration":"147.101587ms","start":"2026-04-07T00:08:03.418977Z","end":"2026-04-07T00:08:03.566079Z","steps":["trace[285832246] 'process raft request' (duration: 146.909184ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:03.674083Z","caller":"traceutil/trace.go:171","msg":"trace[1401856759] linearizableReadLoop","detail":"{readStateIndex:4698; appliedIndex:4697; }","duration":"102.190474ms","start":"2026-04-07T00:08:03.571856Z","end":"2026-04-07T00:08:03.674047Z","steps":["trace[1401856759] 'read index received' (duration: 85.036814ms)","trace[1401856759] 'applied index is now lower than readState.Index' (duration: 17.15183ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:03.674281Z","caller":"traceutil/trace.go:171","msg":"trace[1738017612] transaction","detail":"{read_only:false; response_revision:4503; number_of_response:1; }","duration":"103.714677ms","start":"2026-04-07T00:08:03.570551Z","end":"2026-04-07T00:08:03.674266Z","steps":["trace[1738017612] 'process raft request' (duration: 86.342102ms)","trace[1738017612] 'compare' (duration: 16.968836ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:03.674624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.747615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49a55622-2f84-49d2-b8a8-accb98053f74\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-04-07T00:08:03.674717Z","caller":"traceutil/trace.go:171","msg":"trace[31453407] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49a55622-2f84-49d2-b8a8-accb98053f74; range_end:; response_count:1; response_revision:4503; }","duration":"102.871029ms","start":"2026-04-07T00:08:03.571828Z","end":"2026-04-07T00:08:03.674699Z","steps":["trace[31453407] 'agreement among raft nodes before linearized reading' (duration: 102.696005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:16.930036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.983412ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:16.930191Z","caller":"traceutil/trace.go:171","msg":"trace[1727962944] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4699; }","duration":"133.465673ms","start":"2026-04-07T00:08:16.796705Z","end":"2026-04-07T00:08:16.93017Z","steps":["trace[1727962944] 'range keys from in-memory index tree' (duration: 132.959042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:16.93033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.583905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:08:16.930549Z","caller":"traceutil/trace.go:171","msg":"trace[1245388219] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"501.114037ms","start":"2026-04-07T00:08:16.429417Z","end":"2026-04-07T00:08:16.930531Z","steps":["trace[1245388219] 'process raft request' (duration: 50.240756ms)","trace[1245388219] 'compare' (duration: 450.471032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:16.930692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:16.429404Z","time spent":"501.179269ms","remote":"127.0.0.1:44334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:08:16.930841Z","caller":"traceutil/trace.go:171","msg":"trace[913263087] linearizableReadLoop","detail":"{readStateIndex:4903; appliedIndex:4902; }","duration":"302.442727ms","start":"2026-04-07T00:08:16.628359Z","end":"2026-04-07T00:08:16.930802Z","steps":["trace[913263087] 'read index received' (duration: 33.381µs)","trace[913263087] 'applied index is now lower than readState.Index' (duration: 302.406666ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:16.958566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.205056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-07T00:08:16.958597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.225251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:16.958626Z","caller":"traceutil/trace.go:171","msg":"trace[1524878850] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4700; }","duration":"330.307009ms","start":"2026-04-07T00:08:16.628303Z","end":"2026-04-07T00:08:16.95861Z","steps":["trace[1524878850] 'agreement among raft nodes before linearized reading' (duration: 302.896109ms)","trace[1524878850] 'range keys from in-memory index tree' (duration: 27.252007ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:16.958657Z","caller":"traceutil/trace.go:171","msg":"trace[829404505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4700; }","duration":"290.298863ms","start":"2026-04-07T00:08:16.668341Z","end":"2026-04-07T00:08:16.95864Z","steps":["trace[829404505] 'agreement among raft nodes before linearized reading' (duration: 290.209251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:16.95867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:16.62828Z","time spent":"330.380532ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-07T00:08:16.958696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.234306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T00:08:16.95886Z","caller":"traceutil/trace.go:171","msg":"trace[1413290114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4700; }","duration":"250.434911ms","start":"2026-04-07T00:08:16.708399Z","end":"2026-04-07T00:08:16.958834Z","steps":["trace[1413290114] 'agreement among raft nodes before linearized reading' (duration: 250.124543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:16.959009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.431992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10515"} {"level":"info","ts":"2026-04-07T00:08:16.959071Z","caller":"traceutil/trace.go:171","msg":"trace[1365241204] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4700; }","duration":"330.585516ms","start":"2026-04-07T00:08:16.628455Z","end":"2026-04-07T00:08:16.959041Z","steps":["trace[1365241204] 'agreement among raft nodes before linearized reading' (duration: 330.435812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:16.95911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:16.628436Z","time spent":"330.666998ms","remote":"127.0.0.1:44406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10539,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-07T00:08:16.959212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.730406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-07T00:08:16.959283Z","caller":"traceutil/trace.go:171","msg":"trace[2118746778] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4700; }","duration":"279.833548ms","start":"2026-04-07T00:08:16.679429Z","end":"2026-04-07T00:08:16.959263Z","steps":["trace[2118746778] 'agreement among raft nodes before linearized reading' (duration: 279.688795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:17.758454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.283948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:08:17.758628Z","caller":"traceutil/trace.go:171","msg":"trace[601429440] linearizableReadLoop","detail":"{readStateIndex:4917; appliedIndex:4916; }","duration":"190.484711ms","start":"2026-04-07T00:08:17.56808Z","end":"2026-04-07T00:08:17.758565Z","steps":["trace[601429440] 'read index received' (duration: 71.902296ms)","trace[601429440] 'applied index is now lower than readState.Index' (duration: 118.580475ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:17.758736Z","caller":"traceutil/trace.go:171","msg":"trace[325467188] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"197.085426ms","start":"2026-04-07T00:08:17.561603Z","end":"2026-04-07T00:08:17.758689Z","steps":["trace[325467188] 'process raft request' (duration: 78.46371ms)","trace[325467188] 'compare' (duration: 118.141885ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:17.759124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.006373ms","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-04-07T00:08:17.759242Z","caller":"traceutil/trace.go:171","msg":"trace[850504453] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4713; }","duration":"191.162317ms","start":"2026-04-07T00:08:17.568053Z","end":"2026-04-07T00:08:17.759215Z","steps":["trace[850504453] 'agreement among raft nodes before linearized reading' (duration: 190.701526ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:17.974239Z","caller":"traceutil/trace.go:171","msg":"trace[2104821108] linearizableReadLoop","detail":"{readStateIndex:4920; appliedIndex:4917; }","duration":"194.350132ms","start":"2026-04-07T00:08:17.779875Z","end":"2026-04-07T00:08:17.974225Z","steps":["trace[2104821108] 'read index received' (duration: 312.078µs)","trace[2104821108] 'applied index is now lower than readState.Index' (duration: 194.036794ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:17.974333Z","caller":"traceutil/trace.go:171","msg":"trace[1142734557] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"205.912625ms","start":"2026-04-07T00:08:17.768392Z","end":"2026-04-07T00:08:17.974305Z","steps":["trace[1142734557] 'process raft request' (duration: 205.632099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:17.974606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.7059ms","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-04-07T00:08:17.974677Z","caller":"traceutil/trace.go:171","msg":"trace[2056795324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4716; }","duration":"194.809922ms","start":"2026-04-07T00:08:17.77985Z","end":"2026-04-07T00:08:17.97466Z","steps":["trace[2056795324] 'agreement among raft nodes before linearized reading' (duration: 194.583527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:17.974732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.203491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana.18a3ea758169bc3a\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-04-07T00:08:17.974799Z","caller":"traceutil/trace.go:171","msg":"trace[2033425623] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana.18a3ea758169bc3a; range_end:; response_count:1; response_revision:4716; }","duration":"189.311903ms","start":"2026-04-07T00:08:17.78547Z","end":"2026-04-07T00:08:17.974781Z","steps":["trace[2033425623] 'agreement among raft nodes before linearized reading' (duration: 189.091868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:17.974143Z","caller":"traceutil/trace.go:171","msg":"trace[520130299] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"206.092209ms","start":"2026-04-07T00:08:17.768004Z","end":"2026-04-07T00:08:17.974096Z","steps":["trace[520130299] 'process raft request' (duration: 205.841784ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:18.137968Z","caller":"traceutil/trace.go:171","msg":"trace[542915289] linearizableReadLoop","detail":"{readStateIndex:4922; appliedIndex:4920; }","duration":"155.429388ms","start":"2026-04-07T00:08:17.982517Z","end":"2026-04-07T00:08:18.137946Z","steps":["trace[542915289] 'read index received' (duration: 19.182013ms)","trace[542915289] 'applied index is now lower than readState.Index' (duration: 136.246305ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:18.138127Z","caller":"traceutil/trace.go:171","msg":"trace[832333264] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"154.943106ms","start":"2026-04-07T00:08:17.983155Z","end":"2026-04-07T00:08:18.138098Z","steps":["trace[832333264] 'process raft request' (duration: 154.745592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:18.138406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.867519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T00:08:18.138456Z","caller":"traceutil/trace.go:171","msg":"trace[449878150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4719; }","duration":"155.958232ms","start":"2026-04-07T00:08:17.982486Z","end":"2026-04-07T00:08:18.138444Z","steps":["trace[449878150] 'agreement among raft nodes before linearized reading' (duration: 155.612622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:18.13843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.101113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-alertmanager.18a3ea75821c8b9d\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-04-07T00:08:18.138571Z","caller":"traceutil/trace.go:171","msg":"trace[2096945674] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-alertmanager.18a3ea75821c8b9d; range_end:; response_count:1; response_revision:4719; }","duration":"133.287267ms","start":"2026-04-07T00:08:18.00526Z","end":"2026-04-07T00:08:18.138547Z","steps":["trace[2096945674] 'agreement among raft nodes before linearized reading' (duration: 133.032191ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:18.13858Z","caller":"traceutil/trace.go:171","msg":"trace[329360270] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"158.89037ms","start":"2026-04-07T00:08:17.979338Z","end":"2026-04-07T00:08:18.138229Z","steps":["trace[329360270] 'process raft request' (duration: 97.450811ms)","trace[329360270] 'compare' (duration: 60.990608ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:14.488831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.039216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T00:11:14.488925Z","caller":"traceutil/trace.go:171","msg":"trace[2072522298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6172; }","duration":"200.167731ms","start":"2026-04-07T00:11:14.288733Z","end":"2026-04-07T00:11:14.488901Z","steps":["trace[2072522298] 'agreement among raft nodes before linearized reading' (duration: 43.596468ms)","trace[2072522298] 'range keys from in-memory index tree' (duration: 156.356285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:14.488924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.188955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:14.48949Z","caller":"traceutil/trace.go:171","msg":"trace[858001507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6172; }","duration":"148.477557ms","start":"2026-04-07T00:11:14.340669Z","end":"2026-04-07T00:11:14.489147Z","steps":["trace[858001507] 'range keys from in-memory index tree' (duration: 148.104462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:14.489632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.904006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-04-07T00:11:14.489725Z","caller":"traceutil/trace.go:171","msg":"trace[200300124] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6172; }","duration":"154.032131ms","start":"2026-04-07T00:11:14.335669Z","end":"2026-04-07T00:11:14.489701Z","steps":["trace[200300124] 'range keys from in-memory index tree' (duration: 153.716739ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:14.489788Z","caller":"traceutil/trace.go:171","msg":"trace[1150508345] transaction","detail":"{read_only:false; response_revision:6173; number_of_response:1; }","duration":"145.896053ms","start":"2026-04-07T00:11:14.343865Z","end":"2026-04-07T00:11:14.489761Z","steps":["trace[1150508345] 'process raft request' (duration: 140.978604ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:28.457689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3201} {"level":"info","ts":"2026-04-07T00:11:28.550575Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3201,"took":"91.367598ms","hash":2034463582,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":31346688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T00:11:28.550658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2034463582,"revision":3201,"compact-revision":-1}