{"level":"warn","ts":"2026-08-14T23:09:43.111022Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T23:09:43.111132Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-14T23:09:43.111244Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T23:09:43.111265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-14T23:09:43.111292Z","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-08-14T23:09:43.112428Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-08-14T23:09:43.11257Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-14T23:09:43.11607Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.207454ms"} {"level":"info","ts":"2026-08-14T23:09:43.121931Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-08-14T23:09:43.122027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T23:09:43.122057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-08-14T23:09:43.122077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T23:09:43.122112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-08-14T23:09:43.122173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-08-14T23:09:43.131932Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T23:09:43.134287Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T23:09:43.136068Z","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-08-14T23:09:43.138156Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T23:09:43.139302Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T23:09:43.140104Z","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-08-14T23:09:43.14018Z","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-08-14T23:09:43.140197Z","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-08-14T23:09:43.140245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-08-14T23:09:43.140535Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-14T23:09:43.142854Z","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-08-14T23:09:43.143145Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T23:09:43.143182Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T23:09:43.143187Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-14T23:09:43.143245Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-14T23:09:43.322476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T23:09:43.32259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T23:09:43.322657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-08-14T23:09:43.322699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-08-14T23:09:43.322718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-14T23:09:43.322734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-08-14T23:09:43.322787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-14T23:09:43.323778Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:09:43.324607Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T23:09:43.324812Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T23:09:43.324833Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T23:09:43.325466Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:09:43.325458Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T23:09:43.325651Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T23:09:43.325603Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:09:43.325846Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:09:43.328964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T23:09:43.32932Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-08-14T23:10:18.855281Z","caller":"traceutil/trace.go:171","msg":"trace[30746698] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"117.009641ms","start":"2026-08-14T23:10:18.738226Z","end":"2026-08-14T23:10:18.855235Z","steps":["trace[30746698] 'process raft request' (duration: 116.757729ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:18.855641Z","caller":"traceutil/trace.go:171","msg":"trace[868548634] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"102.439465ms","start":"2026-08-14T23:10:18.753013Z","end":"2026-08-14T23:10:18.855453Z","steps":["trace[868548634] 'process raft request' (duration: 102.384993ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:18.855825Z","caller":"traceutil/trace.go:171","msg":"trace[1873005507] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"105.733071ms","start":"2026-08-14T23:10:18.750076Z","end":"2026-08-14T23:10:18.855809Z","steps":["trace[1873005507] 'process raft request' (duration: 105.201205ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:18.855905Z","caller":"traceutil/trace.go:171","msg":"trace[1735168515] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"105.105692ms","start":"2026-08-14T23:10:18.750791Z","end":"2026-08-14T23:10:18.855897Z","steps":["trace[1735168515] 'process raft request' (duration: 104.525454ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:18.855986Z","caller":"traceutil/trace.go:171","msg":"trace[1735523647] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"103.319767ms","start":"2026-08-14T23:10:18.752659Z","end":"2026-08-14T23:10:18.855978Z","steps":["trace[1735523647] 'process raft request' (duration: 102.691667ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:18.855867Z","caller":"traceutil/trace.go:171","msg":"trace[1133772461] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"107.19713ms","start":"2026-08-14T23:10:18.748518Z","end":"2026-08-14T23:10:18.855715Z","steps":["trace[1133772461] 'process raft request' (duration: 106.671835ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:18.856032Z","caller":"traceutil/trace.go:171","msg":"trace[801077574] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"116.32298ms","start":"2026-08-14T23:10:18.739626Z","end":"2026-08-14T23:10:18.855949Z","steps":["trace[801077574] 'process raft request' (duration: 115.463598ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:18.856239Z","caller":"traceutil/trace.go:171","msg":"trace[1317953334] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"107.857911ms","start":"2026-08-14T23:10:18.748366Z","end":"2026-08-14T23:10:18.856224Z","steps":["trace[1317953334] 'process raft request' (duration: 106.7856ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:23.104004Z","caller":"traceutil/trace.go:171","msg":"trace[271935970] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"104.302112ms","start":"2026-08-14T23:10:22.99951Z","end":"2026-08-14T23:10:23.103812Z","steps":["trace[271935970] 'process raft request' (duration: 19.149578ms)","trace[271935970] 'compare' (duration: 84.85692ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:10:25.279933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.707319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T23:10:25.280045Z","caller":"traceutil/trace.go:171","msg":"trace[1443536754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"132.939051ms","start":"2026-08-14T23:10:25.147084Z","end":"2026-08-14T23:10:25.280023Z","steps":["trace[1443536754] 'range keys from in-memory index tree' (duration: 132.5082ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:25.28888Z","caller":"traceutil/trace.go:171","msg":"trace[368494184] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"116.844484ms","start":"2026-08-14T23:10:25.172011Z","end":"2026-08-14T23:10:25.288855Z","steps":["trace[368494184] 'process raft request' (duration: 116.698647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:10:25.809082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.344672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:10:25.8092Z","caller":"traceutil/trace.go:171","msg":"trace[954842930] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"340.790158ms","start":"2026-08-14T23:10:25.46838Z","end":"2026-08-14T23:10:25.80917Z","steps":["trace[954842930] 'read index received' (duration: 53.833µs)","trace[954842930] 'applied index is now lower than readState.Index' (duration: 340.734854ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:10:25.80927Z","caller":"traceutil/trace.go:171","msg":"trace[931004718] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"430.772162ms","start":"2026-08-14T23:10:25.378433Z","end":"2026-08-14T23:10:25.809205Z","steps":["trace[931004718] 'process raft request' (duration: 75.675584ms)","trace[931004718] 'compare' (duration: 354.080199ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:10:25.809469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:10:25.378406Z","time spent":"430.958661ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:10:25.809779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.390405ms","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-08-14T23:10:25.809836Z","caller":"traceutil/trace.go:171","msg":"trace[1183971875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"341.502291ms","start":"2026-08-14T23:10:25.468322Z","end":"2026-08-14T23:10:25.809824Z","steps":["trace[1183971875] 'agreement among raft nodes before linearized reading' (duration: 341.084162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:10:25.809988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:10:25.468296Z","time spent":"341.673669ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T23:10:25.955955Z","caller":"traceutil/trace.go:171","msg":"trace[1948957607] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"146.674694ms","start":"2026-08-14T23:10:25.809228Z","end":"2026-08-14T23:10:25.955903Z","steps":["trace[1948957607] 'read index received' (duration: 139.594336ms)","trace[1948957607] 'applied index is now lower than readState.Index' (duration: 7.079348ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:10:25.95703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.899234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:10:25.957076Z","caller":"traceutil/trace.go:171","msg":"trace[882808200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"356.987288ms","start":"2026-08-14T23:10:25.600079Z","end":"2026-08-14T23:10:25.957066Z","steps":["trace[882808200] 'agreement among raft nodes before linearized reading' (duration: 355.953359ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:25.956021Z","caller":"traceutil/trace.go:171","msg":"trace[752437369] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"212.290189ms","start":"2026-08-14T23:10:25.743678Z","end":"2026-08-14T23:10:25.955969Z","steps":["trace[752437369] 'process raft request' (duration: 205.958017ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:10:25.957152Z","caller":"traceutil/trace.go:171","msg":"trace[116931384] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"141.738539ms","start":"2026-08-14T23:10:25.814276Z","end":"2026-08-14T23:10:25.956014Z","steps":["trace[116931384] 'process raft request' (duration: 141.583272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:10:25.957354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:10:25.600062Z","time spent":"357.03395ms","remote":"127.0.0.1:60416","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-08-14T23:10:25.957554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.276766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6jzf6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-14T23:10:25.957628Z","caller":"traceutil/trace.go:171","msg":"trace[1327845532] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6jzf6; range_end:; response_count:1; response_revision:630; }","duration":"368.470145ms","start":"2026-08-14T23:10:25.589145Z","end":"2026-08-14T23:10:25.957615Z","steps":["trace[1327845532] 'agreement among raft nodes before linearized reading' (duration: 368.178571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:10:25.957642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.629384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-14T23:10:25.957661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:10:25.589126Z","time spent":"368.527538ms","remote":"127.0.0.1:60614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-6jzf6\" "} {"level":"info","ts":"2026-08-14T23:10:25.957666Z","caller":"traceutil/trace.go:171","msg":"trace[1808205834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"145.676537ms","start":"2026-08-14T23:10:25.811982Z","end":"2026-08-14T23:10:25.957659Z","steps":["trace[1808205834] 'agreement among raft nodes before linearized reading' (duration: 145.596713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:10:49.581241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.092384ms","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-08-14T23:10:49.581365Z","caller":"traceutil/trace.go:171","msg":"trace[1962617306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:814; }","duration":"132.269123ms","start":"2026-08-14T23:10:49.449067Z","end":"2026-08-14T23:10:49.581336Z","steps":["trace[1962617306] 'range keys from in-memory index tree' (duration: 131.795039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:10:49.581458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.808622ms","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-08-14T23:10:49.581634Z","caller":"traceutil/trace.go:171","msg":"trace[1414634023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"294.013562ms","start":"2026-08-14T23:10:49.287573Z","end":"2026-08-14T23:10:49.581586Z","steps":["trace[1414634023] 'range keys from in-memory index tree' (duration: 293.56987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:10.384886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.231018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:12:10.384995Z","caller":"traceutil/trace.go:171","msg":"trace[85566688] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"306.400745ms","start":"2026-08-14T23:12:10.078578Z","end":"2026-08-14T23:12:10.384978Z","steps":["trace[85566688] 'read index received' (duration: 34.111µs)","trace[85566688] 'applied index is now lower than readState.Index' (duration: 306.365454ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:12:10.385203Z","caller":"traceutil/trace.go:171","msg":"trace[16619535] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"337.660153ms","start":"2026-08-14T23:12:10.047528Z","end":"2026-08-14T23:12:10.385188Z","steps":["trace[16619535] 'process raft request' (duration: 12.040893ms)","trace[16619535] 'compare' (duration: 324.878666ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:12:10.385274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:12:10.047499Z","time spent":"337.741713ms","remote":"127.0.0.1:60676","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-08-14T23:12:10.385592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.48443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-14T23:12:10.385667Z","caller":"traceutil/trace.go:171","msg":"trace[1880051847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1902; }","duration":"302.58181ms","start":"2026-08-14T23:12:10.083067Z","end":"2026-08-14T23:12:10.385649Z","steps":["trace[1880051847] 'agreement among raft nodes before linearized reading' (duration: 302.309879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:10.385707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:12:10.083062Z","time spent":"302.637131ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T23:12:10.385867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.585009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c1103de-ae2d-48c8-b8cb-bbd44e471e24\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-14T23:12:10.385921Z","caller":"traceutil/trace.go:171","msg":"trace[1174077890] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c1103de-ae2d-48c8-b8cb-bbd44e471e24; range_end:; response_count:1; response_revision:1902; }","duration":"257.663809ms","start":"2026-08-14T23:12:10.128246Z","end":"2026-08-14T23:12:10.38591Z","steps":["trace[1174077890] 'agreement among raft nodes before linearized reading' (duration: 257.509328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:10.385946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.661673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c1103de-ae2d-48c8-b8cb-bbd44e471e24\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-14T23:12:10.386025Z","caller":"traceutil/trace.go:171","msg":"trace[241928138] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c1103de-ae2d-48c8-b8cb-bbd44e471e24; range_end:; response_count:1; response_revision:1902; }","duration":"238.795565ms","start":"2026-08-14T23:12:10.147212Z","end":"2026-08-14T23:12:10.386007Z","steps":["trace[241928138] 'agreement among raft nodes before linearized reading' (duration: 238.539703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:10.386026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.40383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T23:12:10.386107Z","caller":"traceutil/trace.go:171","msg":"trace[585708404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1902; }","duration":"307.548391ms","start":"2026-08-14T23:12:10.078537Z","end":"2026-08-14T23:12:10.386086Z","steps":["trace[585708404] 'agreement among raft nodes before linearized reading' (duration: 307.208419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:10.386146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:12:10.078519Z","time spent":"307.618141ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T23:12:10.531293Z","caller":"traceutil/trace.go:171","msg":"trace[802364158] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"140.011817ms","start":"2026-08-14T23:12:10.391234Z","end":"2026-08-14T23:12:10.531246Z","steps":["trace[802364158] 'process raft request' (duration: 135.039587ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:12:10.531663Z","caller":"traceutil/trace.go:171","msg":"trace[849460557] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"140.315519ms","start":"2026-08-14T23:12:10.391321Z","end":"2026-08-14T23:12:10.531636Z","steps":["trace[849460557] 'process raft request' (duration: 139.835526ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:12:10.532438Z","caller":"traceutil/trace.go:171","msg":"trace[520654527] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"139.706774ms","start":"2026-08-14T23:12:10.392709Z","end":"2026-08-14T23:12:10.532416Z","steps":["trace[520654527] 'process raft request' (duration: 138.489147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:10.780404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.32261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T23:12:10.780465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.353658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:12:10.780504Z","caller":"traceutil/trace.go:171","msg":"trace[1323982339] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1908; }","duration":"104.474462ms","start":"2026-08-14T23:12:10.676007Z","end":"2026-08-14T23:12:10.780482Z","steps":["trace[1323982339] 'count revisions from in-memory index tree' (duration: 104.2291ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:12:10.78058Z","caller":"traceutil/trace.go:171","msg":"trace[441245684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1908; }","duration":"148.492438ms","start":"2026-08-14T23:12:10.632041Z","end":"2026-08-14T23:12:10.780533Z","steps":["trace[441245684] 'range keys from in-memory index tree' (duration: 148.287067ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:12:21.864185Z","caller":"traceutil/trace.go:171","msg":"trace[2128543537] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"333.540282ms","start":"2026-08-14T23:12:21.530624Z","end":"2026-08-14T23:12:21.864165Z","steps":["trace[2128543537] 'process raft request' (duration: 333.290588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:21.864372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:12:21.530594Z","time spent":"333.682073ms","remote":"127.0.0.1:60600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7926,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:12:21.958709Z","caller":"traceutil/trace.go:171","msg":"trace[319574642] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"425.100538ms","start":"2026-08-14T23:12:21.53359Z","end":"2026-08-14T23:12:21.95869Z","steps":["trace[319574642] 'process raft request' (duration: 417.422389ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:21.95883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:12:21.533569Z","time spent":"425.193669ms","remote":"127.0.0.1:60676","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-08-14T23:12:21.962511Z","caller":"traceutil/trace.go:171","msg":"trace[877338788] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2079; }","duration":"362.773859ms","start":"2026-08-14T23:12:21.599726Z","end":"2026-08-14T23:12:21.9625Z","steps":["trace[877338788] 'read index received' (duration: 264.16287ms)","trace[877338788] 'applied index is now lower than readState.Index' (duration: 98.609909ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:12:21.962607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.88367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:12:21.962639Z","caller":"traceutil/trace.go:171","msg":"trace[1482030180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2003; }","duration":"362.955032ms","start":"2026-08-14T23:12:21.599677Z","end":"2026-08-14T23:12:21.962632Z","steps":["trace[1482030180] 'agreement among raft nodes before linearized reading' (duration: 362.898091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:21.962666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:12:21.599657Z","time spent":"362.997042ms","remote":"127.0.0.1:60414","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-08-14T23:12:21.962681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.510598ms","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-08-14T23:12:21.962713Z","caller":"traceutil/trace.go:171","msg":"trace[171053737] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2003; }","duration":"104.586318ms","start":"2026-08-14T23:12:21.858114Z","end":"2026-08-14T23:12:21.962701Z","steps":["trace[171053737] 'agreement among raft nodes before linearized reading' (duration: 104.469867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:44.790017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.957839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-14T23:12:44.7901Z","caller":"traceutil/trace.go:171","msg":"trace[741864634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2140; }","duration":"108.090062ms","start":"2026-08-14T23:12:44.681994Z","end":"2026-08-14T23:12:44.790084Z","steps":["trace[741864634] 'range keys from in-memory index tree' (duration: 107.800717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:44.790329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.080711ms","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":"warn","ts":"2026-08-14T23:12:44.790373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.310456ms","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-08-14T23:12:44.790412Z","caller":"traceutil/trace.go:171","msg":"trace[1043195380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2140; }","duration":"106.289666ms","start":"2026-08-14T23:12:44.684104Z","end":"2026-08-14T23:12:44.790393Z","steps":["trace[1043195380] 'range keys from in-memory index tree' (duration: 105.877917ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:12:44.790531Z","caller":"traceutil/trace.go:171","msg":"trace[1973751803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2140; }","duration":"101.380997ms","start":"2026-08-14T23:12:44.689028Z","end":"2026-08-14T23:12:44.790409Z","steps":["trace[1973751803] 'range keys from in-memory index tree' (duration: 101.026259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:45.115481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.612816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-14T23:12:45.115555Z","caller":"traceutil/trace.go:171","msg":"trace[129654156] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2144; }","duration":"204.738699ms","start":"2026-08-14T23:12:44.9108Z","end":"2026-08-14T23:12:45.115539Z","steps":["trace[129654156] 'range keys from in-memory index tree' (duration: 204.501353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:45.115767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.880312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:119146"} {"level":"info","ts":"2026-08-14T23:12:45.115928Z","caller":"traceutil/trace.go:171","msg":"trace[337202355] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2144; }","duration":"205.246749ms","start":"2026-08-14T23:12:44.910663Z","end":"2026-08-14T23:12:45.11591Z","steps":["trace[337202355] 'range keys from in-memory index tree' (duration: 204.656557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:45.116518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.468797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:12:45.116648Z","caller":"traceutil/trace.go:171","msg":"trace[1931863702] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"113.744305ms","start":"2026-08-14T23:12:45.002885Z","end":"2026-08-14T23:12:45.116629Z","steps":["trace[1931863702] 'compare' (duration: 110.230102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:45.429934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.887935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:12:45.430019Z","caller":"traceutil/trace.go:171","msg":"trace[605282442] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2145; }","duration":"212.018868ms","start":"2026-08-14T23:12:45.217982Z","end":"2026-08-14T23:12:45.430001Z","steps":["trace[605282442] 'count revisions from in-memory index tree' (duration: 211.753303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:45.430035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.418052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-14T23:12:45.430225Z","caller":"traceutil/trace.go:171","msg":"trace[690774027] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2145; }","duration":"164.653498ms","start":"2026-08-14T23:12:45.265551Z","end":"2026-08-14T23:12:45.430204Z","steps":["trace[690774027] 'range keys from in-memory index tree' (duration: 164.243339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:12:45.74586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.521011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:12:45.746511Z","caller":"traceutil/trace.go:171","msg":"trace[2089687854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2146; }","duration":"145.209125ms","start":"2026-08-14T23:12:45.601248Z","end":"2026-08-14T23:12:45.746457Z","steps":["trace[2089687854] 'range keys from in-memory index tree' (duration: 144.337948ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:13:49.56387Z","caller":"traceutil/trace.go:171","msg":"trace[935961492] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"207.608242ms","start":"2026-08-14T23:13:49.356232Z","end":"2026-08-14T23:13:49.56384Z","steps":["trace[935961492] 'process raft request' (duration: 207.170418ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:14:22.669672Z","caller":"traceutil/trace.go:171","msg":"trace[2024388129] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"170.84723ms","start":"2026-08-14T23:14:22.498792Z","end":"2026-08-14T23:14:22.669639Z","steps":["trace[2024388129] 'process raft request' (duration: 170.620272ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:14:27.956865Z","caller":"traceutil/trace.go:171","msg":"trace[691837935] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"165.117776ms","start":"2026-08-14T23:14:27.791717Z","end":"2026-08-14T23:14:27.956835Z","steps":["trace[691837935] 'process raft request' (duration: 155.819046ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:12.125895Z","caller":"traceutil/trace.go:171","msg":"trace[1541934757] linearizableReadLoop","detail":"{readStateIndex:4826; appliedIndex:4825; }","duration":"220.808139ms","start":"2026-08-14T23:18:11.905037Z","end":"2026-08-14T23:18:12.125845Z","steps":["trace[1541934757] 'read index received' (duration: 163.731665ms)","trace[1541934757] 'applied index is now lower than readState.Index' (duration: 57.075224ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:12.125974Z","caller":"traceutil/trace.go:171","msg":"trace[1342458902] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"221.687633ms","start":"2026-08-14T23:18:11.904249Z","end":"2026-08-14T23:18:12.125936Z","steps":["trace[1342458902] 'process raft request' (duration: 163.636823ms)","trace[1342458902] 'compare' (duration: 57.513265ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:18:12.126173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.103467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-14T23:18:12.126243Z","caller":"traceutil/trace.go:171","msg":"trace[293124574] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4644; }","duration":"221.21273ms","start":"2026-08-14T23:18:11.905016Z","end":"2026-08-14T23:18:12.126229Z","steps":["trace[293124574] 'agreement among raft nodes before linearized reading' (duration: 221.008324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:12.129388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.307399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-08-14T23:18:12.129569Z","caller":"traceutil/trace.go:171","msg":"trace[905036712] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4644; }","duration":"106.427182ms","start":"2026-08-14T23:18:12.023017Z","end":"2026-08-14T23:18:12.129445Z","steps":["trace[905036712] 'agreement among raft nodes before linearized reading' (duration: 103.047571ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:12.990551Z","caller":"traceutil/trace.go:171","msg":"trace[218660682] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"105.086986ms","start":"2026-08-14T23:18:12.885435Z","end":"2026-08-14T23:18:12.990522Z","steps":["trace[218660682] 'process raft request' (duration: 74.856838ms)","trace[218660682] 'compare' (duration: 30.058923ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:13.006878Z","caller":"traceutil/trace.go:171","msg":"trace[513202603] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"114.352514ms","start":"2026-08-14T23:18:12.892505Z","end":"2026-08-14T23:18:13.006857Z","steps":["trace[513202603] 'process raft request' (duration: 114.225411ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:18.436554Z","caller":"traceutil/trace.go:171","msg":"trace[720869931] linearizableReadLoop","detail":"{readStateIndex:4914; appliedIndex:4913; }","duration":"229.578205ms","start":"2026-08-14T23:18:18.206951Z","end":"2026-08-14T23:18:18.436529Z","steps":["trace[720869931] 'read index received' (duration: 229.362419ms)","trace[720869931] 'applied index is now lower than readState.Index' (duration: 215.016µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:18.436619Z","caller":"traceutil/trace.go:171","msg":"trace[1615201065] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"249.566218ms","start":"2026-08-14T23:18:18.187021Z","end":"2026-08-14T23:18:18.436587Z","steps":["trace[1615201065] 'process raft request' (duration: 249.344802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:18.436909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.948365ms","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-08-14T23:18:18.436968Z","caller":"traceutil/trace.go:171","msg":"trace[1970796380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4729; }","duration":"230.060988ms","start":"2026-08-14T23:18:18.20688Z","end":"2026-08-14T23:18:18.436941Z","steps":["trace[1970796380] 'agreement among raft nodes before linearized reading' (duration: 229.808131ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:18.639448Z","caller":"traceutil/trace.go:171","msg":"trace[2033681659] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"117.437199ms","start":"2026-08-14T23:18:18.521969Z","end":"2026-08-14T23:18:18.639406Z","steps":["trace[2033681659] 'process raft request' (duration: 73.067254ms)","trace[2033681659] 'compare' (duration: 44.151388ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:19.168543Z","caller":"traceutil/trace.go:171","msg":"trace[356780371] linearizableReadLoop","detail":"{readStateIndex:4927; appliedIndex:4925; }","duration":"154.172908ms","start":"2026-08-14T23:18:19.01434Z","end":"2026-08-14T23:18:19.168513Z","steps":["trace[356780371] 'read index received' (duration: 124.664105ms)","trace[356780371] 'applied index is now lower than readState.Index' (duration: 29.507623ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:18:19.168809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.448056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-14T23:18:19.168786Z","caller":"traceutil/trace.go:171","msg":"trace[40480315] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"215.066722ms","start":"2026-08-14T23:18:18.953688Z","end":"2026-08-14T23:18:19.168755Z","steps":["trace[40480315] 'process raft request' (duration: 214.696122ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:19.168879Z","caller":"traceutil/trace.go:171","msg":"trace[795799475] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4742; }","duration":"154.534279ms","start":"2026-08-14T23:18:19.014323Z","end":"2026-08-14T23:18:19.168858Z","steps":["trace[795799475] 'agreement among raft nodes before linearized reading' (duration: 154.306732ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:19.168764Z","caller":"traceutil/trace.go:171","msg":"trace[169078050] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"220.276314ms","start":"2026-08-14T23:18:18.948454Z","end":"2026-08-14T23:18:19.16873Z","steps":["trace[169078050] 'process raft request' (duration: 190.651538ms)","trace[169078050] 'compare' (duration: 29.049861ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:19.553387Z","caller":"traceutil/trace.go:171","msg":"trace[1334381704] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"182.314465ms","start":"2026-08-14T23:18:19.371022Z","end":"2026-08-14T23:18:19.553336Z","steps":["trace[1334381704] 'process raft request' (duration: 155.166825ms)","trace[1334381704] 'compare' (duration: 26.945034ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:19.553483Z","caller":"traceutil/trace.go:171","msg":"trace[1689112015] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"161.335074ms","start":"2026-08-14T23:18:19.392118Z","end":"2026-08-14T23:18:19.553453Z","steps":["trace[1689112015] 'process raft request' (duration: 161.164339ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:19.553886Z","caller":"traceutil/trace.go:171","msg":"trace[663800480] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"160.787618ms","start":"2026-08-14T23:18:19.393035Z","end":"2026-08-14T23:18:19.553822Z","steps":["trace[663800480] 'process raft request' (duration: 160.298225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:19.554112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.765014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-14T23:18:19.553531Z","caller":"traceutil/trace.go:171","msg":"trace[1878422771] linearizableReadLoop","detail":"{readStateIndex:4945; appliedIndex:4942; }","duration":"158.140707ms","start":"2026-08-14T23:18:19.395326Z","end":"2026-08-14T23:18:19.553466Z","steps":["trace[1878422771] 'read index received' (duration: 130.985027ms)","trace[1878422771] 'applied index is now lower than readState.Index' (duration: 27.15474ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:19.554172Z","caller":"traceutil/trace.go:171","msg":"trace[584080734] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4761; }","duration":"158.869177ms","start":"2026-08-14T23:18:19.395288Z","end":"2026-08-14T23:18:19.554158Z","steps":["trace[584080734] 'agreement among raft nodes before linearized reading' (duration: 158.693202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:19.55441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.991005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-14T23:18:19.554514Z","caller":"traceutil/trace.go:171","msg":"trace[690239484] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4761; }","duration":"157.137069ms","start":"2026-08-14T23:18:19.397356Z","end":"2026-08-14T23:18:19.554493Z","steps":["trace[690239484] 'agreement among raft nodes before linearized reading' (duration: 156.885302ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:19.730093Z","caller":"traceutil/trace.go:171","msg":"trace[276556016] linearizableReadLoop","detail":"{readStateIndex:4948; appliedIndex:4946; }","duration":"168.279822ms","start":"2026-08-14T23:18:19.5618Z","end":"2026-08-14T23:18:19.730079Z","steps":["trace[276556016] 'read index received' (duration: 37.84003ms)","trace[276556016] 'applied index is now lower than readState.Index' (duration: 130.438962ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:19.730097Z","caller":"traceutil/trace.go:171","msg":"trace[1847708582] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"168.465827ms","start":"2026-08-14T23:18:19.561607Z","end":"2026-08-14T23:18:19.730073Z","steps":["trace[1847708582] 'process raft request' (duration: 162.120515ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:19.73015Z","caller":"traceutil/trace.go:171","msg":"trace[148501280] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"136.168429ms","start":"2026-08-14T23:18:19.593961Z","end":"2026-08-14T23:18:19.730129Z","steps":["trace[148501280] 'process raft request' (duration: 136.070476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:19.730261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.421426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-08-14T23:18:19.730678Z","caller":"traceutil/trace.go:171","msg":"trace[711819073] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"127.350177ms","start":"2026-08-14T23:18:19.603317Z","end":"2026-08-14T23:18:19.730667Z","steps":["trace[711819073] 'process raft request' (duration: 127.295126ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:19.730293Z","caller":"traceutil/trace.go:171","msg":"trace[1155807866] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4764; }","duration":"168.516059ms","start":"2026-08-14T23:18:19.561767Z","end":"2026-08-14T23:18:19.730283Z","steps":["trace[1155807866] 'agreement among raft nodes before linearized reading' (duration: 168.378695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:21.150366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.48618ms","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-08-14T23:18:21.150483Z","caller":"traceutil/trace.go:171","msg":"trace[1398459629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4818; }","duration":"450.647685ms","start":"2026-08-14T23:18:20.699795Z","end":"2026-08-14T23:18:21.150443Z","steps":["trace[1398459629] 'range keys from in-memory index tree' (duration: 450.264914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:21.150546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:20.699772Z","time spent":"450.755277ms","remote":"127.0.0.1:60676","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":"warn","ts":"2026-08-14T23:18:21.151041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.457766ms","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-08-14T23:18:21.151132Z","caller":"traceutil/trace.go:171","msg":"trace[1838285362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4818; }","duration":"451.57912ms","start":"2026-08-14T23:18:20.699527Z","end":"2026-08-14T23:18:21.151106Z","steps":["trace[1838285362] 'range keys from in-memory index tree' (duration: 451.308102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:21.151179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:20.699516Z","time spent":"451.653922ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T23:18:21.151186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.912293ms","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-08-14T23:18:21.151242Z","caller":"traceutil/trace.go:171","msg":"trace[2029834690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4818; }","duration":"457.972004ms","start":"2026-08-14T23:18:20.693255Z","end":"2026-08-14T23:18:21.151227Z","steps":["trace[2029834690] 'range keys from in-memory index tree' (duration: 456.884614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:21.1513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:20.693212Z","time spent":"458.073347ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T23:18:21.235684Z","caller":"traceutil/trace.go:171","msg":"trace[1592502671] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5003; }","duration":"370.213104ms","start":"2026-08-14T23:18:20.865439Z","end":"2026-08-14T23:18:21.235652Z","steps":["trace[1592502671] 'read index received' (duration: 370.05799ms)","trace[1592502671] 'applied index is now lower than readState.Index' (duration: 154.094µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:21.235828Z","caller":"traceutil/trace.go:171","msg":"trace[1731115345] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"534.816849ms","start":"2026-08-14T23:18:20.700996Z","end":"2026-08-14T23:18:21.235813Z","steps":["trace[1731115345] 'process raft request' (duration: 534.4936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:21.235942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:20.700973Z","time spent":"534.883741ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:18:21.236001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.551163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-14T23:18:21.23605Z","caller":"traceutil/trace.go:171","msg":"trace[883703735] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92; range_end:; response_count:1; response_revision:4819; }","duration":"370.640656ms","start":"2026-08-14T23:18:20.865394Z","end":"2026-08-14T23:18:21.236035Z","steps":["trace[883703735] 'agreement among raft nodes before linearized reading' (duration: 370.496262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:21.236083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:20.865372Z","time spent":"370.704308ms","remote":"127.0.0.1:60614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92\" "} {"level":"info","ts":"2026-08-14T23:18:21.236344Z","caller":"traceutil/trace.go:171","msg":"trace[1010292919] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"246.645061ms","start":"2026-08-14T23:18:20.989674Z","end":"2026-08-14T23:18:21.236319Z","steps":["trace[1010292919] 'process raft request' (duration: 246.402854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:21.237338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.108312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-14T23:18:21.237392Z","caller":"traceutil/trace.go:171","msg":"trace[463196478] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4823; }","duration":"318.210685ms","start":"2026-08-14T23:18:20.919167Z","end":"2026-08-14T23:18:21.237378Z","steps":["trace[463196478] 'agreement among raft nodes before linearized reading' (duration: 318.00694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:21.237426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:20.919145Z","time spent":"318.273147ms","remote":"127.0.0.1:60614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-14T23:18:21.23761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.025709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-14T23:18:21.237663Z","caller":"traceutil/trace.go:171","msg":"trace[591386058] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92; range_end:; response_count:1; response_revision:4823; }","duration":"274.123001ms","start":"2026-08-14T23:18:20.963525Z","end":"2026-08-14T23:18:21.237648Z","steps":["trace[591386058] 'agreement among raft nodes before linearized reading' (duration: 273.926326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:23.741794Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204305249303903,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T23:18:24.001978Z","caller":"traceutil/trace.go:171","msg":"trace[426882746] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"760.737615ms","start":"2026-08-14T23:18:23.241214Z","end":"2026-08-14T23:18:24.001951Z","steps":["trace[426882746] 'process raft request' (duration: 760.496578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:24.002151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.241191Z","time spent":"760.864358ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:18:24.004404Z","caller":"traceutil/trace.go:171","msg":"trace[800425051] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"262.663908ms","start":"2026-08-14T23:18:23.741723Z","end":"2026-08-14T23:18:24.004387Z","steps":["trace[800425051] 'process raft request' (duration: 262.521994ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:24.004574Z","caller":"traceutil/trace.go:171","msg":"trace[267242071] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"260.742805ms","start":"2026-08-14T23:18:23.743825Z","end":"2026-08-14T23:18:24.004568Z","steps":["trace[267242071] 'process raft request' (duration: 260.522259ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:24.00456Z","caller":"traceutil/trace.go:171","msg":"trace[1690582703] linearizableReadLoop","detail":"{readStateIndex:5018; appliedIndex:5016; }","duration":"762.973977ms","start":"2026-08-14T23:18:23.241548Z","end":"2026-08-14T23:18:24.004522Z","steps":["trace[1690582703] 'read index received' (duration: 760.16762ms)","trace[1690582703] 'applied index is now lower than readState.Index' (duration: 2.804507ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:18:24.004732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.039608ms","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-08-14T23:18:24.004756Z","caller":"traceutil/trace.go:171","msg":"trace[323800088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4833; }","duration":"763.11063ms","start":"2026-08-14T23:18:23.24164Z","end":"2026-08-14T23:18:24.004751Z","steps":["trace[323800088] 'agreement among raft nodes before linearized reading' (duration: 763.012617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:24.004771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.241632Z","time spent":"763.134621ms","remote":"127.0.0.1:60676","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":"warn","ts":"2026-08-14T23:18:24.004906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.218443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-14T23:18:24.004916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.457018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-58b55f657-bbt4c.18cbcf33a6f7060d\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-08-14T23:18:24.004951Z","caller":"traceutil/trace.go:171","msg":"trace[549464822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4833; }","duration":"763.402978ms","start":"2026-08-14T23:18:23.24154Z","end":"2026-08-14T23:18:24.004943Z","steps":["trace[549464822] 'agreement among raft nodes before linearized reading' (duration: 763.08818ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:24.004966Z","caller":"traceutil/trace.go:171","msg":"trace[1860622133] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-58b55f657-bbt4c.18cbcf33a6f7060d; range_end:; response_count:1; response_revision:4833; }","duration":"570.545921ms","start":"2026-08-14T23:18:23.434406Z","end":"2026-08-14T23:18:24.004952Z","steps":["trace[1860622133] 'agreement among raft nodes before linearized reading' (duration: 570.396956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:24.005008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.434385Z","time spent":"570.615253ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":916,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-58b55f657-bbt4c.18cbcf33a6f7060d\" "} {"level":"warn","ts":"2026-08-14T23:18:24.005064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.24148Z","time spent":"763.489981ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T23:18:24.00514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.86037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-08-14T23:18:24.005165Z","caller":"traceutil/trace.go:171","msg":"trace[957063416] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92; range_end:; response_count:1; response_revision:4833; }","duration":"759.900101ms","start":"2026-08-14T23:18:23.245258Z","end":"2026-08-14T23:18:24.005159Z","steps":["trace[957063416] 'agreement among raft nodes before linearized reading' (duration: 759.84027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:24.005181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.245251Z","time spent":"759.926072ms","remote":"127.0.0.1:60614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3975,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92\" "} {"level":"warn","ts":"2026-08-14T23:18:24.005277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.46802ms","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":"warn","ts":"2026-08-14T23:18:24.005355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.487591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18cbcf3418ac70c6\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-08-14T23:18:24.005382Z","caller":"traceutil/trace.go:171","msg":"trace[1145224866] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18cbcf3418ac70c6; range_end:; response_count:1; response_revision:4833; }","duration":"553.527242ms","start":"2026-08-14T23:18:23.451843Z","end":"2026-08-14T23:18:24.005371Z","steps":["trace[1145224866] 'agreement among raft nodes before linearized reading' (duration: 553.47243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:24.005396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.451802Z","time spent":"553.590484ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":1035,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18cbcf3418ac70c6\" "} {"level":"info","ts":"2026-08-14T23:18:24.005383Z","caller":"traceutil/trace.go:171","msg":"trace[1622810003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4833; }","duration":"763.631525ms","start":"2026-08-14T23:18:23.241724Z","end":"2026-08-14T23:18:24.005356Z","steps":["trace[1622810003] 'agreement among raft nodes before linearized reading' (duration: 763.291485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:24.005441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.241705Z","time spent":"763.726917ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T23:18:24.005463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.66344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:18:24.005476Z","caller":"traceutil/trace.go:171","msg":"trace[436454937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4833; }","duration":"406.710652ms","start":"2026-08-14T23:18:23.598761Z","end":"2026-08-14T23:18:24.005472Z","steps":["trace[436454937] 'agreement among raft nodes before linearized reading' (duration: 406.689771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:24.005507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.598743Z","time spent":"406.743253ms","remote":"127.0.0.1:60414","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-08-14T23:18:24.005319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.581869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-08-14T23:18:24.005584Z","caller":"traceutil/trace.go:171","msg":"trace[860277665] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4833; }","duration":"301.876157ms","start":"2026-08-14T23:18:23.703694Z","end":"2026-08-14T23:18:24.00557Z","steps":["trace[860277665] 'agreement among raft nodes before linearized reading' (duration: 301.506447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:24.00566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.70368Z","time spent":"301.967949ms","remote":"127.0.0.1:56384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-14T23:18:24.005015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.274754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-14T23:18:24.00579Z","caller":"traceutil/trace.go:171","msg":"trace[1621959577] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4833; }","duration":"755.997224ms","start":"2026-08-14T23:18:23.249733Z","end":"2026-08-14T23:18:24.005731Z","steps":["trace[1621959577] 'agreement among raft nodes before linearized reading' (duration: 755.11719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:24.005951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:18:23.249669Z","time spent":"756.228ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-14T23:18:25.113333Z","caller":"traceutil/trace.go:171","msg":"trace[569430881] linearizableReadLoop","detail":"{readStateIndex:5029; appliedIndex:5028; }","duration":"103.595655ms","start":"2026-08-14T23:18:25.009715Z","end":"2026-08-14T23:18:25.113311Z","steps":["trace[569430881] 'read index received' (duration: 103.339608ms)","trace[569430881] 'applied index is now lower than readState.Index' (duration: 254.857µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:18:25.113486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.74483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92\" ","response":"range_response_count:1 size:3954"} {"level":"info","ts":"2026-08-14T23:18:25.113611Z","caller":"traceutil/trace.go:171","msg":"trace[1270755331] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d53e81d4-bb47-4245-b771-b30a1c2b1a92; range_end:; response_count:1; response_revision:4843; }","duration":"103.853614ms","start":"2026-08-14T23:18:25.00969Z","end":"2026-08-14T23:18:25.113544Z","steps":["trace[1270755331] 'agreement among raft nodes before linearized reading' (duration: 103.71272ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:25.113782Z","caller":"traceutil/trace.go:171","msg":"trace[1870104301] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"110.073675ms","start":"2026-08-14T23:18:25.003666Z","end":"2026-08-14T23:18:25.113709Z","steps":["trace[1870104301] 'process raft request' (duration: 109.499918ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:25.393077Z","caller":"traceutil/trace.go:171","msg":"trace[786315192] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"273.285888ms","start":"2026-08-14T23:18:25.1197Z","end":"2026-08-14T23:18:25.392986Z","steps":["trace[786315192] 'process raft request' (duration: 253.497111ms)","trace[786315192] 'compare' (duration: 19.476058ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:25.963736Z","caller":"traceutil/trace.go:171","msg":"trace[911763533] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"124.851704ms","start":"2026-08-14T23:18:25.83886Z","end":"2026-08-14T23:18:25.963712Z","steps":["trace[911763533] 'process raft request' (duration: 124.697689ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:26.656951Z","caller":"traceutil/trace.go:171","msg":"trace[1296155543] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5055; }","duration":"214.132117ms","start":"2026-08-14T23:18:26.442796Z","end":"2026-08-14T23:18:26.656928Z","steps":["trace[1296155543] 'read index received' (duration: 214.015964ms)","trace[1296155543] 'applied index is now lower than readState.Index' (duration: 115.113µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:18:26.65711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.293923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:18:26.657151Z","caller":"traceutil/trace.go:171","msg":"trace[1314490765] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4870; }","duration":"214.365115ms","start":"2026-08-14T23:18:26.442776Z","end":"2026-08-14T23:18:26.657141Z","steps":["trace[1314490765] 'agreement among raft nodes before linearized reading' (duration: 214.260512ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:26.657005Z","caller":"traceutil/trace.go:171","msg":"trace[895160035] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"215.388763ms","start":"2026-08-14T23:18:26.441581Z","end":"2026-08-14T23:18:26.65697Z","steps":["trace[895160035] 'process raft request' (duration: 215.216077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:26.657971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.084948ms","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-08-14T23:18:26.658033Z","caller":"traceutil/trace.go:171","msg":"trace[30724874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4871; }","duration":"138.189072ms","start":"2026-08-14T23:18:26.51983Z","end":"2026-08-14T23:18:26.658019Z","steps":["trace[30724874] 'agreement among raft nodes before linearized reading' (duration: 137.987306ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.223042Z","caller":"traceutil/trace.go:171","msg":"trace[709435089] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"121.020181ms","start":"2026-08-14T23:18:34.102002Z","end":"2026-08-14T23:18:34.223022Z","steps":["trace[709435089] 'process raft request' (duration: 120.862717ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.25016Z","caller":"traceutil/trace.go:171","msg":"trace[1893139760] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"123.12528ms","start":"2026-08-14T23:18:34.127008Z","end":"2026-08-14T23:18:34.250133Z","steps":["trace[1893139760] 'process raft request' (duration: 122.947155ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.250808Z","caller":"traceutil/trace.go:171","msg":"trace[1568542777] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"117.615094ms","start":"2026-08-14T23:18:34.133162Z","end":"2026-08-14T23:18:34.250777Z","steps":["trace[1568542777] 'process raft request' (duration: 116.928695ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.251421Z","caller":"traceutil/trace.go:171","msg":"trace[65998479] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"117.840921ms","start":"2026-08-14T23:18:34.133559Z","end":"2026-08-14T23:18:34.2514Z","steps":["trace[65998479] 'process raft request' (duration: 117.557973ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.251966Z","caller":"traceutil/trace.go:171","msg":"trace[60596050] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5163; }","duration":"102.335181ms","start":"2026-08-14T23:18:34.148902Z","end":"2026-08-14T23:18:34.251238Z","steps":["trace[60596050] 'read index received' (duration: 74.063282ms)","trace[60596050] 'applied index is now lower than readState.Index' (duration: 28.270909ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:18:34.252243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.31633ms","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-08-14T23:18:34.252488Z","caller":"traceutil/trace.go:171","msg":"trace[744528776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4976; }","duration":"103.456093ms","start":"2026-08-14T23:18:34.148864Z","end":"2026-08-14T23:18:34.25232Z","steps":["trace[744528776] 'agreement among raft nodes before linearized reading' (duration: 103.086823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:34.428354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.228916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:18:34.428663Z","caller":"traceutil/trace.go:171","msg":"trace[1250614967] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"200.483136ms","start":"2026-08-14T23:18:34.228138Z","end":"2026-08-14T23:18:34.428621Z","steps":["trace[1250614967] 'process raft request' (duration: 83.883541ms)","trace[1250614967] 'compare' (duration: 115.209816ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:34.428963Z","caller":"traceutil/trace.go:171","msg":"trace[992838510] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"198.071969ms","start":"2026-08-14T23:18:34.230883Z","end":"2026-08-14T23:18:34.428955Z","steps":["trace[992838510] 'process raft request' (duration: 197.582565ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.429024Z","caller":"traceutil/trace.go:171","msg":"trace[955830719] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"197.220575ms","start":"2026-08-14T23:18:34.231766Z","end":"2026-08-14T23:18:34.428986Z","steps":["trace[955830719] 'process raft request' (duration: 197.074841ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.429222Z","caller":"traceutil/trace.go:171","msg":"trace[1590091134] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"197.054839ms","start":"2026-08-14T23:18:34.232043Z","end":"2026-08-14T23:18:34.429098Z","steps":["trace[1590091134] 'process raft request' (duration: 196.893294ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.429217Z","caller":"traceutil/trace.go:171","msg":"trace[1767431350] linearizableReadLoop","detail":"{readStateIndex:5171; appliedIndex:5167; }","duration":"177.149746ms","start":"2026-08-14T23:18:34.252005Z","end":"2026-08-14T23:18:34.429155Z","steps":["trace[1767431350] 'read index received' (duration: 60.031476ms)","trace[1767431350] 'applied index is now lower than readState.Index' (duration: 117.11549ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:18:34.429594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.061155ms","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-08-14T23:18:34.430509Z","caller":"traceutil/trace.go:171","msg":"trace[2130521721] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4981; }","duration":"247.014262ms","start":"2026-08-14T23:18:34.183473Z","end":"2026-08-14T23:18:34.430488Z","steps":["trace[2130521721] 'agreement among raft nodes before linearized reading' (duration: 245.970032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:34.429593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.217678ms","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-08-14T23:18:34.430762Z","caller":"traceutil/trace.go:171","msg":"trace[249335819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4981; }","duration":"276.537605ms","start":"2026-08-14T23:18:34.15421Z","end":"2026-08-14T23:18:34.430748Z","steps":["trace[249335819] 'agreement among raft nodes before linearized reading' (duration: 275.014162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:34.429874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.972123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-14T23:18:34.430876Z","caller":"traceutil/trace.go:171","msg":"trace[760885098] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4981; }","duration":"280.993872ms","start":"2026-08-14T23:18:34.149868Z","end":"2026-08-14T23:18:34.430862Z","steps":["trace[760885098] 'agreement among raft nodes before linearized reading' (duration: 279.89568ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.430311Z","caller":"traceutil/trace.go:171","msg":"trace[1741123801] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"173.748791ms","start":"2026-08-14T23:18:34.256538Z","end":"2026-08-14T23:18:34.430287Z","steps":["trace[1741123801] 'process raft request' (duration: 172.450505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:18:34.430779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.906315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T23:18:34.431313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.943512ms","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-08-14T23:18:34.431376Z","caller":"traceutil/trace.go:171","msg":"trace[40631077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4981; }","duration":"280.065176ms","start":"2026-08-14T23:18:34.151295Z","end":"2026-08-14T23:18:34.43136Z","steps":["trace[40631077] 'agreement among raft nodes before linearized reading' (duration: 278.122301ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:34.431321Z","caller":"traceutil/trace.go:171","msg":"trace[1559790801] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4981; }","duration":"122.512283ms","start":"2026-08-14T23:18:34.308782Z","end":"2026-08-14T23:18:34.431295Z","steps":["trace[1559790801] 'agreement among raft nodes before linearized reading' (duration: 121.883845ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:40.453065Z","caller":"traceutil/trace.go:171","msg":"trace[1749223991] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"162.642409ms","start":"2026-08-14T23:18:40.290396Z","end":"2026-08-14T23:18:40.453039Z","steps":["trace[1749223991] 'process raft request' (duration: 156.450962ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:40.454177Z","caller":"traceutil/trace.go:171","msg":"trace[1109137516] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"163.568146ms","start":"2026-08-14T23:18:40.290586Z","end":"2026-08-14T23:18:40.454154Z","steps":["trace[1109137516] 'process raft request' (duration: 162.402172ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:40.634888Z","caller":"traceutil/trace.go:171","msg":"trace[1314233154] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"130.351893ms","start":"2026-08-14T23:18:40.504511Z","end":"2026-08-14T23:18:40.634863Z","steps":["trace[1314233154] 'process raft request' (duration: 130.299221ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:40.634947Z","caller":"traceutil/trace.go:171","msg":"trace[870067000] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"130.473377ms","start":"2026-08-14T23:18:40.504428Z","end":"2026-08-14T23:18:40.634902Z","steps":["trace[870067000] 'process raft request' (duration: 66.35242ms)","trace[870067000] 'compare' (duration: 63.911761ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:40.97789Z","caller":"traceutil/trace.go:171","msg":"trace[1768785763] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"122.843568ms","start":"2026-08-14T23:18:40.855018Z","end":"2026-08-14T23:18:40.977861Z","steps":["trace[1768785763] 'process raft request' (duration: 122.694744ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:41.022273Z","caller":"traceutil/trace.go:171","msg":"trace[1914019618] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"166.230381ms","start":"2026-08-14T23:18:40.856018Z","end":"2026-08-14T23:18:41.022248Z","steps":["trace[1914019618] 'process raft request' (duration: 166.116918ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:59.212555Z","caller":"traceutil/trace.go:171","msg":"trace[1818415757] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"104.867339ms","start":"2026-08-14T23:18:59.107665Z","end":"2026-08-14T23:18:59.212533Z","steps":["trace[1818415757] 'process raft request' (duration: 69.111845ms)","trace[1818415757] 'compare' (duration: 35.61748ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:18:59.218521Z","caller":"traceutil/trace.go:171","msg":"trace[1059394277] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"108.876297ms","start":"2026-08-14T23:18:59.109635Z","end":"2026-08-14T23:18:59.218511Z","steps":["trace[1059394277] 'process raft request' (duration: 108.29121ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:04.51617Z","caller":"traceutil/trace.go:171","msg":"trace[1844126775] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"116.69114ms","start":"2026-08-14T23:19:04.399419Z","end":"2026-08-14T23:19:04.516111Z","steps":["trace[1844126775] 'process raft request' (duration: 116.33677ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:13.092541Z","caller":"traceutil/trace.go:171","msg":"trace[1916199661] linearizableReadLoop","detail":"{readStateIndex:5513; appliedIndex:5512; }","duration":"311.887034ms","start":"2026-08-14T23:19:12.78063Z","end":"2026-08-14T23:19:13.092517Z","steps":["trace[1916199661] 'read index received' (duration: 311.681708ms)","trace[1916199661] 'applied index is now lower than readState.Index' (duration: 204.496µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:19:13.092802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.135972ms","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-08-14T23:19:13.09285Z","caller":"traceutil/trace.go:171","msg":"trace[1419473433] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5313; }","duration":"312.246036ms","start":"2026-08-14T23:19:12.780589Z","end":"2026-08-14T23:19:13.092835Z","steps":["trace[1419473433] 'agreement among raft nodes before linearized reading' (duration: 312.057769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:19:13.092887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:19:12.780567Z","time spent":"312.309528ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-14T23:19:13.092998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.988577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-14T23:19:13.093056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.165424ms","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-08-14T23:19:13.093085Z","caller":"traceutil/trace.go:171","msg":"trace[828740653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5313; }","duration":"140.229986ms","start":"2026-08-14T23:19:12.952847Z","end":"2026-08-14T23:19:13.093077Z","steps":["trace[828740653] 'agreement among raft nodes before linearized reading' (duration: 140.143714ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:13.093085Z","caller":"traceutil/trace.go:171","msg":"trace[5044181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5313; }","duration":"233.120211ms","start":"2026-08-14T23:19:12.859943Z","end":"2026-08-14T23:19:13.093063Z","steps":["trace[5044181] 'agreement among raft nodes before linearized reading' (duration: 232.856942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:19:13.093336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.298433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-14T23:19:13.093799Z","caller":"traceutil/trace.go:171","msg":"trace[1105259437] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5313; }","duration":"200.825949ms","start":"2026-08-14T23:19:12.892944Z","end":"2026-08-14T23:19:13.09377Z","steps":["trace[1105259437] 'agreement among raft nodes before linearized reading' (duration: 200.022165ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:13.092799Z","caller":"traceutil/trace.go:171","msg":"trace[977941597] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"360.520302ms","start":"2026-08-14T23:19:12.732239Z","end":"2026-08-14T23:19:13.092759Z","steps":["trace[977941597] 'process raft request' (duration: 360.12951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:19:13.094181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.797474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-08-14T23:19:13.094274Z","caller":"traceutil/trace.go:171","msg":"trace[63455751] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5313; }","duration":"215.97775ms","start":"2026-08-14T23:19:12.878277Z","end":"2026-08-14T23:19:13.094254Z","steps":["trace[63455751] 'agreement among raft nodes before linearized reading' (duration: 214.751853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:19:13.094812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:19:12.732224Z","time spent":"361.822511ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:19:16.573852Z","caller":"traceutil/trace.go:171","msg":"trace[685359294] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"147.002436ms","start":"2026-08-14T23:19:16.426761Z","end":"2026-08-14T23:19:16.573764Z","steps":["trace[685359294] 'process raft request' (duration: 64.681778ms)","trace[685359294] 'compare' (duration: 81.600516ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:19:28.694341Z","caller":"traceutil/trace.go:171","msg":"trace[323639570] linearizableReadLoop","detail":"{readStateIndex:5626; appliedIndex:5625; }","duration":"190.768987ms","start":"2026-08-14T23:19:28.503546Z","end":"2026-08-14T23:19:28.694315Z","steps":["trace[323639570] 'read index received' (duration: 190.605391ms)","trace[323639570] 'applied index is now lower than readState.Index' (duration: 162.445µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:19:28.694378Z","caller":"traceutil/trace.go:171","msg":"trace[1048918933] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"192.659836ms","start":"2026-08-14T23:19:28.50169Z","end":"2026-08-14T23:19:28.69435Z","steps":["trace[1048918933] 'process raft request' (duration: 192.348206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:19:28.694694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.108007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-14T23:19:28.694829Z","caller":"traceutil/trace.go:171","msg":"trace[1576138809] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5422; }","duration":"191.298652ms","start":"2026-08-14T23:19:28.503494Z","end":"2026-08-14T23:19:28.694793Z","steps":["trace[1576138809] 'agreement among raft nodes before linearized reading' (duration: 190.923411ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:28.759919Z","caller":"traceutil/trace.go:171","msg":"trace[1860131438] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"108.62683ms","start":"2026-08-14T23:19:28.651268Z","end":"2026-08-14T23:19:28.759895Z","steps":["trace[1860131438] 'process raft request' (duration: 108.439614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:19:28.760483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.602288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:19:28.76056Z","caller":"traceutil/trace.go:171","msg":"trace[801951852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5425; }","duration":"161.726892ms","start":"2026-08-14T23:19:28.598812Z","end":"2026-08-14T23:19:28.760539Z","steps":["trace[801951852] 'agreement among raft nodes before linearized reading' (duration: 161.601668ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:38.933582Z","caller":"traceutil/trace.go:171","msg":"trace[354582965] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"135.514323ms","start":"2026-08-14T23:19:38.798042Z","end":"2026-08-14T23:19:38.933556Z","steps":["trace[354582965] 'process raft request' (duration: 135.289746ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:38.933742Z","caller":"traceutil/trace.go:171","msg":"trace[2082978965] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"133.766407ms","start":"2026-08-14T23:19:38.799949Z","end":"2026-08-14T23:19:38.933715Z","steps":["trace[2082978965] 'process raft request' (duration: 133.52996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:19:39.566839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.760772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:19:39.566946Z","caller":"traceutil/trace.go:171","msg":"trace[1057362410] linearizableReadLoop","detail":"{readStateIndex:5711; appliedIndex:5710; }","duration":"129.499348ms","start":"2026-08-14T23:19:39.437429Z","end":"2026-08-14T23:19:39.566929Z","steps":["trace[1057362410] 'read index received' (duration: 21.508338ms)","trace[1057362410] 'applied index is now lower than readState.Index' (duration: 107.98996ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:19:39.567027Z","caller":"traceutil/trace.go:171","msg":"trace[909306936] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"204.307529ms","start":"2026-08-14T23:19:39.362707Z","end":"2026-08-14T23:19:39.567015Z","steps":["trace[909306936] 'process raft request' (duration: 96.267258ms)","trace[909306936] 'compare' (duration: 107.66802ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:19:39.567252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.821067ms","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-08-14T23:19:39.567303Z","caller":"traceutil/trace.go:171","msg":"trace[1694696481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5505; }","duration":"129.884009ms","start":"2026-08-14T23:19:39.437393Z","end":"2026-08-14T23:19:39.567277Z","steps":["trace[1694696481] 'agreement among raft nodes before linearized reading' (duration: 129.749835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:19:39.567674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.602006ms","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-08-14T23:19:39.568161Z","caller":"traceutil/trace.go:171","msg":"trace[696622313] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5505; }","duration":"113.73692ms","start":"2026-08-14T23:19:39.454006Z","end":"2026-08-14T23:19:39.567743Z","steps":["trace[696622313] 'agreement among raft nodes before linearized reading' (duration: 113.455721ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:39.704609Z","caller":"traceutil/trace.go:171","msg":"trace[1238487385] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"131.239713ms","start":"2026-08-14T23:19:39.573313Z","end":"2026-08-14T23:19:39.704553Z","steps":["trace[1238487385] 'process raft request' (duration: 99.223352ms)","trace[1238487385] 'compare' (duration: 31.748732ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:19:39.704992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.11706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:19:39.705039Z","caller":"traceutil/trace.go:171","msg":"trace[871436539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5509; }","duration":"106.208562ms","start":"2026-08-14T23:19:39.598818Z","end":"2026-08-14T23:19:39.705026Z","steps":["trace[871436539] 'agreement among raft nodes before linearized reading' (duration: 106.082008ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:39.70761Z","caller":"traceutil/trace.go:171","msg":"trace[622595591] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"131.242123ms","start":"2026-08-14T23:19:39.573507Z","end":"2026-08-14T23:19:39.704749Z","steps":["trace[622595591] 'process raft request' (duration: 130.909802ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:43.725542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2890} {"level":"info","ts":"2026-08-14T23:19:43.836765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2890,"took":"109.866485ms","hash":1653099850,"current-db-size-bytes":53104640,"current-db-size":"53 MB","current-db-size-in-use-bytes":29134848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-14T23:19:43.836831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1653099850,"revision":2890,"compact-revision":-1} {"level":"info","ts":"2026-08-14T23:21:05.347114Z","caller":"traceutil/trace.go:171","msg":"trace[1121075894] transaction","detail":"{read_only:false; response_revision:6021; number_of_response:1; }","duration":"214.972429ms","start":"2026-08-14T23:21:05.132114Z","end":"2026-08-14T23:21:05.347086Z","steps":["trace[1121075894] 'process raft request' (duration: 206.818582ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:05.347205Z","caller":"traceutil/trace.go:171","msg":"trace[566540548] transaction","detail":"{read_only:false; response_revision:6022; number_of_response:1; }","duration":"214.819644ms","start":"2026-08-14T23:21:05.132359Z","end":"2026-08-14T23:21:05.347179Z","steps":["trace[566540548] 'process raft request' (duration: 214.673259ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:05.348529Z","caller":"traceutil/trace.go:171","msg":"trace[1932958790] transaction","detail":"{read_only:false; response_revision:6023; number_of_response:1; }","duration":"211.667638ms","start":"2026-08-14T23:21:05.136845Z","end":"2026-08-14T23:21:05.348512Z","steps":["trace[1932958790] 'process raft request' (duration: 210.238019ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:05.348786Z","caller":"traceutil/trace.go:171","msg":"trace[354411080] transaction","detail":"{read_only:false; response_revision:6024; number_of_response:1; }","duration":"170.049302ms","start":"2026-08-14T23:21:05.178687Z","end":"2026-08-14T23:21:05.348737Z","steps":["trace[354411080] 'process raft request' (duration: 168.452938ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:59.145177Z","caller":"traceutil/trace.go:171","msg":"trace[1306111220] transaction","detail":"{read_only:false; response_revision:6492; number_of_response:1; }","duration":"199.978704ms","start":"2026-08-14T23:21:58.945167Z","end":"2026-08-14T23:21:59.145146Z","steps":["trace[1306111220] 'process raft request' (duration: 197.943032ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:09.729893Z","caller":"traceutil/trace.go:171","msg":"trace[682993041] transaction","detail":"{read_only:false; response_revision:6548; number_of_response:1; }","duration":"118.651783ms","start":"2026-08-14T23:22:09.611215Z","end":"2026-08-14T23:22:09.729867Z","steps":["trace[682993041] 'process raft request' (duration: 118.488528ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:10.669028Z","caller":"traceutil/trace.go:171","msg":"trace[48970836] linearizableReadLoop","detail":"{readStateIndex:6794; appliedIndex:6793; }","duration":"128.398265ms","start":"2026-08-14T23:22:10.540603Z","end":"2026-08-14T23:22:10.669002Z","steps":["trace[48970836] 'read index received' (duration: 128.264621ms)","trace[48970836] 'applied index is now lower than readState.Index' (duration: 132.714µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:22:10.669224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.603293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:22:10.669282Z","caller":"traceutil/trace.go:171","msg":"trace[353087234] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6552; }","duration":"128.705797ms","start":"2026-08-14T23:22:10.54056Z","end":"2026-08-14T23:22:10.669266Z","steps":["trace[353087234] 'agreement among raft nodes before linearized reading' (duration: 128.604023ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:10.66915Z","caller":"traceutil/trace.go:171","msg":"trace[2056884818] transaction","detail":"{read_only:false; response_revision:6552; number_of_response:1; }","duration":"154.147977ms","start":"2026-08-14T23:22:10.514976Z","end":"2026-08-14T23:22:10.669123Z","steps":["trace[2056884818] 'process raft request' (duration: 153.801605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:22:10.86032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.349004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:22:10.86047Z","caller":"traceutil/trace.go:171","msg":"trace[1951992014] transaction","detail":"{read_only:false; response_revision:6553; number_of_response:1; }","duration":"186.085274ms","start":"2026-08-14T23:22:10.67434Z","end":"2026-08-14T23:22:10.860425Z","steps":["trace[1951992014] 'process raft request' (duration: 37.531063ms)","trace[1951992014] 'compare' (duration: 148.170638ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:22:10.860538Z","caller":"traceutil/trace.go:171","msg":"trace[726356726] transaction","detail":"{read_only:false; response_revision:6554; number_of_response:1; }","duration":"106.076055ms","start":"2026-08-14T23:22:10.754441Z","end":"2026-08-14T23:22:10.860517Z","steps":["trace[726356726] 'process raft request' (duration: 105.997882ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:10.860535Z","caller":"traceutil/trace.go:171","msg":"trace[1684781828] linearizableReadLoop","detail":"{readStateIndex:6795; appliedIndex:6794; }","duration":"184.313543ms","start":"2026-08-14T23:22:10.676195Z","end":"2026-08-14T23:22:10.860509Z","steps":["trace[1684781828] 'read index received' (duration: 35.601376ms)","trace[1684781828] 'applied index is now lower than readState.Index' (duration: 148.709997ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:22:10.86078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.561922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-14T23:22:10.86082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.364269ms","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-08-14T23:22:10.860832Z","caller":"traceutil/trace.go:171","msg":"trace[1319138920] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6554; }","duration":"184.661505ms","start":"2026-08-14T23:22:10.676159Z","end":"2026-08-14T23:22:10.860821Z","steps":["trace[1319138920] 'agreement among raft nodes before linearized reading' (duration: 184.469559ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:10.860867Z","caller":"traceutil/trace.go:171","msg":"trace[828749833] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6554; }","duration":"107.444101ms","start":"2026-08-14T23:22:10.75341Z","end":"2026-08-14T23:22:10.860854Z","steps":["trace[828749833] 'agreement among raft nodes before linearized reading' (duration: 107.279466ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:12.807356Z","caller":"traceutil/trace.go:171","msg":"trace[434749451] transaction","detail":"{read_only:false; response_revision:6564; number_of_response:1; }","duration":"129.048231ms","start":"2026-08-14T23:22:12.678282Z","end":"2026-08-14T23:22:12.80733Z","steps":["trace[434749451] 'process raft request' (duration: 128.908976ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:12.810122Z","caller":"traceutil/trace.go:171","msg":"trace[1748515685] transaction","detail":"{read_only:false; response_revision:6565; number_of_response:1; }","duration":"129.945713ms","start":"2026-08-14T23:22:12.680152Z","end":"2026-08-14T23:22:12.810097Z","steps":["trace[1748515685] 'process raft request' (duration: 129.828699ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:15.710798Z","caller":"traceutil/trace.go:171","msg":"trace[1313353209] transaction","detail":"{read_only:false; number_of_response:1; response_revision:6593; }","duration":"128.685244ms","start":"2026-08-14T23:22:15.582096Z","end":"2026-08-14T23:22:15.710781Z","steps":["trace[1313353209] 'process raft request' (duration: 128.662883ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:15.710999Z","caller":"traceutil/trace.go:171","msg":"trace[1784831027] transaction","detail":"{read_only:false; response_revision:6593; number_of_response:1; }","duration":"128.963313ms","start":"2026-08-14T23:22:15.582008Z","end":"2026-08-14T23:22:15.710971Z","steps":["trace[1784831027] 'process raft request' (duration: 128.646482ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:15.735669Z","caller":"traceutil/trace.go:171","msg":"trace[680711027] linearizableReadLoop","detail":"{readStateIndex:6839; appliedIndex:6838; }","duration":"137.43325ms","start":"2026-08-14T23:22:15.598217Z","end":"2026-08-14T23:22:15.73565Z","steps":["trace[680711027] 'read index received' (duration: 112.734065ms)","trace[680711027] 'applied index is now lower than readState.Index' (duration: 24.698275ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:22:15.735937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.70172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:22:15.735989Z","caller":"traceutil/trace.go:171","msg":"trace[64863833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6594; }","duration":"137.796845ms","start":"2026-08-14T23:22:15.598183Z","end":"2026-08-14T23:22:15.73598Z","steps":["trace[64863833] 'agreement among raft nodes before linearized reading' (duration: 137.546575ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:15.735699Z","caller":"traceutil/trace.go:171","msg":"trace[667905809] transaction","detail":"{read_only:false; response_revision:6594; number_of_response:1; }","duration":"140.913072ms","start":"2026-08-14T23:22:15.594765Z","end":"2026-08-14T23:22:15.735678Z","steps":["trace[667905809] 'process raft request' (duration: 140.774917ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:29.125058Z","caller":"traceutil/trace.go:171","msg":"trace[1204205222] transaction","detail":"{read_only:false; response_revision:6701; number_of_response:1; }","duration":"100.319494ms","start":"2026-08-14T23:22:29.024714Z","end":"2026-08-14T23:22:29.125033Z","steps":["trace[1204205222] 'process raft request' (duration: 70.4029ms)","trace[1204205222] 'compare' (duration: 29.645915ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:22:29.574065Z","caller":"traceutil/trace.go:171","msg":"trace[2016085022] transaction","detail":"{read_only:false; response_revision:6704; number_of_response:1; }","duration":"108.106368ms","start":"2026-08-14T23:22:29.46593Z","end":"2026-08-14T23:22:29.574037Z","steps":["trace[2016085022] 'process raft request' (duration: 107.968323ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:47.516139Z","caller":"traceutil/trace.go:171","msg":"trace[1244682435] linearizableReadLoop","detail":"{readStateIndex:7388; appliedIndex:7387; }","duration":"119.356316ms","start":"2026-08-14T23:23:47.396757Z","end":"2026-08-14T23:23:47.516113Z","steps":["trace[1244682435] 'read index received' (duration: 19.462667ms)","trace[1244682435] 'applied index is now lower than readState.Index' (duration: 99.892359ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:23:47.516298Z","caller":"traceutil/trace.go:171","msg":"trace[684303700] transaction","detail":"{read_only:false; response_revision:7122; number_of_response:1; }","duration":"171.41927ms","start":"2026-08-14T23:23:47.344856Z","end":"2026-08-14T23:23:47.516275Z","steps":["trace[684303700] 'process raft request' (duration: 71.410357ms)","trace[684303700] 'compare' (duration: 99.709883ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:23:47.516371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.596555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-14T23:23:47.516644Z","caller":"traceutil/trace.go:171","msg":"trace[1377972270] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7122; }","duration":"119.915166ms","start":"2026-08-14T23:23:47.396716Z","end":"2026-08-14T23:23:47.516631Z","steps":["trace[1377972270] 'agreement among raft nodes before linearized reading' (duration: 119.511712ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:24:43.731973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5546} {"level":"info","ts":"2026-08-14T23:24:43.803946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5546,"took":"70.790462ms","hash":3565716222,"current-db-size-bytes":53104640,"current-db-size":"53 MB","current-db-size-in-use-bytes":18182144,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-14T23:24:43.803992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3565716222,"revision":5546,"compact-revision":2890}