{"level":"warn","ts":"2026-06-05T22:03:09.838255Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T22:03:09.838343Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-05T22:03:09.838412Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T22:03:09.838433Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-06-05T22:03:09.838451Z","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-06-05T22:03:09.840639Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-06-05T22:03:09.840721Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-05T22:03:09.844794Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.742402ms"} {"level":"info","ts":"2026-06-05T22:03:09.850203Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-06-05T22:03:09.850309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T22:03:09.850331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-06-05T22:03:09.850345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T22:03:09.850512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-06-05T22:03:09.850571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-06-05T22:03:09.858908Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T22:03:09.860189Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T22:03:09.861446Z","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-06-05T22:03:09.863782Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T22:03:09.863986Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T22:03:09.864227Z","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-06-05T22:03:09.864312Z","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-06-05T22:03:09.864321Z","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-06-05T22:03:09.864851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-06-05T22:03:09.866113Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-06-05T22:03:09.870808Z","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-06-05T22:03:09.870971Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-06-05T22:03:09.871014Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-06-05T22:03:09.871295Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T22:03:09.871356Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T22:03:10.250964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T22:03:10.251121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T22:03:10.251198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-06-05T22:03:10.251227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-06-05T22:03:10.25124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-06-05T22:03:10.251257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-06-05T22:03:10.251274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-06-05T22:03:10.252823Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T22:03:10.252865Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T22:03:10.252839Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:03:10.253051Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T22:03:10.253233Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T22:03:10.25331Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T22:03:10.255627Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-06-05T22:03:10.256955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T22:03:10.257551Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:03:10.257816Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:03:10.257981Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:03:21.464735Z","caller":"traceutil/trace.go:171","msg":"trace[1600796106] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"108.79036ms","start":"2026-06-05T22:03:21.355918Z","end":"2026-06-05T22:03:21.464708Z","steps":["trace[1600796106] 'process raft request' (duration: 108.651198ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.92641Z","caller":"traceutil/trace.go:171","msg":"trace[1509958027] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"237.456142ms","start":"2026-06-05T22:03:49.688923Z","end":"2026-06-05T22:03:49.926379Z","steps":["trace[1509958027] 'process raft request' (duration: 236.942316ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.946494Z","caller":"traceutil/trace.go:171","msg":"trace[467254777] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"252.194665ms","start":"2026-06-05T22:03:49.694286Z","end":"2026-06-05T22:03:49.946481Z","steps":["trace[467254777] 'process raft request' (duration: 252.022933ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.946539Z","caller":"traceutil/trace.go:171","msg":"trace[88035253] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"248.266198ms","start":"2026-06-05T22:03:49.698243Z","end":"2026-06-05T22:03:49.946509Z","steps":["trace[88035253] 'process raft request' (duration: 248.151347ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.946689Z","caller":"traceutil/trace.go:171","msg":"trace[1812403849] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"244.20444ms","start":"2026-06-05T22:03:49.702478Z","end":"2026-06-05T22:03:49.946683Z","steps":["trace[1812403849] 'process raft request' (duration: 243.952597ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.947227Z","caller":"traceutil/trace.go:171","msg":"trace[2002680031] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"244.679815ms","start":"2026-06-05T22:03:49.702519Z","end":"2026-06-05T22:03:49.947198Z","steps":["trace[2002680031] 'process raft request' (duration: 243.945287ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.947337Z","caller":"traceutil/trace.go:171","msg":"trace[831085917] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"243.778906ms","start":"2026-06-05T22:03:49.703539Z","end":"2026-06-05T22:03:49.947317Z","steps":["trace[831085917] 'process raft request' (duration: 243.571413ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.947738Z","caller":"traceutil/trace.go:171","msg":"trace[1860389439] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"242.296608ms","start":"2026-06-05T22:03:49.70542Z","end":"2026-06-05T22:03:49.947717Z","steps":["trace[1860389439] 'process raft request' (duration: 241.839832ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.948399Z","caller":"traceutil/trace.go:171","msg":"trace[769099849] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"240.958852ms","start":"2026-06-05T22:03:49.707409Z","end":"2026-06-05T22:03:49.948368Z","steps":["trace[769099849] 'process raft request' (duration: 240.195212ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.94875Z","caller":"traceutil/trace.go:171","msg":"trace[1876810153] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"232.644834ms","start":"2026-06-05T22:03:49.716094Z","end":"2026-06-05T22:03:49.948739Z","steps":["trace[1876810153] 'process raft request' (duration: 231.901076ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.948865Z","caller":"traceutil/trace.go:171","msg":"trace[357304821] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"217.591846ms","start":"2026-06-05T22:03:49.731256Z","end":"2026-06-05T22:03:49.948847Z","steps":["trace[357304821] 'process raft request' (duration: 217.208892ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.949031Z","caller":"traceutil/trace.go:171","msg":"trace[1792377285] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"199.237018ms","start":"2026-06-05T22:03:49.749785Z","end":"2026-06-05T22:03:49.949022Z","steps":["trace[1792377285] 'process raft request' (duration: 198.765613ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:51.380225Z","caller":"traceutil/trace.go:171","msg":"trace[1703952241] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"315.480468ms","start":"2026-06-05T22:03:51.064728Z","end":"2026-06-05T22:03:51.380208Z","steps":["trace[1703952241] 'process raft request' (duration: 315.343097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:51.380569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:51.064708Z","time spent":"315.578349ms","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T22:03:51.395428Z","caller":"traceutil/trace.go:171","msg":"trace[1747468721] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"322.011814ms","start":"2026-06-05T22:03:51.073403Z","end":"2026-06-05T22:03:51.395415Z","steps":["trace[1747468721] 'process raft request' (duration: 321.796272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:51.396287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:51.073377Z","time spent":"322.699014ms","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:03:51.399962Z","caller":"traceutil/trace.go:171","msg":"trace[1573482396] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"212.961186ms","start":"2026-06-05T22:03:51.186943Z","end":"2026-06-05T22:03:51.399904Z","steps":["trace[1573482396] 'read index received' (duration: 193.048533ms)","trace[1573482396] 'applied index is now lower than readState.Index' (duration: 19.911403ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:03:51.400095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.137598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T22:03:51.400159Z","caller":"traceutil/trace.go:171","msg":"trace[142023265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"213.245639ms","start":"2026-06-05T22:03:51.1869Z","end":"2026-06-05T22:03:51.400146Z","steps":["trace[142023265] 'agreement among raft nodes before linearized reading' (duration: 213.123368ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:51.400332Z","caller":"traceutil/trace.go:171","msg":"trace[991775680] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"161.799056ms","start":"2026-06-05T22:03:51.238465Z","end":"2026-06-05T22:03:51.400264Z","steps":["trace[991775680] 'process raft request' (duration: 157.931392ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:56.429206Z","caller":"traceutil/trace.go:171","msg":"trace[1856787761] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"142.300582ms","start":"2026-06-05T22:03:56.286882Z","end":"2026-06-05T22:03:56.429183Z","steps":["trace[1856787761] 'process raft request' (duration: 134.008086ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:56.668712Z","caller":"traceutil/trace.go:171","msg":"trace[54164166] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"153.903744ms","start":"2026-06-05T22:03:56.514791Z","end":"2026-06-05T22:03:56.668694Z","steps":["trace[54164166] 'read index received' (duration: 153.753752ms)","trace[54164166] 'applied index is now lower than readState.Index' (duration: 149.172µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:03:56.668816Z","caller":"traceutil/trace.go:171","msg":"trace[1807895361] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"185.230404ms","start":"2026-06-05T22:03:56.483548Z","end":"2026-06-05T22:03:56.668779Z","steps":["trace[1807895361] 'process raft request' (duration: 185.042252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:56.668912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.105337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b8t57\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-05T22:03:56.669265Z","caller":"traceutil/trace.go:171","msg":"trace[1916039973] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b8t57; range_end:; response_count:1; response_revision:638; }","duration":"154.505321ms","start":"2026-06-05T22:03:56.514749Z","end":"2026-06-05T22:03:56.669255Z","steps":["trace[1916039973] 'agreement among raft nodes before linearized reading' (duration: 154.081386ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:57.162058Z","caller":"traceutil/trace.go:171","msg":"trace[728514697] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"278.292913ms","start":"2026-06-05T22:03:56.883709Z","end":"2026-06-05T22:03:57.162002Z","steps":["trace[728514697] 'read index received' (duration: 246.483049ms)","trace[728514697] 'applied index is now lower than readState.Index' (duration: 31.808474ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:03:57.162169Z","caller":"traceutil/trace.go:171","msg":"trace[2109912686] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"413.433822ms","start":"2026-06-05T22:03:56.748722Z","end":"2026-06-05T22:03:57.162156Z","steps":["trace[2109912686] 'process raft request' (duration: 381.390525ms)","trace[2109912686] 'compare' (duration: 31.535731ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:03:57.162433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:56.748672Z","time spent":"413.522693ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:03:57.162601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.845459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-05T22:03:57.162678Z","caller":"traceutil/trace.go:171","msg":"trace[1789828925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"278.988831ms","start":"2026-06-05T22:03:56.883669Z","end":"2026-06-05T22:03:57.162658Z","steps":["trace[1789828925] 'agreement among raft nodes before linearized reading' (duration: 278.749009ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:57.39893Z","caller":"traceutil/trace.go:171","msg":"trace[35795198] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"304.751758ms","start":"2026-06-05T22:03:57.094139Z","end":"2026-06-05T22:03:57.398891Z","steps":["trace[35795198] 'process raft request' (duration: 275.231832ms)","trace[35795198] 'compare' (duration: 28.96335ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:03:57.398973Z","caller":"traceutil/trace.go:171","msg":"trace[466148625] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"231.635093ms","start":"2026-06-05T22:03:57.167282Z","end":"2026-06-05T22:03:57.398917Z","steps":["trace[466148625] 'process raft request' (duration: 231.538032ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:57.39897Z","caller":"traceutil/trace.go:171","msg":"trace[559737987] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"236.795343ms","start":"2026-06-05T22:03:57.162124Z","end":"2026-06-05T22:03:57.398919Z","steps":["trace[559737987] 'read index received' (duration: 207.113045ms)","trace[559737987] 'applied index is now lower than readState.Index' (duration: 29.677148ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:03:57.399102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.771919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:03:57.399235Z","caller":"traceutil/trace.go:171","msg":"trace[713739062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"367.927891ms","start":"2026-06-05T22:03:57.031271Z","end":"2026-06-05T22:03:57.399199Z","steps":["trace[713739062] 'agreement among raft nodes before linearized reading' (duration: 367.761089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:57.39927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:57.094112Z","time spent":"304.89938ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:03:57.399359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:57.031254Z","time spent":"368.060173ms","remote":"127.0.0.1:46446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T22:04:12.527465Z","caller":"traceutil/trace.go:171","msg":"trace[247108779] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"171.240686ms","start":"2026-06-05T22:04:12.356192Z","end":"2026-06-05T22:04:12.527433Z","steps":["trace[247108779] 'process raft request' (duration: 171.083074ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:29.975285Z","caller":"traceutil/trace.go:171","msg":"trace[521655308] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"184.275276ms","start":"2026-06-05T22:05:29.790984Z","end":"2026-06-05T22:05:29.97526Z","steps":["trace[521655308] 'process raft request' (duration: 184.119833ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:29.975288Z","caller":"traceutil/trace.go:171","msg":"trace[77144088] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1940; }","duration":"178.795335ms","start":"2026-06-05T22:05:29.796461Z","end":"2026-06-05T22:05:29.975256Z","steps":["trace[77144088] 'read index received' (duration: 178.783224ms)","trace[77144088] 'applied index is now lower than readState.Index' (duration: 9.611µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:05:29.97549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.002787ms","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-06-05T22:05:29.975573Z","caller":"traceutil/trace.go:171","msg":"trace[1883389108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1865; }","duration":"179.159459ms","start":"2026-06-05T22:05:29.796397Z","end":"2026-06-05T22:05:29.975556Z","steps":["trace[1883389108] 'agreement among raft nodes before linearized reading' (duration: 178.897936ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:30.051516Z","caller":"traceutil/trace.go:171","msg":"trace[439859924] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"159.649422ms","start":"2026-06-05T22:05:29.891838Z","end":"2026-06-05T22:05:30.051487Z","steps":["trace[439859924] 'process raft request' (duration: 97.499463ms)","trace[439859924] 'compare' (duration: 61.952837ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:05:30.05187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.110774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T22:05:30.051951Z","caller":"traceutil/trace.go:171","msg":"trace[1830657021] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"239.251987ms","start":"2026-06-05T22:05:29.812681Z","end":"2026-06-05T22:05:30.051933Z","steps":["trace[1830657021] 'agreement among raft nodes before linearized reading' (duration: 238.988114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:30.05196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.020341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-05T22:05:30.052006Z","caller":"traceutil/trace.go:171","msg":"trace[1312781831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1867; }","duration":"110.093681ms","start":"2026-06-05T22:05:29.941899Z","end":"2026-06-05T22:05:30.051993Z","steps":["trace[1312781831] 'agreement among raft nodes before linearized reading' (duration: 109.924999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:30.053053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.004044ms","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-06-05T22:05:30.053829Z","caller":"traceutil/trace.go:171","msg":"trace[1213049567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1867; }","duration":"114.823035ms","start":"2026-06-05T22:05:29.938975Z","end":"2026-06-05T22:05:30.053798Z","steps":["trace[1213049567] 'agreement among raft nodes before linearized reading' (duration: 113.092375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:31.467122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.095431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8109d6d0-681e-4adf-bf2d-639e6ef85b1d\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-06-05T22:05:31.467201Z","caller":"traceutil/trace.go:171","msg":"trace[1343165687] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8109d6d0-681e-4adf-bf2d-639e6ef85b1d; range_end:; response_count:1; response_revision:1880; }","duration":"177.197863ms","start":"2026-06-05T22:05:31.289988Z","end":"2026-06-05T22:05:31.467186Z","steps":["trace[1343165687] 'range keys from in-memory index tree' (duration: 176.97814ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:06:04.099606Z","caller":"traceutil/trace.go:171","msg":"trace[688529695] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"176.308522ms","start":"2026-06-05T22:06:03.923258Z","end":"2026-06-05T22:06:04.099567Z","steps":["trace[688529695] 'process raft request' (duration: 176.11696ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:06:09.561419Z","caller":"traceutil/trace.go:171","msg":"trace[1974627534] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"196.908588ms","start":"2026-06-05T22:06:09.364491Z","end":"2026-06-05T22:06:09.561399Z","steps":["trace[1974627534] 'process raft request' (duration: 196.785437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:06:10.427386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.791175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:06:10.427639Z","caller":"traceutil/trace.go:171","msg":"trace[1279309062] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"247.204856ms","start":"2026-06-05T22:06:10.180404Z","end":"2026-06-05T22:06:10.427609Z","steps":["trace[1279309062] 'process raft request' (duration: 129.909245ms)","trace[1279309062] 'compare' (duration: 116.668114ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:06:10.427707Z","caller":"traceutil/trace.go:171","msg":"trace[107917478] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2225; }","duration":"181.954495ms","start":"2026-06-05T22:06:10.245727Z","end":"2026-06-05T22:06:10.427681Z","steps":["trace[107917478] 'read index received' (duration: 64.674075ms)","trace[107917478] 'applied index is now lower than readState.Index' (duration: 117.27793ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:06:10.427877Z","caller":"traceutil/trace.go:171","msg":"trace[739859831] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"224.686254ms","start":"2026-06-05T22:06:10.203037Z","end":"2026-06-05T22:06:10.427723Z","steps":["trace[739859831] 'process raft request' (duration: 224.484332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:06:10.427944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.072976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-05T22:06:10.427989Z","caller":"traceutil/trace.go:171","msg":"trace[1227946186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2143; }","duration":"182.159657ms","start":"2026-06-05T22:06:10.245819Z","end":"2026-06-05T22:06:10.427979Z","steps":["trace[1227946186] 'agreement among raft nodes before linearized reading' (duration: 182.007416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:06:10.428113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.794503ms","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-06-05T22:06:10.428137Z","caller":"traceutil/trace.go:171","msg":"trace[1023107469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2143; }","duration":"180.818813ms","start":"2026-06-05T22:06:10.24731Z","end":"2026-06-05T22:06:10.428129Z","steps":["trace[1023107469] 'agreement among raft nodes before linearized reading' (duration: 180.746882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:06:10.428148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.40145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T22:06:10.428214Z","caller":"traceutil/trace.go:171","msg":"trace[1323744676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2143; }","duration":"182.504771ms","start":"2026-06-05T22:06:10.245693Z","end":"2026-06-05T22:06:10.428198Z","steps":["trace[1323744676] 'agreement among raft nodes before linearized reading' (duration: 182.322859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:07:34.035555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.01473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:07:34.035665Z","caller":"traceutil/trace.go:171","msg":"trace[1085449321] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2702; }","duration":"154.171752ms","start":"2026-06-05T22:07:33.88147Z","end":"2026-06-05T22:07:34.035641Z","steps":["trace[1085449321] 'count revisions from in-memory index tree' (duration: 153.849268ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:16.783771Z","caller":"traceutil/trace.go:171","msg":"trace[586511538] transaction","detail":"{read_only:false; response_revision:4391; number_of_response:1; }","duration":"178.664209ms","start":"2026-06-05T22:11:16.605068Z","end":"2026-06-05T22:11:16.783732Z","steps":["trace[586511538] 'process raft request' (duration: 178.398494ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:16.783881Z","caller":"traceutil/trace.go:171","msg":"trace[1943804047] transaction","detail":"{read_only:false; response_revision:4392; number_of_response:1; }","duration":"176.604569ms","start":"2026-06-05T22:11:16.607237Z","end":"2026-06-05T22:11:16.783842Z","steps":["trace[1943804047] 'process raft request' (duration: 176.404445ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:16.783754Z","caller":"traceutil/trace.go:171","msg":"trace[1372240169] linearizableReadLoop","detail":"{readStateIndex:4567; appliedIndex:4565; }","duration":"121.960688ms","start":"2026-06-05T22:11:16.661767Z","end":"2026-06-05T22:11:16.783727Z","steps":["trace[1372240169] 'read index received' (duration: 121.705993ms)","trace[1372240169] 'applied index is now lower than readState.Index' (duration: 253.235µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:16.784194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.413887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls-bdr6l\" ","response":"range_response_count:1 size:2427"} {"level":"info","ts":"2026-06-05T22:11:16.784275Z","caller":"traceutil/trace.go:171","msg":"trace[218071620] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls-bdr6l; range_end:; response_count:1; response_revision:4392; }","duration":"122.54388ms","start":"2026-06-05T22:11:16.661719Z","end":"2026-06-05T22:11:16.784262Z","steps":["trace[218071620] 'agreement among raft nodes before linearized reading' (duration: 122.349776ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:16.788489Z","caller":"traceutil/trace.go:171","msg":"trace[2079361296] transaction","detail":"{read_only:false; response_revision:4393; number_of_response:1; }","duration":"125.709691ms","start":"2026-06-05T22:11:16.662754Z","end":"2026-06-05T22:11:16.788463Z","steps":["trace[2079361296] 'process raft request' (duration: 124.035489ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:24.258788Z","caller":"traceutil/trace.go:171","msg":"trace[223496771] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"127.219045ms","start":"2026-06-05T22:11:24.131544Z","end":"2026-06-05T22:11:24.258763Z","steps":["trace[223496771] 'process raft request' (duration: 124.408511ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:24.258976Z","caller":"traceutil/trace.go:171","msg":"trace[1505877017] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"126.45537ms","start":"2026-06-05T22:11:24.132481Z","end":"2026-06-05T22:11:24.258936Z","steps":["trace[1505877017] 'process raft request' (duration: 126.191515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:31.325892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.4075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:11:31.326414Z","caller":"traceutil/trace.go:171","msg":"trace[390474408] linearizableReadLoop","detail":"{readStateIndex:4908; appliedIndex:4907; }","duration":"297.393827ms","start":"2026-06-05T22:11:31.029006Z","end":"2026-06-05T22:11:31.3264Z","steps":["trace[390474408] 'read index received' (duration: 28.699718ms)","trace[390474408] 'applied index is now lower than readState.Index' (duration: 268.692919ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:31.326409Z","caller":"traceutil/trace.go:171","msg":"trace[356750470] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"330.03285ms","start":"2026-06-05T22:11:30.996347Z","end":"2026-06-05T22:11:31.32638Z","steps":["trace[356750470] 'process raft request' (duration: 227.022779ms)","trace[356750470] 'compare' (duration: 102.237186ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:31.326559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:30.996322Z","time spent":"330.160722ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:31.326567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.336211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:31.32663Z","caller":"traceutil/trace.go:171","msg":"trace[1173379406] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4725; }","duration":"109.467253ms","start":"2026-06-05T22:11:31.217154Z","end":"2026-06-05T22:11:31.326621Z","steps":["trace[1173379406] 'agreement among raft nodes before linearized reading' (duration: 109.378751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:31.326662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.081168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-997c3d6f-d484-4f82-b63a-4d414aad96e8\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-06-05T22:11:31.326701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.700243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:31.326723Z","caller":"traceutil/trace.go:171","msg":"trace[1012002109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4725; }","duration":"297.750034ms","start":"2026-06-05T22:11:31.028966Z","end":"2026-06-05T22:11:31.326716Z","steps":["trace[1012002109] 'agreement among raft nodes before linearized reading' (duration: 297.52903ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:31.32672Z","caller":"traceutil/trace.go:171","msg":"trace[1828876587] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-997c3d6f-d484-4f82-b63a-4d414aad96e8; range_end:; response_count:1; response_revision:4725; }","duration":"268.19956ms","start":"2026-06-05T22:11:31.058504Z","end":"2026-06-05T22:11:31.326703Z","steps":["trace[1828876587] 'agreement among raft nodes before linearized reading' (duration: 268.026757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:31.326789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.649238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-05T22:11:31.326898Z","caller":"traceutil/trace.go:171","msg":"trace[1248915903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4725; }","duration":"156.828452ms","start":"2026-06-05T22:11:31.170049Z","end":"2026-06-05T22:11:31.326877Z","steps":["trace[1248915903] 'agreement among raft nodes before linearized reading' (duration: 156.507606ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:31.702471Z","caller":"traceutil/trace.go:171","msg":"trace[985551253] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"145.535639ms","start":"2026-06-05T22:11:31.556915Z","end":"2026-06-05T22:11:31.702451Z","steps":["trace[985551253] 'process raft request' (duration: 145.413207ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.172487Z","caller":"traceutil/trace.go:171","msg":"trace[440440366] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"208.6188ms","start":"2026-06-05T22:11:45.963847Z","end":"2026-06-05T22:11:46.172466Z","steps":["trace[440440366] 'process raft request' (duration: 208.578919ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.172761Z","caller":"traceutil/trace.go:171","msg":"trace[1571708897] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"385.747904ms","start":"2026-06-05T22:11:45.787006Z","end":"2026-06-05T22:11:46.172754Z","steps":["trace[1571708897] 'process raft request' (duration: 378.743437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.172847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:45.786989Z","time spent":"385.788754ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:46.17295Z","caller":"traceutil/trace.go:171","msg":"trace[1010743401] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5107; }","duration":"339.077745ms","start":"2026-06-05T22:11:45.833857Z","end":"2026-06-05T22:11:46.172935Z","steps":["trace[1010743401] 'read index received' (duration: 331.827524ms)","trace[1010743401] 'applied index is now lower than readState.Index' (duration: 7.248101ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:46.173053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.936487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:46.173083Z","caller":"traceutil/trace.go:171","msg":"trace[1659210455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4919; }","duration":"142.995409ms","start":"2026-06-05T22:11:46.030079Z","end":"2026-06-05T22:11:46.173074Z","steps":["trace[1659210455] 'agreement among raft nodes before linearized reading' (duration: 142.946238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.173096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.241578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-06-05T22:11:46.173102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.237879ms","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-06-05T22:11:46.173126Z","caller":"traceutil/trace.go:171","msg":"trace[51351002] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4919; }","duration":"256.322969ms","start":"2026-06-05T22:11:45.916795Z","end":"2026-06-05T22:11:46.173118Z","steps":["trace[51351002] 'agreement among raft nodes before linearized reading' (duration: 256.221768ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.173147Z","caller":"traceutil/trace.go:171","msg":"trace[140100176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4919; }","duration":"339.320471ms","start":"2026-06-05T22:11:45.833813Z","end":"2026-06-05T22:11:46.173134Z","steps":["trace[140100176] 'agreement among raft nodes before linearized reading' (duration: 339.186678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.173185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:45.833793Z","time spent":"339.383941ms","remote":"127.0.0.1:46706","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-06-05T22:11:46.17323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.408584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T22:11:46.173254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.882347ms","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-06-05T22:11:46.173347Z","caller":"traceutil/trace.go:171","msg":"trace[1459487081] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4919; }","duration":"187.588468ms","start":"2026-06-05T22:11:45.985735Z","end":"2026-06-05T22:11:46.173324Z","steps":["trace[1459487081] 'agreement among raft nodes before linearized reading' (duration: 187.370704ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.17336Z","caller":"traceutil/trace.go:171","msg":"trace[1235117280] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4919; }","duration":"247.00935ms","start":"2026-06-05T22:11:45.926325Z","end":"2026-06-05T22:11:46.173334Z","steps":["trace[1235117280] 'agreement among raft nodes before linearized reading' (duration: 246.798877ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.535648Z","caller":"traceutil/trace.go:171","msg":"trace[449194772] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"282.615173ms","start":"2026-06-05T22:11:46.253004Z","end":"2026-06-05T22:11:46.535619Z","steps":["trace[449194772] 'process raft request' (duration: 281.993922ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.535129Z","caller":"traceutil/trace.go:171","msg":"trace[2103539154] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"282.661115ms","start":"2026-06-05T22:11:46.252405Z","end":"2026-06-05T22:11:46.535066Z","steps":["trace[2103539154] 'process raft request' (duration: 282.358259ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.536323Z","caller":"traceutil/trace.go:171","msg":"trace[825135556] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"283.124173ms","start":"2026-06-05T22:11:46.253185Z","end":"2026-06-05T22:11:46.53631Z","steps":["trace[825135556] 'process raft request' (duration: 282.846548ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.536409Z","caller":"traceutil/trace.go:171","msg":"trace[1258609299] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"283.216854ms","start":"2026-06-05T22:11:46.253156Z","end":"2026-06-05T22:11:46.536373Z","steps":["trace[1258609299] 'process raft request' (duration: 281.937511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.538154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.903376ms","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-06-05T22:11:46.538211Z","caller":"traceutil/trace.go:171","msg":"trace[168619013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4927; }","duration":"154.032027ms","start":"2026-06-05T22:11:46.384164Z","end":"2026-06-05T22:11:46.538196Z","steps":["trace[168619013] 'agreement among raft nodes before linearized reading' (duration: 153.835754ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.53613Z","caller":"traceutil/trace.go:171","msg":"trace[212446337] linearizableReadLoop","detail":"{readStateIndex:5117; appliedIndex:5113; }","duration":"151.905248ms","start":"2026-06-05T22:11:46.384209Z","end":"2026-06-05T22:11:46.536114Z","steps":["trace[212446337] 'read index received' (duration: 150.602565ms)","trace[212446337] 'applied index is now lower than readState.Index' (duration: 1.301803ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:46.555924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.487885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-4wlr8.18b64eedc1932f6f\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-06-05T22:11:46.556003Z","caller":"traceutil/trace.go:171","msg":"trace[273553610] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-4wlr8.18b64eedc1932f6f; range_end:; response_count:1; response_revision:4928; }","duration":"149.578866ms","start":"2026-06-05T22:11:46.406406Z","end":"2026-06-05T22:11:46.555985Z","steps":["trace[273553610] 'agreement among raft nodes before linearized reading' (duration: 149.372582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.556857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.81752ms","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-06-05T22:11:46.556903Z","caller":"traceutil/trace.go:171","msg":"trace[358047360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4928; }","duration":"145.89806ms","start":"2026-06-05T22:11:46.410994Z","end":"2026-06-05T22:11:46.556892Z","steps":["trace[358047360] 'agreement among raft nodes before linearized reading' (duration: 145.780727ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.74377Z","caller":"traceutil/trace.go:171","msg":"trace[1377491357] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"185.518089ms","start":"2026-06-05T22:11:46.558216Z","end":"2026-06-05T22:11:46.743734Z","steps":["trace[1377491357] 'process raft request' (duration: 105.924334ms)","trace[1377491357] 'compare' (duration: 79.26285ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:46.744004Z","caller":"traceutil/trace.go:171","msg":"trace[1235913440] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"181.608269ms","start":"2026-06-05T22:11:46.562374Z","end":"2026-06-05T22:11:46.743983Z","steps":["trace[1235913440] 'process raft request' (duration: 181.255263ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.744082Z","caller":"traceutil/trace.go:171","msg":"trace[820011949] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"181.620808ms","start":"2026-06-05T22:11:46.562438Z","end":"2026-06-05T22:11:46.744058Z","steps":["trace[820011949] 'process raft request' (duration: 181.284482ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:13:10.545289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-06-05T22:13:10.743695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"197.302363ms","hash":1621782675,"current-db-size-bytes":52834304,"current-db-size":"53 MB","current-db-size-in-use-bytes":29155328,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-05T22:13:10.743747Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1621782675,"revision":2896,"compact-revision":-1} {"level":"warn","ts":"2026-06-05T22:15:27.651832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.683773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-05T22:15:27.651924Z","caller":"traceutil/trace.go:171","msg":"trace[361730052] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6538; }","duration":"151.820725ms","start":"2026-06-05T22:15:27.500081Z","end":"2026-06-05T22:15:27.651901Z","steps":["trace[361730052] 'range keys from in-memory index tree' (duration: 151.554211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:27.651935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.365603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-06-05T22:15:27.652025Z","caller":"traceutil/trace.go:171","msg":"trace[1732357787] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6538; }","duration":"159.517195ms","start":"2026-06-05T22:15:27.492488Z","end":"2026-06-05T22:15:27.652005Z","steps":["trace[1732357787] 'range keys from in-memory index tree' (duration: 159.15159ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:27.803911Z","caller":"traceutil/trace.go:171","msg":"trace[575658899] transaction","detail":"{read_only:false; response_revision:6539; number_of_response:1; }","duration":"147.86499ms","start":"2026-06-05T22:15:27.656014Z","end":"2026-06-05T22:15:27.80388Z","steps":["trace[575658899] 'process raft request' (duration: 147.692137ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:27.968928Z","caller":"traceutil/trace.go:171","msg":"trace[633139433] transaction","detail":"{read_only:false; response_revision:6540; number_of_response:1; }","duration":"136.104641ms","start":"2026-06-05T22:15:27.832802Z","end":"2026-06-05T22:15:27.968907Z","steps":["trace[633139433] 'process raft request' (duration: 135.98ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:27.974384Z","caller":"traceutil/trace.go:171","msg":"trace[108923863] transaction","detail":"{read_only:false; response_revision:6541; number_of_response:1; }","duration":"140.359542ms","start":"2026-06-05T22:15:27.833996Z","end":"2026-06-05T22:15:27.974356Z","steps":["trace[108923863] 'process raft request' (duration: 140.046857ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:33.934091Z","caller":"traceutil/trace.go:171","msg":"trace[1728535029] transaction","detail":"{read_only:false; response_revision:6571; number_of_response:1; }","duration":"413.390389ms","start":"2026-06-05T22:15:33.520677Z","end":"2026-06-05T22:15:33.934067Z","steps":["trace[1728535029] 'process raft request' (duration: 413.262217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:33.934284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:33.520656Z","time spent":"413.553772ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6485,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:15:33.934452Z","caller":"traceutil/trace.go:171","msg":"trace[1145388819] linearizableReadLoop","detail":"{readStateIndex:6815; appliedIndex:6815; }","duration":"319.526255ms","start":"2026-06-05T22:15:33.614914Z","end":"2026-06-05T22:15:33.93444Z","steps":["trace[1145388819] 'read index received' (duration: 319.519605ms)","trace[1145388819] 'applied index is now lower than readState.Index' (duration: 4.84µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:15:33.934851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.741328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:15:33.934915Z","caller":"traceutil/trace.go:171","msg":"trace[1815394632] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:6571; }","duration":"139.879229ms","start":"2026-06-05T22:15:33.795027Z","end":"2026-06-05T22:15:33.934906Z","steps":["trace[1815394632] 'agreement among raft nodes before linearized reading' (duration: 139.733787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:33.934979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.30597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:15:33.93508Z","caller":"traceutil/trace.go:171","msg":"trace[1118829290] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:6571; }","duration":"167.445471ms","start":"2026-06-05T22:15:33.76761Z","end":"2026-06-05T22:15:33.935056Z","steps":["trace[1118829290] 'agreement among raft nodes before linearized reading' (duration: 167.286459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:33.935255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.371626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-05T22:15:33.935308Z","caller":"traceutil/trace.go:171","msg":"trace[1958055854] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:6571; }","duration":"320.501639ms","start":"2026-06-05T22:15:33.614798Z","end":"2026-06-05T22:15:33.9353Z","steps":["trace[1958055854] 'agreement among raft nodes before linearized reading' (duration: 319.726218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:33.936256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:33.61478Z","time spent":"321.465083ms","remote":"127.0.0.1:46474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.132\" "} {"level":"warn","ts":"2026-06-05T22:15:33.935925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.310208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-05T22:15:33.936498Z","caller":"traceutil/trace.go:171","msg":"trace[86897353] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6571; }","duration":"110.909496ms","start":"2026-06-05T22:15:33.825576Z","end":"2026-06-05T22:15:33.936485Z","steps":["trace[86897353] 'agreement among raft nodes before linearized reading' (duration: 110.238217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:33.935985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.031611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-06-05T22:15:33.936831Z","caller":"traceutil/trace.go:171","msg":"trace[1950098051] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6571; }","duration":"118.08341ms","start":"2026-06-05T22:15:33.818738Z","end":"2026-06-05T22:15:33.936821Z","steps":["trace[1950098051] 'agreement among raft nodes before linearized reading' (duration: 115.94375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:33.93707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.18152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-06-05T22:15:33.938155Z","caller":"traceutil/trace.go:171","msg":"trace[477339232] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6571; }","duration":"270.310295ms","start":"2026-06-05T22:15:33.667815Z","end":"2026-06-05T22:15:33.938125Z","steps":["trace[477339232] 'agreement among raft nodes before linearized reading' (duration: 266.821235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.517236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.488671ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-05T22:15:34.517499Z","caller":"traceutil/trace.go:171","msg":"trace[2071400365] linearizableReadLoop","detail":"{readStateIndex:6817; appliedIndex:6815; }","duration":"523.982399ms","start":"2026-06-05T22:15:33.993496Z","end":"2026-06-05T22:15:34.517478Z","steps":["trace[2071400365] 'read index received' (duration: 24.261844ms)","trace[2071400365] 'applied index is now lower than readState.Index' (duration: 499.719365ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:15:34.517589Z","caller":"traceutil/trace.go:171","msg":"trace[1120054754] transaction","detail":"{read_only:false; response_revision:6572; number_of_response:1; }","duration":"576.116881ms","start":"2026-06-05T22:15:33.94146Z","end":"2026-06-05T22:15:34.517577Z","steps":["trace[1120054754] 'process raft request' (duration: 575.885718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.517581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:33.937841Z","time spent":"579.732762ms","remote":"127.0.0.1:46474","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-05T22:15:34.517724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:33.941438Z","time spent":"576.186272ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:15:34.517807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.321434ms","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-06-05T22:15:34.517836Z","caller":"traceutil/trace.go:171","msg":"trace[776690955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6572; }","duration":"526.443223ms","start":"2026-06-05T22:15:33.991384Z","end":"2026-06-05T22:15:34.517827Z","steps":["trace[776690955] 'agreement among raft nodes before linearized reading' (duration: 526.347642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.517866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:33.991362Z","time spent":"526.499285ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T22:15:34.554206Z","caller":"traceutil/trace.go:171","msg":"trace[1516426652] transaction","detail":"{read_only:false; response_revision:6573; number_of_response:1; }","duration":"429.999154ms","start":"2026-06-05T22:15:34.124185Z","end":"2026-06-05T22:15:34.554184Z","steps":["trace[1516426652] 'process raft request' (duration: 429.868382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.554341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.12415Z","time spent":"430.118225ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:15:34.780743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.767188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:15:34.781167Z","caller":"traceutil/trace.go:171","msg":"trace[1023231916] linearizableReadLoop","detail":"{readStateIndex:6819; appliedIndex:6817; }","duration":"263.602608ms","start":"2026-06-05T22:15:34.517539Z","end":"2026-06-05T22:15:34.781142Z","steps":["trace[1023231916] 'read index received' (duration: 36.5119ms)","trace[1023231916] 'applied index is now lower than readState.Index' (duration: 227.089428ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:15:34.781564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.805299ms","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-06-05T22:15:34.781633Z","caller":"traceutil/trace.go:171","msg":"trace[66428871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6575; }","duration":"787.937101ms","start":"2026-06-05T22:15:33.99368Z","end":"2026-06-05T22:15:34.781617Z","steps":["trace[66428871] 'agreement among raft nodes before linearized reading' (duration: 787.772099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.781672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:33.993667Z","time spent":"787.995862ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T22:15:34.781339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.995861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-05T22:15:34.781809Z","caller":"traceutil/trace.go:171","msg":"trace[1177635884] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6575; }","duration":"719.482698ms","start":"2026-06-05T22:15:34.062303Z","end":"2026-06-05T22:15:34.781785Z","steps":["trace[1177635884] 'agreement among raft nodes before linearized reading' (duration: 718.874719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.781927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.062293Z","time spent":"719.60763ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-05T22:15:34.781361Z","caller":"traceutil/trace.go:171","msg":"trace[661766890] transaction","detail":"{read_only:false; response_revision:6574; number_of_response:1; }","duration":"509.557984ms","start":"2026-06-05T22:15:34.271658Z","end":"2026-06-05T22:15:34.781216Z","steps":["trace[661766890] 'process raft request' (duration: 345.179087ms)","trace[661766890] 'compare' (duration: 163.639496ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:15:34.781428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.487832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:15:34.782464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.271629Z","time spent":"510.496707ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:15:34.782331Z","caller":"traceutil/trace.go:171","msg":"trace[1379253119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6575; }","duration":"752.416156ms","start":"2026-06-05T22:15:34.029894Z","end":"2026-06-05T22:15:34.78231Z","steps":["trace[1379253119] 'agreement among raft nodes before linearized reading' (duration: 751.483893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.782691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.029878Z","time spent":"752.74157ms","remote":"127.0.0.1:46446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T22:15:34.781516Z","caller":"traceutil/trace.go:171","msg":"trace[725311091] transaction","detail":"{read_only:false; response_revision:6575; number_of_response:1; }","duration":"262.728266ms","start":"2026-06-05T22:15:34.518763Z","end":"2026-06-05T22:15:34.781491Z","steps":["trace[725311091] 'process raft request' (duration: 262.282829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.781477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.075476ms","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-06-05T22:15:34.783294Z","caller":"traceutil/trace.go:171","msg":"trace[140962964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6575; }","duration":"778.830752ms","start":"2026-06-05T22:15:34.004348Z","end":"2026-06-05T22:15:34.783178Z","steps":["trace[140962964] 'agreement among raft nodes before linearized reading' (duration: 777.052786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.783359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.004338Z","time spent":"779.000894ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T22:15:34.867034Z","caller":"traceutil/trace.go:171","msg":"trace[2040671018] transaction","detail":"{read_only:false; response_revision:6576; number_of_response:1; }","duration":"312.715265ms","start":"2026-06-05T22:15:34.554294Z","end":"2026-06-05T22:15:34.867009Z","steps":["trace[2040671018] 'process raft request' (duration: 312.481132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:34.867266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.55427Z","time spent":"312.916758ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:15:34.867586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.850197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T22:15:34.867798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.815263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-05T22:15:34.867875Z","caller":"traceutil/trace.go:171","msg":"trace[1097145343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6576; }","duration":"273.925115ms","start":"2026-06-05T22:15:34.593931Z","end":"2026-06-05T22:15:34.867856Z","steps":["trace[1097145343] 'agreement among raft nodes before linearized reading' (duration: 273.647851ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:34.867688Z","caller":"traceutil/trace.go:171","msg":"trace[898478939] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:6576; }","duration":"262.978169ms","start":"2026-06-05T22:15:34.604685Z","end":"2026-06-05T22:15:34.867663Z","steps":["trace[898478939] 'agreement among raft nodes before linearized reading' (duration: 262.331789ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:35.187256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.626592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:15:35.187726Z","caller":"traceutil/trace.go:171","msg":"trace[488282481] transaction","detail":"{read_only:false; response_revision:6578; number_of_response:1; }","duration":"400.35626ms","start":"2026-06-05T22:15:34.787338Z","end":"2026-06-05T22:15:35.187694Z","steps":["trace[488282481] 'process raft request' (duration: 400.031886ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:35.187758Z","caller":"traceutil/trace.go:171","msg":"trace[155496485] transaction","detail":"{read_only:false; response_revision:6577; number_of_response:1; }","duration":"401.172341ms","start":"2026-06-05T22:15:34.786553Z","end":"2026-06-05T22:15:35.187725Z","steps":["trace[155496485] 'process raft request' (duration: 206.998983ms)","trace[155496485] 'compare' (duration: 193.475939ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:15:35.187804Z","caller":"traceutil/trace.go:171","msg":"trace[902828121] transaction","detail":"{read_only:false; response_revision:6579; number_of_response:1; }","duration":"397.949745ms","start":"2026-06-05T22:15:34.789845Z","end":"2026-06-05T22:15:35.187794Z","steps":["trace[902828121] 'process raft request' (duration: 397.675942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:35.187885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.789819Z","time spent":"398.017977ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:15:35.187875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.786541Z","time spent":"401.274593ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:15:35.187904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.787324Z","time spent":"400.480372ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:15:35.187756Z","caller":"traceutil/trace.go:171","msg":"trace[1610603809] transaction","detail":"{read_only:false; response_revision:6581; number_of_response:1; }","duration":"242.69533ms","start":"2026-06-05T22:15:34.945042Z","end":"2026-06-05T22:15:35.187737Z","steps":["trace[1610603809] 'process raft request' (duration: 242.636149ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:35.18814Z","caller":"traceutil/trace.go:171","msg":"trace[1747448244] linearizableReadLoop","detail":"{readStateIndex:6824; appliedIndex:6821; }","duration":"321.149305ms","start":"2026-06-05T22:15:34.866972Z","end":"2026-06-05T22:15:35.188121Z","steps":["trace[1747448244] 'read index received' (duration: 126.600041ms)","trace[1747448244] 'applied index is now lower than readState.Index' (duration: 194.547274ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:15:35.188313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.948027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-05T22:15:35.188349Z","caller":"traceutil/trace.go:171","msg":"trace[1548093264] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:6581; }","duration":"402.998578ms","start":"2026-06-05T22:15:34.785341Z","end":"2026-06-05T22:15:35.188339Z","steps":["trace[1548093264] 'agreement among raft nodes before linearized reading' (duration: 402.833516ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:35.188366Z","caller":"traceutil/trace.go:171","msg":"trace[388156078] transaction","detail":"{read_only:false; response_revision:6580; number_of_response:1; }","duration":"316.468768ms","start":"2026-06-05T22:15:34.871872Z","end":"2026-06-05T22:15:35.188341Z","steps":["trace[388156078] 'process raft request' (duration: 315.751008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:35.188429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.009823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:15:35.18846Z","caller":"traceutil/trace.go:171","msg":"trace[2065501840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6581; }","duration":"402.058044ms","start":"2026-06-05T22:15:34.786394Z","end":"2026-06-05T22:15:35.188453Z","steps":["trace[2065501840] 'agreement among raft nodes before linearized reading' (duration: 402.014963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:35.188495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.786384Z","time spent":"402.104864ms","remote":"127.0.0.1:46440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T22:15:35.188492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.871854Z","time spent":"316.565219ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:15:35.188392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:34.785325Z","time spent":"403.059639ms","remote":"127.0.0.1:46632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-06-05T22:15:35.548236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.584352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:15:35.548336Z","caller":"traceutil/trace.go:171","msg":"trace[72503835] linearizableReadLoop","detail":"{readStateIndex:6827; appliedIndex:6826; }","duration":"351.271042ms","start":"2026-06-05T22:15:35.19705Z","end":"2026-06-05T22:15:35.548321Z","steps":["trace[72503835] 'read index received' (duration: 185.470616ms)","trace[72503835] 'applied index is now lower than readState.Index' (duration: 165.799386ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:15:35.548438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.385113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:15:35.548471Z","caller":"traceutil/trace.go:171","msg":"trace[1715504431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6582; }","duration":"351.445785ms","start":"2026-06-05T22:15:35.197016Z","end":"2026-06-05T22:15:35.548462Z","steps":["trace[1715504431] 'agreement among raft nodes before linearized reading' (duration: 351.387874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:35.548502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:35.197002Z","time spent":"351.492735ms","remote":"127.0.0.1:46446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T22:15:35.548554Z","caller":"traceutil/trace.go:171","msg":"trace[134204971] transaction","detail":"{read_only:false; response_revision:6582; number_of_response:1; }","duration":"353.186648ms","start":"2026-06-05T22:15:35.195162Z","end":"2026-06-05T22:15:35.548349Z","steps":["trace[134204971] 'process raft request' (duration: 187.408543ms)","trace[134204971] 'compare' (duration: 165.462461ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:15:35.548791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:35.195146Z","time spent":"353.524494ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:15:35.548826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.789469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-06-05T22:15:35.549045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.791068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-06-05T22:15:35.549017Z","caller":"traceutil/trace.go:171","msg":"trace[1625596124] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:6582; }","duration":"290.016121ms","start":"2026-06-05T22:15:35.258987Z","end":"2026-06-05T22:15:35.549003Z","steps":["trace[1625596124] 'agreement among raft nodes before linearized reading' (duration: 289.743297ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:35.549085Z","caller":"traceutil/trace.go:171","msg":"trace[538664299] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6582; }","duration":"113.903109ms","start":"2026-06-05T22:15:35.43517Z","end":"2026-06-05T22:15:35.549073Z","steps":["trace[538664299] 'agreement among raft nodes before linearized reading' (duration: 113.823628ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:35.74316Z","caller":"traceutil/trace.go:171","msg":"trace[1562450186] transaction","detail":"{read_only:false; response_revision:6584; number_of_response:1; }","duration":"185.084459ms","start":"2026-06-05T22:15:35.558038Z","end":"2026-06-05T22:15:35.743123Z","steps":["trace[1562450186] 'process raft request' (duration: 99.347292ms)","trace[1562450186] 'compare' (duration: 85.335692ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:15:36.819448Z","caller":"traceutil/trace.go:171","msg":"trace[1947431050] transaction","detail":"{read_only:false; response_revision:6585; number_of_response:1; }","duration":"261.575805ms","start":"2026-06-05T22:15:36.557838Z","end":"2026-06-05T22:15:36.819413Z","steps":["trace[1947431050] 'process raft request' (duration: 261.442533ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:36.957147Z","caller":"traceutil/trace.go:171","msg":"trace[461692506] transaction","detail":"{read_only:false; response_revision:6587; number_of_response:1; }","duration":"169.165393ms","start":"2026-06-05T22:15:36.78796Z","end":"2026-06-05T22:15:36.957126Z","steps":["trace[461692506] 'process raft request' (duration: 169.111532ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:36.957493Z","caller":"traceutil/trace.go:171","msg":"trace[441918599] transaction","detail":"{read_only:false; response_revision:6588; number_of_response:1; }","duration":"129.224156ms","start":"2026-06-05T22:15:36.828256Z","end":"2026-06-05T22:15:36.95748Z","steps":["trace[441918599] 'process raft request' (duration: 129.099874ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:36.958335Z","caller":"traceutil/trace.go:171","msg":"trace[1651336814] transaction","detail":"{read_only:false; response_revision:6586; number_of_response:1; }","duration":"399.377662ms","start":"2026-06-05T22:15:36.558942Z","end":"2026-06-05T22:15:36.95832Z","steps":["trace[1651336814] 'process raft request' (duration: 397.85285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:15:36.958441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:15:36.558922Z","time spent":"399.453314ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:15:51.452778Z","caller":"traceutil/trace.go:171","msg":"trace[1192136968] transaction","detail":"{read_only:false; response_revision:6715; number_of_response:1; }","duration":"197.67534ms","start":"2026-06-05T22:15:51.255077Z","end":"2026-06-05T22:15:51.452753Z","steps":["trace[1192136968] 'process raft request' (duration: 141.368815ms)","trace[1192136968] 'compare' (duration: 55.784388ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:15:51.483022Z","caller":"traceutil/trace.go:171","msg":"trace[2098487571] linearizableReadLoop","detail":"{readStateIndex:6967; appliedIndex:6965; }","duration":"102.70331ms","start":"2026-06-05T22:15:51.380302Z","end":"2026-06-05T22:15:51.483005Z","steps":["trace[2098487571] 'read index received' (duration: 16.153308ms)","trace[2098487571] 'applied index is now lower than readState.Index' (duration: 86.549452ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:15:51.483181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.860672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:15:51.483223Z","caller":"traceutil/trace.go:171","msg":"trace[981283618] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:6716; }","duration":"102.962594ms","start":"2026-06-05T22:15:51.380252Z","end":"2026-06-05T22:15:51.483215Z","steps":["trace[981283618] 'agreement among raft nodes before linearized reading' (duration: 102.865683ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:51.48346Z","caller":"traceutil/trace.go:171","msg":"trace[130024504] transaction","detail":"{read_only:false; response_revision:6716; number_of_response:1; }","duration":"186.837828ms","start":"2026-06-05T22:15:51.296616Z","end":"2026-06-05T22:15:51.483454Z","steps":["trace[130024504] 'process raft request' (duration: 186.305781ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:57.386499Z","caller":"traceutil/trace.go:171","msg":"trace[1715072417] transaction","detail":"{read_only:false; response_revision:6744; number_of_response:1; }","duration":"191.332675ms","start":"2026-06-05T22:15:57.195147Z","end":"2026-06-05T22:15:57.38648Z","steps":["trace[1715072417] 'process raft request' (duration: 178.738168ms)","trace[1715072417] 'compare' (duration: 11.803606ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:15:57.588359Z","caller":"traceutil/trace.go:171","msg":"trace[1306967227] transaction","detail":"{read_only:false; response_revision:6746; number_of_response:1; }","duration":"108.876353ms","start":"2026-06-05T22:15:57.479461Z","end":"2026-06-05T22:15:57.588337Z","steps":["trace[1306967227] 'process raft request' (duration: 41.372603ms)","trace[1306967227] 'compare' (duration: 67.159435ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:15:57.779675Z","caller":"traceutil/trace.go:171","msg":"trace[1943507900] transaction","detail":"{read_only:false; response_revision:6748; number_of_response:1; }","duration":"110.930642ms","start":"2026-06-05T22:15:57.668714Z","end":"2026-06-05T22:15:57.779645Z","steps":["trace[1943507900] 'process raft request' (duration: 85.132018ms)","trace[1943507900] 'compare' (duration: 25.48439ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:18:10.553439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5592} {"level":"info","ts":"2026-06-05T22:18:10.650422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5592,"took":"95.262227ms","hash":2988295427,"current-db-size-bytes":52834304,"current-db-size":"53 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-05T22:18:10.650472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2988295427,"revision":5592,"compact-revision":2896} {"level":"info","ts":"2026-06-05T22:19:16.296533Z","caller":"traceutil/trace.go:171","msg":"trace[1868194280] linearizableReadLoop","detail":"{readStateIndex:8067; appliedIndex:8066; }","duration":"110.822572ms","start":"2026-06-05T22:19:16.185685Z","end":"2026-06-05T22:19:16.296508Z","steps":["trace[1868194280] 'read index received' (duration: 110.338739ms)","trace[1868194280] 'applied index is now lower than readState.Index' (duration: 482.213µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:19:16.296668Z","caller":"traceutil/trace.go:171","msg":"trace[746701293] transaction","detail":"{read_only:false; response_revision:7774; number_of_response:1; }","duration":"115.185943ms","start":"2026-06-05T22:19:16.181461Z","end":"2026-06-05T22:19:16.296647Z","steps":["trace[746701293] 'process raft request' (duration: 114.500163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:19:16.296824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.122211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-94grm\" ","response":"range_response_count:1 size:5487"} {"level":"info","ts":"2026-06-05T22:19:16.296896Z","caller":"traceutil/trace.go:171","msg":"trace[2033260805] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-94grm; range_end:; response_count:1; response_revision:7774; }","duration":"111.220774ms","start":"2026-06-05T22:19:16.185649Z","end":"2026-06-05T22:19:16.29687Z","steps":["trace[2033260805] 'agreement among raft nodes before linearized reading' (duration: 111.000378ms)"],"step_count":1}