{"level":"warn","ts":"2026-03-10T18:06:26.917088Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-10T18:06:26.917204Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.100: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.19.213.100:2380","--initial-cluster=instance=https://199.19.213.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.100: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-03-10T18:06:26.91728Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-10T18:06:26.917294Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-03-10T18:06:26.917324Z","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-03-10T18:06:26.918603Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"]} {"level":"info","ts":"2026-03-10T18:06:26.918696Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.100: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-03-10T18:06:26.922816Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.823181ms"} {"level":"info","ts":"2026-03-10T18:06:26.929959Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"79e65b4197a2a1a","cluster-id":"bba89cbb2a6fc22a"} {"level":"info","ts":"2026-03-10T18:06:26.930071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=()"} {"level":"info","ts":"2026-03-10T18:06:26.93011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 0"} {"level":"info","ts":"2026-03-10T18:06:26.930128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 79e65b4197a2a1a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-10T18:06:26.930144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 1"} {"level":"info","ts":"2026-03-10T18:06:26.93019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"warn","ts":"2026-03-10T18:06:26.940111Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-10T18:06:26.943733Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-10T18:06:26.944926Z","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-03-10T18:06:26.949524Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"79e65b4197a2a1a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-10T18:06:26.949692Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"79e65b4197a2a1a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-10T18:06:26.949792Z","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-03-10T18:06:26.949884Z","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-03-10T18:06:26.949918Z","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-03-10T18:06:26.9521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"info","ts":"2026-03-10T18:06:26.952247Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","added-peer-id":"79e65b4197a2a1a","added-peer-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-03-10T18:06:26.953734Z","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-03-10T18:06:26.954001Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"79e65b4197a2a1a","initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-10T18:06:26.954033Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-10T18:06:26.954118Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-03-10T18:06:26.954261Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-03-10T18:06:27.530926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a is starting a new election at term 1"} {"level":"info","ts":"2026-03-10T18:06:27.530975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-10T18:06:27.530992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgPreVoteResp from 79e65b4197a2a1a at term 1"} {"level":"info","ts":"2026-03-10T18:06:27.531005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became candidate at term 2"} {"level":"info","ts":"2026-03-10T18:06:27.531013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgVoteResp from 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-03-10T18:06:27.531024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became leader at term 2"} {"level":"info","ts":"2026-03-10T18:06:27.531034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 79e65b4197a2a1a elected leader 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-03-10T18:06:27.532049Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"79e65b4197a2a1a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.100:2379]}","request-path":"/0/members/79e65b4197a2a1a/attributes","cluster-id":"bba89cbb2a6fc22a","publish-timeout":"7s"} {"level":"info","ts":"2026-03-10T18:06:27.532411Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T18:06:27.533405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-10T18:06:27.533846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-10T18:06:27.534094Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-10T18:06:27.534119Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-10T18:06:27.534185Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T18:06:27.534281Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T18:06:27.534314Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T18:06:27.536367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-10T18:06:27.537373Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.100:2379"} {"level":"warn","ts":"2026-03-10T18:06:38.148251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.256654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-10T18:06:38.148398Z","caller":"traceutil/trace.go:171","msg":"trace[94515016] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"102.19338ms","start":"2026-03-10T18:06:38.046183Z","end":"2026-03-10T18:06:38.148376Z","steps":["trace[94515016] 'read index received' (duration: 36.401µs)","trace[94515016] 'applied index is now lower than readState.Index' (duration: 102.154609ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:06:38.148471Z","caller":"traceutil/trace.go:171","msg":"trace[837225787] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"143.819949ms","start":"2026-03-10T18:06:38.004594Z","end":"2026-03-10T18:06:38.148414Z","steps":["trace[837225787] 'process raft request' (duration: 39.82269ms)","trace[837225787] 'compare' (duration: 103.07719ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:06:38.148578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.386364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-10T18:06:38.148625Z","caller":"traceutil/trace.go:171","msg":"trace[759421444] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:356; }","duration":"102.465366ms","start":"2026-03-10T18:06:38.046144Z","end":"2026-03-10T18:06:38.148609Z","steps":["trace[759421444] 'agreement among raft nodes before linearized reading' (duration: 102.350084ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:06:38.351279Z","caller":"traceutil/trace.go:171","msg":"trace[1457521785] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"195.573883ms","start":"2026-03-10T18:06:38.155681Z","end":"2026-03-10T18:06:38.351255Z","steps":["trace[1457521785] 'process raft request' (duration: 158.963252ms)","trace[1457521785] 'compare' (duration: 36.296424ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:06:38.504993Z","caller":"traceutil/trace.go:171","msg":"trace[1004839004] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:368; }","duration":"307.869959ms","start":"2026-03-10T18:06:38.197082Z","end":"2026-03-10T18:06:38.504952Z","steps":["trace[1004839004] 'read index received' (duration: 117.58255ms)","trace[1004839004] 'applied index is now lower than readState.Index' (duration: 190.285829ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:06:38.505078Z","caller":"traceutil/trace.go:171","msg":"trace[1551670202] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"347.351041ms","start":"2026-03-10T18:06:38.157691Z","end":"2026-03-10T18:06:38.505042Z","steps":["trace[1551670202] 'process raft request' (duration: 323.704057ms)","trace[1551670202] 'compare' (duration: 23.245586ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:06:38.505011Z","caller":"traceutil/trace.go:171","msg":"trace[994171807] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"344.583704ms","start":"2026-03-10T18:06:38.160394Z","end":"2026-03-10T18:06:38.504978Z","steps":["trace[994171807] 'process raft request' (duration: 344.454071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:06:38.505222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.122265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-03-10T18:06:38.505233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:06:38.160385Z","time spent":"344.779419ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T18:06:38.505326Z","caller":"traceutil/trace.go:171","msg":"trace[500533241] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:359; }","duration":"308.279329ms","start":"2026-03-10T18:06:38.197027Z","end":"2026-03-10T18:06:38.505307Z","steps":["trace[500533241] 'agreement among raft nodes before linearized reading' (duration: 308.060694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:06:38.505317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.523474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-03-10T18:06:38.505361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.458289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-10T18:06:38.505381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:06:38.197006Z","time spent":"308.36248ms","remote":"127.0.0.1:39324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-03-10T18:06:38.505391Z","caller":"traceutil/trace.go:171","msg":"trace[1314567992] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:359; }","duration":"158.653956ms","start":"2026-03-10T18:06:38.346717Z","end":"2026-03-10T18:06:38.505371Z","steps":["trace[1314567992] 'agreement among raft nodes before linearized reading' (duration: 158.492493ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:06:38.505427Z","caller":"traceutil/trace.go:171","msg":"trace[729334917] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:359; }","duration":"208.577092ms","start":"2026-03-10T18:06:38.296833Z","end":"2026-03-10T18:06:38.50541Z","steps":["trace[729334917] 'agreement among raft nodes before linearized reading' (duration: 208.386688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:06:38.5054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.109557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"warn","ts":"2026-03-10T18:06:38.505489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:06:38.15768Z","time spent":"347.482306ms","remote":"127.0.0.1:39302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4359,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:06:38.505534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.864253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-10T18:06:38.505614Z","caller":"traceutil/trace.go:171","msg":"trace[1351942190] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:359; }","duration":"258.215691ms","start":"2026-03-10T18:06:38.24738Z","end":"2026-03-10T18:06:38.505596Z","steps":["trace[1351942190] 'agreement among raft nodes before linearized reading' (duration: 257.807142ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:06:38.505488Z","caller":"traceutil/trace.go:171","msg":"trace[305495516] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:359; }","duration":"175.22754ms","start":"2026-03-10T18:06:38.33024Z","end":"2026-03-10T18:06:38.505468Z","steps":["trace[305495516] 'agreement among raft nodes before linearized reading' (duration: 174.983945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:06:38.748097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.244092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-10T18:06:38.74825Z","caller":"traceutil/trace.go:171","msg":"trace[1672492202] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"236.575398ms","start":"2026-03-10T18:06:38.511648Z","end":"2026-03-10T18:06:38.748224Z","steps":["trace[1672492202] 'process raft request' (duration: 126.097501ms)","trace[1672492202] 'compare' (duration: 108.866363ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:06:38.749934Z","caller":"traceutil/trace.go:171","msg":"trace[1515731271] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"237.338986ms","start":"2026-03-10T18:06:38.51257Z","end":"2026-03-10T18:06:38.749909Z","steps":["trace[1515731271] 'process raft request' (duration: 237.08399ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:06:38.750031Z","caller":"traceutil/trace.go:171","msg":"trace[60979176] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:371; }","duration":"121.655827ms","start":"2026-03-10T18:06:38.628307Z","end":"2026-03-10T18:06:38.749963Z","steps":["trace[60979176] 'read index received' (duration: 9.445412ms)","trace[60979176] 'applied index is now lower than readState.Index' (duration: 112.208545ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:06:38.750124Z","caller":"traceutil/trace.go:171","msg":"trace[1967728267] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"234.876783ms","start":"2026-03-10T18:06:38.515219Z","end":"2026-03-10T18:06:38.750096Z","steps":["trace[1967728267] 'process raft request' (duration: 234.668068ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:06:38.750032Z","caller":"traceutil/trace.go:171","msg":"trace[1671717539] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"236.16317ms","start":"2026-03-10T18:06:38.513799Z","end":"2026-03-10T18:06:38.749963Z","steps":["trace[1671717539] 'process raft request' (duration: 235.934035ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:06:38.750145Z","caller":"traceutil/trace.go:171","msg":"trace[890854473] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"237.749044ms","start":"2026-03-10T18:06:38.51235Z","end":"2026-03-10T18:06:38.750099Z","steps":["trace[890854473] 'process raft request' (duration: 237.09574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:06:38.750486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.170167ms","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-03-10T18:06:38.750554Z","caller":"traceutil/trace.go:171","msg":"trace[2023081066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:365; }","duration":"122.271069ms","start":"2026-03-10T18:06:38.62825Z","end":"2026-03-10T18:06:38.750521Z","steps":["trace[2023081066] 'agreement among raft nodes before linearized reading' (duration: 122.164007ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:06:38.750242Z","caller":"traceutil/trace.go:171","msg":"trace[1505545718] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"235.21452ms","start":"2026-03-10T18:06:38.514879Z","end":"2026-03-10T18:06:38.750093Z","steps":["trace[1505545718] 'process raft request' (duration: 234.944264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:07:03.577976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.081171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hprkt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-10T18:07:03.578043Z","caller":"traceutil/trace.go:171","msg":"trace[2064449708] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hprkt; range_end:; response_count:1; response_revision:550; }","duration":"181.188754ms","start":"2026-03-10T18:07:03.396839Z","end":"2026-03-10T18:07:03.578028Z","steps":["trace[2064449708] 'range keys from in-memory index tree' (duration: 180.943198ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:07:03.727421Z","caller":"traceutil/trace.go:171","msg":"trace[549081390] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"137.255799ms","start":"2026-03-10T18:07:03.590148Z","end":"2026-03-10T18:07:03.727404Z","steps":["trace[549081390] 'process raft request' (duration: 137.108356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:07:04.354096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.915623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:07:04.354229Z","caller":"traceutil/trace.go:171","msg":"trace[1747631669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"130.100397ms","start":"2026-03-10T18:07:04.224105Z","end":"2026-03-10T18:07:04.354206Z","steps":["trace[1747631669] 'range keys from in-memory index tree' (duration: 129.812562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:07:04.354125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.832463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:07:04.354386Z","caller":"traceutil/trace.go:171","msg":"trace[455155215] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:552; }","duration":"250.128919ms","start":"2026-03-10T18:07:04.104221Z","end":"2026-03-10T18:07:04.35435Z","steps":["trace[455155215] 'range keys from in-memory index tree' (duration: 249.764062ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:08:11.118185Z","caller":"traceutil/trace.go:171","msg":"trace[1048653666] linearizableReadLoop","detail":"{readStateIndex:1649; appliedIndex:1647; }","duration":"308.078607ms","start":"2026-03-10T18:08:10.810082Z","end":"2026-03-10T18:08:11.11816Z","steps":["trace[1048653666] 'read index received' (duration: 40.316663ms)","trace[1048653666] 'applied index is now lower than readState.Index' (duration: 267.760734ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:08:11.118305Z","caller":"traceutil/trace.go:171","msg":"trace[1482311032] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"350.021702ms","start":"2026-03-10T18:08:10.768232Z","end":"2026-03-10T18:08:11.118254Z","steps":["trace[1482311032] 'process raft request' (duration: 349.725226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:08:11.118427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.317753ms","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-03-10T18:08:11.118483Z","caller":"traceutil/trace.go:171","msg":"trace[919467636] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1588; }","duration":"308.421874ms","start":"2026-03-10T18:08:10.810045Z","end":"2026-03-10T18:08:11.118467Z","steps":["trace[919467636] 'agreement among raft nodes before linearized reading' (duration: 308.225489ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:08:11.118529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:08:10.810029Z","time spent":"308.491066ms","remote":"127.0.0.1:33624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-10T18:08:11.118563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:08:10.768207Z","time spent":"350.235768ms","remote":"127.0.0.1:39310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:08:11.118875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.57047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-10T18:08:11.118933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.366701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:08:11.118991Z","caller":"traceutil/trace.go:171","msg":"trace[775926127] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1588; }","duration":"252.758704ms","start":"2026-03-10T18:08:10.866208Z","end":"2026-03-10T18:08:11.118967Z","steps":["trace[775926127] 'agreement among raft nodes before linearized reading' (duration: 252.56449ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:08:11.119064Z","caller":"traceutil/trace.go:171","msg":"trace[600763825] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1588; }","duration":"270.612516ms","start":"2026-03-10T18:08:10.848423Z","end":"2026-03-10T18:08:11.119035Z","steps":["trace[600763825] 'agreement among raft nodes before linearized reading' (duration: 270.2891ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:08:23.764822Z","caller":"traceutil/trace.go:171","msg":"trace[329367541] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"167.084213ms","start":"2026-03-10T18:08:23.597698Z","end":"2026-03-10T18:08:23.764782Z","steps":["trace[329367541] 'process raft request' (duration: 166.759586ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:08:27.021732Z","caller":"traceutil/trace.go:171","msg":"trace[7427505] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"117.853241ms","start":"2026-03-10T18:08:26.903858Z","end":"2026-03-10T18:08:27.021711Z","steps":["trace[7427505] 'process raft request' (duration: 117.723307ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:08:27.395559Z","caller":"traceutil/trace.go:171","msg":"trace[255596139] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"113.853385ms","start":"2026-03-10T18:08:27.281681Z","end":"2026-03-10T18:08:27.395534Z","steps":["trace[255596139] 'process raft request' (duration: 113.688441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:09:00.108993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.433306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T18:09:00.109155Z","caller":"traceutil/trace.go:171","msg":"trace[994702952] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"184.74625ms","start":"2026-03-10T18:08:59.924388Z","end":"2026-03-10T18:09:00.109134Z","steps":["trace[994702952] 'process raft request' (duration: 63.063808ms)","trace[994702952] 'compare' (duration: 121.14922ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:09:06.231502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.25481ms","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-03-10T18:09:06.231588Z","caller":"traceutil/trace.go:171","msg":"trace[564689611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"236.388173ms","start":"2026-03-10T18:09:05.995182Z","end":"2026-03-10T18:09:06.23157Z","steps":["trace[564689611] 'range keys from in-memory index tree' (duration: 236.111648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:09:06.489807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.262543ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-10T18:09:06.490107Z","caller":"traceutil/trace.go:171","msg":"trace[593060708] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1984; }","duration":"339.907457ms","start":"2026-03-10T18:09:06.150134Z","end":"2026-03-10T18:09:06.490042Z","steps":["trace[593060708] 'read index received' (duration: 130.401429ms)","trace[593060708] 'applied index is now lower than readState.Index' (duration: 209.496808ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:09:06.490147Z","caller":"traceutil/trace.go:171","msg":"trace[160818930] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"255.215636ms","start":"2026-03-10T18:09:06.234894Z","end":"2026-03-10T18:09:06.49011Z","steps":["trace[160818930] 'process raft request' (duration: 255.027263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:09:06.758519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.313817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T18:09:06.758615Z","caller":"traceutil/trace.go:171","msg":"trace[1661060804] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"268.392958ms","start":"2026-03-10T18:09:06.490206Z","end":"2026-03-10T18:09:06.758599Z","steps":["trace[1661060804] 'read index received' (duration: 57.782µs)","trace[1661060804] 'applied index is now lower than readState.Index' (duration: 268.334056ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:09:06.75851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.357366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-10T18:09:06.758635Z","caller":"traceutil/trace.go:171","msg":"trace[2077024374] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"483.565188ms","start":"2026-03-10T18:09:06.275037Z","end":"2026-03-10T18:09:06.758602Z","steps":["trace[2077024374] 'process raft request' (duration: 215.060367ms)","trace[2077024374] 'compare' (duration: 268.133823ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:09:06.758756Z","caller":"traceutil/trace.go:171","msg":"trace[1034898410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1912; }","duration":"608.624432ms","start":"2026-03-10T18:09:06.150077Z","end":"2026-03-10T18:09:06.758702Z","steps":["trace[1034898410] 'agreement among raft nodes before linearized reading' (duration: 340.136623ms)","trace[1034898410] 'range keys from in-memory index tree' (duration: 267.962899ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:09:06.758722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.19938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-10T18:09:06.758808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:09:06.150053Z","time spent":"608.745824ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-10T18:09:06.758837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:09:06.275022Z","time spent":"483.708301ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:09:06.758916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.842333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-10T18:09:06.758969Z","caller":"traceutil/trace.go:171","msg":"trace[1806924574] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1913; }","duration":"148.892184ms","start":"2026-03-10T18:09:06.610062Z","end":"2026-03-10T18:09:06.758954Z","steps":["trace[1806924574] 'agreement among raft nodes before linearized reading' (duration: 148.817802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:09:06.758833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.823675ms","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-03-10T18:09:06.759125Z","caller":"traceutil/trace.go:171","msg":"trace[1770574520] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1913; }","duration":"224.153714ms","start":"2026-03-10T18:09:06.534956Z","end":"2026-03-10T18:09:06.759109Z","steps":["trace[1770574520] 'agreement among raft nodes before linearized reading' (duration: 223.730304ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:09:06.758833Z","caller":"traceutil/trace.go:171","msg":"trace[454842139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1913; }","duration":"534.340264ms","start":"2026-03-10T18:09:06.224474Z","end":"2026-03-10T18:09:06.758814Z","steps":["trace[454842139] 'agreement among raft nodes before linearized reading' (duration: 534.18919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:09:06.75933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:09:06.224457Z","time spent":"534.859295ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-10T18:10:09.22311Z","caller":"traceutil/trace.go:171","msg":"trace[82196307] transaction","detail":"{read_only:false; response_revision:2421; number_of_response:1; }","duration":"110.797637ms","start":"2026-03-10T18:10:09.112278Z","end":"2026-03-10T18:10:09.223076Z","steps":["trace[82196307] 'process raft request' (duration: 110.644893ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:10:09.235408Z","caller":"traceutil/trace.go:171","msg":"trace[562422726] transaction","detail":"{read_only:false; response_revision:2422; number_of_response:1; }","duration":"122.453177ms","start":"2026-03-10T18:10:09.11294Z","end":"2026-03-10T18:10:09.235393Z","steps":["trace[562422726] 'process raft request' (duration: 122.345504ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:10:09.235577Z","caller":"traceutil/trace.go:171","msg":"trace[896375933] linearizableReadLoop","detail":"{readStateIndex:2517; appliedIndex:2514; }","duration":"114.065577ms","start":"2026-03-10T18:10:09.121474Z","end":"2026-03-10T18:10:09.23554Z","steps":["trace[896375933] 'read index received' (duration: 101.529708ms)","trace[896375933] 'applied index is now lower than readState.Index' (duration: 12.533809ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:10:09.235646Z","caller":"traceutil/trace.go:171","msg":"trace[1128429314] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"121.641059ms","start":"2026-03-10T18:10:09.113971Z","end":"2026-03-10T18:10:09.235612Z","steps":["trace[1128429314] 'process raft request' (duration: 121.398054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:10:09.235865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.360774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-10T18:10:09.23591Z","caller":"traceutil/trace.go:171","msg":"trace[68085470] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2423; }","duration":"114.456995ms","start":"2026-03-10T18:10:09.121443Z","end":"2026-03-10T18:10:09.2359Z","steps":["trace[68085470] 'agreement among raft nodes before linearized reading' (duration: 114.253901ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:10:15.095774Z","caller":"traceutil/trace.go:171","msg":"trace[363340364] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"104.159035ms","start":"2026-03-10T18:10:14.991591Z","end":"2026-03-10T18:10:15.09575Z","steps":["trace[363340364] 'process raft request' (duration: 78.118888ms)","trace[363340364] 'compare' (duration: 25.893263ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:10:15.225041Z","caller":"traceutil/trace.go:171","msg":"trace[1009971653] linearizableReadLoop","detail":"{readStateIndex:2543; appliedIndex:2542; }","duration":"103.429669ms","start":"2026-03-10T18:10:15.121582Z","end":"2026-03-10T18:10:15.225011Z","steps":["trace[1009971653] 'read index received' (duration: 80.160072ms)","trace[1009971653] 'applied index is now lower than readState.Index' (duration: 23.268137ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:10:15.225193Z","caller":"traceutil/trace.go:171","msg":"trace[639463683] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"201.647487ms","start":"2026-03-10T18:10:15.023503Z","end":"2026-03-10T18:10:15.22515Z","steps":["trace[639463683] 'process raft request' (duration: 178.3717ms)","trace[639463683] 'compare' (duration: 23.002762ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:10:15.225385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.728106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-10T18:10:15.225459Z","caller":"traceutil/trace.go:171","msg":"trace[1921556369] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2448; }","duration":"103.913779ms","start":"2026-03-10T18:10:15.121529Z","end":"2026-03-10T18:10:15.225443Z","steps":["trace[1921556369] 'agreement among raft nodes before linearized reading' (duration: 103.657814ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:13:49.493782Z","caller":"traceutil/trace.go:171","msg":"trace[416558129] linearizableReadLoop","detail":"{readStateIndex:4316; appliedIndex:4315; }","duration":"109.028513ms","start":"2026-03-10T18:13:49.384725Z","end":"2026-03-10T18:13:49.493754Z","steps":["trace[416558129] 'read index received' (duration: 109.016823ms)","trace[416558129] 'applied index is now lower than readState.Index' (duration: 10.02µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:13:49.493789Z","caller":"traceutil/trace.go:171","msg":"trace[941081313] transaction","detail":"{read_only:false; response_revision:4149; number_of_response:1; }","duration":"111.937745ms","start":"2026-03-10T18:13:49.38183Z","end":"2026-03-10T18:13:49.493768Z","steps":["trace[941081313] 'process raft request' (duration: 111.783022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:13:49.494124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.36324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-03-10T18:13:49.49424Z","caller":"traceutil/trace.go:171","msg":"trace[2019336767] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4149; }","duration":"109.520693ms","start":"2026-03-10T18:13:49.384686Z","end":"2026-03-10T18:13:49.494206Z","steps":["trace[2019336767] 'agreement among raft nodes before linearized reading' (duration: 109.162685ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:13:49.498436Z","caller":"traceutil/trace.go:171","msg":"trace[1592591068] transaction","detail":"{read_only:false; response_revision:4150; number_of_response:1; }","duration":"110.203008ms","start":"2026-03-10T18:13:49.388212Z","end":"2026-03-10T18:13:49.498415Z","steps":["trace[1592591068] 'process raft request' (duration: 109.739548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:13:54.750503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.607446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-03-10T18:13:54.75059Z","caller":"traceutil/trace.go:171","msg":"trace[379538961] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4312; }","duration":"102.721219ms","start":"2026-03-10T18:13:54.647851Z","end":"2026-03-10T18:13:54.750572Z","steps":["trace[379538961] 'range keys from in-memory index tree' (duration: 102.480034ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:13:58.433037Z","caller":"traceutil/trace.go:171","msg":"trace[1107283506] linearizableReadLoop","detail":"{readStateIndex:4620; appliedIndex:4619; }","duration":"208.482063ms","start":"2026-03-10T18:13:58.224538Z","end":"2026-03-10T18:13:58.43302Z","steps":["trace[1107283506] 'read index received' (duration: 208.360821ms)","trace[1107283506] 'applied index is now lower than readState.Index' (duration: 120.402µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:13:58.433118Z","caller":"traceutil/trace.go:171","msg":"trace[117504414] transaction","detail":"{read_only:false; response_revision:4445; number_of_response:1; }","duration":"237.196323ms","start":"2026-03-10T18:13:58.195885Z","end":"2026-03-10T18:13:58.433081Z","steps":["trace[117504414] 'process raft request' (duration: 236.998889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:13:58.433204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.642007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:13:58.433298Z","caller":"traceutil/trace.go:171","msg":"trace[2001545225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4445; }","duration":"208.77779ms","start":"2026-03-10T18:13:58.224502Z","end":"2026-03-10T18:13:58.433279Z","steps":["trace[2001545225] 'agreement among raft nodes before linearized reading' (duration: 208.588526ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:13:58.602106Z","caller":"traceutil/trace.go:171","msg":"trace[133120032] linearizableReadLoop","detail":"{readStateIndex:4621; appliedIndex:4620; }","duration":"168.985334ms","start":"2026-03-10T18:13:58.433085Z","end":"2026-03-10T18:13:58.60207Z","steps":["trace[133120032] 'read index received' (duration: 78.359712ms)","trace[133120032] 'applied index is now lower than readState.Index' (duration: 90.624332ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:13:58.602133Z","caller":"traceutil/trace.go:171","msg":"trace[1064077683] transaction","detail":"{read_only:false; response_revision:4446; number_of_response:1; }","duration":"325.394123ms","start":"2026-03-10T18:13:58.276712Z","end":"2026-03-10T18:13:58.602106Z","steps":["trace[1064077683] 'process raft request' (duration: 234.71405ms)","trace[1064077683] 'compare' (duration: 90.53816ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:13:58.602275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:13:58.276688Z","time spent":"325.508485ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:13:58.602323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.600281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62a2e657-6ea4-4217-bfd7-f0c078b36605\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-03-10T18:13:58.602322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.836213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-10T18:13:58.60237Z","caller":"traceutil/trace.go:171","msg":"trace[1389865297] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62a2e657-6ea4-4217-bfd7-f0c078b36605; range_end:; response_count:1; response_revision:4446; }","duration":"304.695943ms","start":"2026-03-10T18:13:58.297659Z","end":"2026-03-10T18:13:58.602355Z","steps":["trace[1389865297] 'agreement among raft nodes before linearized reading' (duration: 304.53796ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:13:58.602392Z","caller":"traceutil/trace.go:171","msg":"trace[1365295866] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:4446; }","duration":"227.942896ms","start":"2026-03-10T18:13:58.374433Z","end":"2026-03-10T18:13:58.602376Z","steps":["trace[1365295866] 'agreement among raft nodes before linearized reading' (duration: 227.755342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:13:58.60241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:13:58.297637Z","time spent":"304.765244ms","remote":"127.0.0.1:39310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62a2e657-6ea4-4217-bfd7-f0c078b36605\" "} {"level":"warn","ts":"2026-03-10T18:13:58.610631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.135247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:13:58.610668Z","caller":"traceutil/trace.go:171","msg":"trace[98013547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4447; }","duration":"176.211499ms","start":"2026-03-10T18:13:58.434447Z","end":"2026-03-10T18:13:58.610659Z","steps":["trace[98013547] 'agreement among raft nodes before linearized reading' (duration: 176.150707ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:13:58.610851Z","caller":"traceutil/trace.go:171","msg":"trace[1231721622] transaction","detail":"{read_only:false; response_revision:4447; number_of_response:1; }","duration":"170.430276ms","start":"2026-03-10T18:13:58.440414Z","end":"2026-03-10T18:13:58.610844Z","steps":["trace[1231721622] 'process raft request' (duration: 170.135229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:13:58.611083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.469435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62a2e657-6ea4-4217-bfd7-f0c078b36605\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-10T18:13:58.61111Z","caller":"traceutil/trace.go:171","msg":"trace[1043350322] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62a2e657-6ea4-4217-bfd7-f0c078b36605; range_end:; response_count:1; response_revision:4447; }","duration":"170.515197ms","start":"2026-03-10T18:13:58.440588Z","end":"2026-03-10T18:13:58.611103Z","steps":["trace[1043350322] 'agreement among raft nodes before linearized reading' (duration: 170.445005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:13:58.611489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.270298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-10T18:13:58.611519Z","caller":"traceutil/trace.go:171","msg":"trace[1125235167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4447; }","duration":"109.332019ms","start":"2026-03-10T18:13:58.50218Z","end":"2026-03-10T18:13:58.611512Z","steps":["trace[1125235167] 'agreement among raft nodes before linearized reading' (duration: 109.251808ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:13:59.010436Z","caller":"traceutil/trace.go:171","msg":"trace[1246364461] linearizableReadLoop","detail":"{readStateIndex:4634; appliedIndex:4633; }","duration":"216.931132ms","start":"2026-03-10T18:13:58.793481Z","end":"2026-03-10T18:13:59.010412Z","steps":["trace[1246364461] 'read index received' (duration: 216.82341ms)","trace[1246364461] 'applied index is now lower than readState.Index' (duration: 105.032µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:13:59.010515Z","caller":"traceutil/trace.go:171","msg":"trace[1894431600] transaction","detail":"{read_only:false; response_revision:4459; number_of_response:1; }","duration":"217.635377ms","start":"2026-03-10T18:13:58.792844Z","end":"2026-03-10T18:13:59.010479Z","steps":["trace[1894431600] 'process raft request' (duration: 217.363652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:13:59.0108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.253669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-9lnwh-tls\" ","response":"range_response_count:1 size:4432"} {"level":"info","ts":"2026-03-10T18:13:59.010881Z","caller":"traceutil/trace.go:171","msg":"trace[164461994] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-9lnwh-tls; range_end:; response_count:1; response_revision:4459; }","duration":"217.418942ms","start":"2026-03-10T18:13:58.79344Z","end":"2026-03-10T18:13:59.010859Z","steps":["trace[164461994] 'agreement among raft nodes before linearized reading' (duration: 217.062585ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:13:59.018947Z","caller":"traceutil/trace.go:171","msg":"trace[1813328876] transaction","detail":"{read_only:false; response_revision:4460; number_of_response:1; }","duration":"213.651833ms","start":"2026-03-10T18:13:58.805274Z","end":"2026-03-10T18:13:59.018926Z","steps":["trace[1813328876] 'process raft request' (duration: 213.448668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:13:59.019175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.050811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xnhkn\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-03-10T18:13:59.019232Z","caller":"traceutil/trace.go:171","msg":"trace[1231801304] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xnhkn; range_end:; response_count:1; response_revision:4460; }","duration":"206.159593ms","start":"2026-03-10T18:13:58.81306Z","end":"2026-03-10T18:13:59.01922Z","steps":["trace[1231801304] 'agreement among raft nodes before linearized reading' (duration: 205.924808ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:14:00.626096Z","caller":"traceutil/trace.go:171","msg":"trace[892522231] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"123.01033ms","start":"2026-03-10T18:14:00.503054Z","end":"2026-03-10T18:14:00.626065Z","steps":["trace[892522231] 'process raft request' (duration: 106.138522ms)","trace[892522231] 'compare' (duration: 16.757496ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:14:00.626117Z","caller":"traceutil/trace.go:171","msg":"trace[1582661230] linearizableReadLoop","detail":"{readStateIndex:4659; appliedIndex:4658; }","duration":"102.001895ms","start":"2026-03-10T18:14:00.524095Z","end":"2026-03-10T18:14:00.626097Z","steps":["trace[1582661230] 'read index received' (duration: 85.107046ms)","trace[1582661230] 'applied index is now lower than readState.Index' (duration: 16.892279ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:14:00.627619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.500616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777197"} {"level":"info","ts":"2026-03-10T18:14:00.627703Z","caller":"traceutil/trace.go:171","msg":"trace[1868230842] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4485; }","duration":"103.626089ms","start":"2026-03-10T18:14:00.524057Z","end":"2026-03-10T18:14:00.627683Z","steps":["trace[1868230842] 'agreement among raft nodes before linearized reading' (duration: 102.105857ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:14:16.767201Z","caller":"traceutil/trace.go:171","msg":"trace[1817128927] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"130.449108ms","start":"2026-03-10T18:14:16.636728Z","end":"2026-03-10T18:14:16.767177Z","steps":["trace[1817128927] 'process raft request' (duration: 130.299184ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:14:16.798417Z","caller":"traceutil/trace.go:171","msg":"trace[1896623598] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"125.234947ms","start":"2026-03-10T18:14:16.673153Z","end":"2026-03-10T18:14:16.798388Z","steps":["trace[1896623598] 'process raft request' (duration: 125.038583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:14:17.074635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.561644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T18:14:17.074829Z","caller":"traceutil/trace.go:171","msg":"trace[88622512] linearizableReadLoop","detail":"{readStateIndex:5020; appliedIndex:5018; }","duration":"252.226231ms","start":"2026-03-10T18:14:16.822587Z","end":"2026-03-10T18:14:17.074813Z","steps":["trace[88622512] 'read index received' (duration: 134.30019ms)","trace[88622512] 'applied index is now lower than readState.Index' (duration: 117.924861ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:14:17.074833Z","caller":"traceutil/trace.go:171","msg":"trace[1745779983] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"271.443269ms","start":"2026-03-10T18:14:16.803347Z","end":"2026-03-10T18:14:17.07479Z","steps":["trace[1745779983] 'process raft request' (duration: 153.64289ms)","trace[1745779983] 'compare' (duration: 117.439471ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:14:17.074948Z","caller":"traceutil/trace.go:171","msg":"trace[1089034063] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"271.546351ms","start":"2026-03-10T18:14:16.803376Z","end":"2026-03-10T18:14:17.074923Z","steps":["trace[1089034063] 'process raft request' (duration: 271.349196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:14:17.074997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.405125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6f7af3b7-052c-4b30-ba91-57ff58ae088b\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-03-10T18:14:17.075052Z","caller":"traceutil/trace.go:171","msg":"trace[1581986149] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6f7af3b7-052c-4b30-ba91-57ff58ae088b; range_end:; response_count:1; response_revision:4834; }","duration":"252.486396ms","start":"2026-03-10T18:14:16.822556Z","end":"2026-03-10T18:14:17.075043Z","steps":["trace[1581986149] 'agreement among raft nodes before linearized reading' (duration: 252.342863ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:15:07.132151Z","caller":"traceutil/trace.go:171","msg":"trace[133066363] linearizableReadLoop","detail":"{readStateIndex:5498; appliedIndex:5497; }","duration":"109.518991ms","start":"2026-03-10T18:15:07.022608Z","end":"2026-03-10T18:15:07.132127Z","steps":["trace[133066363] 'read index received' (duration: 109.374788ms)","trace[133066363] 'applied index is now lower than readState.Index' (duration: 143.583µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:15:07.132275Z","caller":"traceutil/trace.go:171","msg":"trace[2048050573] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"127.402028ms","start":"2026-03-10T18:15:07.004833Z","end":"2026-03-10T18:15:07.132235Z","steps":["trace[2048050573] 'process raft request' (duration: 127.193854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:15:07.132325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.703693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:15:07.132398Z","caller":"traceutil/trace.go:171","msg":"trace[30727842] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5300; }","duration":"107.816494ms","start":"2026-03-10T18:15:07.024574Z","end":"2026-03-10T18:15:07.132391Z","steps":["trace[30727842] 'agreement among raft nodes before linearized reading' (duration: 107.714402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:15:07.132413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.971525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6084"} {"level":"info","ts":"2026-03-10T18:15:07.132445Z","caller":"traceutil/trace.go:171","msg":"trace[1176453865] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5300; }","duration":"105.011405ms","start":"2026-03-10T18:15:07.027424Z","end":"2026-03-10T18:15:07.132435Z","steps":["trace[1176453865] 'agreement among raft nodes before linearized reading' (duration: 104.952094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:15:07.132347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.743275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-03-10T18:15:07.132529Z","caller":"traceutil/trace.go:171","msg":"trace[1679192744] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5300; }","duration":"109.96914ms","start":"2026-03-10T18:15:07.022552Z","end":"2026-03-10T18:15:07.132521Z","steps":["trace[1679192744] 'agreement among raft nodes before linearized reading' (duration: 109.708465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:15:07.132547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.510818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:15:07.132614Z","caller":"traceutil/trace.go:171","msg":"trace[1337281446] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5300; }","duration":"107.609721ms","start":"2026-03-10T18:15:07.02498Z","end":"2026-03-10T18:15:07.132589Z","steps":["trace[1337281446] 'agreement among raft nodes before linearized reading' (duration: 107.530329ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:16:27.598604Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2802} {"level":"info","ts":"2026-03-10T18:16:27.656501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2802,"took":"56.364479ms","hash":447281379,"current-db-size-bytes":42389504,"current-db-size":"42 MB","current-db-size-in-use-bytes":31891456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-10T18:16:27.656557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":447281379,"revision":2802,"compact-revision":-1} {"level":"info","ts":"2026-03-10T18:20:16.855077Z","caller":"traceutil/trace.go:171","msg":"trace[1139379538] transaction","detail":"{read_only:false; response_revision:7452; number_of_response:1; }","duration":"277.085393ms","start":"2026-03-10T18:20:16.577966Z","end":"2026-03-10T18:20:16.855051Z","steps":["trace[1139379538] 'process raft request' (duration: 276.90355ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:20:16.99763Z","caller":"traceutil/trace.go:171","msg":"trace[1794247671] transaction","detail":"{read_only:false; response_revision:7453; number_of_response:1; }","duration":"114.773048ms","start":"2026-03-10T18:20:16.882827Z","end":"2026-03-10T18:20:16.9976Z","steps":["trace[1794247671] 'process raft request' (duration: 114.580234ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:20:16.998888Z","caller":"traceutil/trace.go:171","msg":"trace[2103541277] transaction","detail":"{read_only:false; response_revision:7454; number_of_response:1; }","duration":"114.015012ms","start":"2026-03-10T18:20:16.884846Z","end":"2026-03-10T18:20:16.998861Z","steps":["trace[2103541277] 'process raft request' (duration: 113.748687ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:20:16.999024Z","caller":"traceutil/trace.go:171","msg":"trace[629854920] linearizableReadLoop","detail":"{readStateIndex:7727; appliedIndex:7724; }","duration":"106.417283ms","start":"2026-03-10T18:20:16.892586Z","end":"2026-03-10T18:20:16.999004Z","steps":["trace[629854920] 'read index received' (duration: 104.832039ms)","trace[629854920] 'applied index is now lower than readState.Index' (duration: 1.584114ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:20:16.999145Z","caller":"traceutil/trace.go:171","msg":"trace[208935644] transaction","detail":"{read_only:false; response_revision:7455; number_of_response:1; }","duration":"114.099744ms","start":"2026-03-10T18:20:16.885007Z","end":"2026-03-10T18:20:16.999106Z","steps":["trace[208935644] 'process raft request' (duration: 113.775487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:20:16.999237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.627567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xnhkn\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-03-10T18:20:16.999309Z","caller":"traceutil/trace.go:171","msg":"trace[1153511928] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xnhkn; range_end:; response_count:1; response_revision:7455; }","duration":"106.704668ms","start":"2026-03-10T18:20:16.89256Z","end":"2026-03-10T18:20:16.999265Z","steps":["trace[1153511928] 'agreement among raft nodes before linearized reading' (duration: 106.514784ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:20:29.127899Z","caller":"traceutil/trace.go:171","msg":"trace[516816521] transaction","detail":"{read_only:false; response_revision:7507; number_of_response:1; }","duration":"215.135693ms","start":"2026-03-10T18:20:28.912732Z","end":"2026-03-10T18:20:29.127868Z","steps":["trace[516816521] 'process raft request' (duration: 214.931069ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:20:29.239207Z","caller":"traceutil/trace.go:171","msg":"trace[280918568] transaction","detail":"{read_only:false; response_revision:7510; number_of_response:1; }","duration":"105.893321ms","start":"2026-03-10T18:20:29.133291Z","end":"2026-03-10T18:20:29.239184Z","steps":["trace[280918568] 'process raft request' (duration: 105.765318ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:20:29.896676Z","caller":"traceutil/trace.go:171","msg":"trace[1537244714] transaction","detail":"{read_only:false; response_revision:7514; number_of_response:1; }","duration":"142.348127ms","start":"2026-03-10T18:20:29.754298Z","end":"2026-03-10T18:20:29.896646Z","steps":["trace[1537244714] 'process raft request' (duration: 142.158773ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:20:56.630288Z","caller":"traceutil/trace.go:171","msg":"trace[1118532822] transaction","detail":"{read_only:false; response_revision:7699; number_of_response:1; }","duration":"269.624326ms","start":"2026-03-10T18:20:56.360638Z","end":"2026-03-10T18:20:56.630262Z","steps":["trace[1118532822] 'process raft request' (duration: 269.33044ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:21:00.022305Z","caller":"traceutil/trace.go:171","msg":"trace[915212677] transaction","detail":"{read_only:false; response_revision:7715; number_of_response:1; }","duration":"145.983502ms","start":"2026-03-10T18:20:59.876294Z","end":"2026-03-10T18:21:00.022277Z","steps":["trace[915212677] 'process raft request' (duration: 145.818559ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:21:09.515654Z","caller":"traceutil/trace.go:171","msg":"trace[221348594] transaction","detail":"{read_only:false; response_revision:7809; number_of_response:1; }","duration":"117.73254ms","start":"2026-03-10T18:21:09.397885Z","end":"2026-03-10T18:21:09.515617Z","steps":["trace[221348594] 'process raft request' (duration: 117.634878ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:21:09.515861Z","caller":"traceutil/trace.go:171","msg":"trace[883761087] transaction","detail":"{read_only:false; response_revision:7808; number_of_response:1; }","duration":"142.98732ms","start":"2026-03-10T18:21:09.372827Z","end":"2026-03-10T18:21:09.515814Z","steps":["trace[883761087] 'process raft request' (duration: 110.926697ms)","trace[883761087] 'compare' (duration: 31.639974ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:21:09.641111Z","caller":"traceutil/trace.go:171","msg":"trace[1850822340] transaction","detail":"{read_only:false; response_revision:7811; number_of_response:1; }","duration":"119.802184ms","start":"2026-03-10T18:21:09.52128Z","end":"2026-03-10T18:21:09.641082Z","steps":["trace[1850822340] 'process raft request' (duration: 98.411304ms)","trace[1850822340] 'compare' (duration: 21.227615ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:21:09.641347Z","caller":"traceutil/trace.go:171","msg":"trace[787257160] linearizableReadLoop","detail":"{readStateIndex:8100; appliedIndex:8098; }","duration":"115.596746ms","start":"2026-03-10T18:21:09.525711Z","end":"2026-03-10T18:21:09.641308Z","steps":["trace[787257160] 'read index received' (duration: 4.363042ms)","trace[787257160] 'applied index is now lower than readState.Index' (duration: 111.231084ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:21:09.641381Z","caller":"traceutil/trace.go:171","msg":"trace[213930722] transaction","detail":"{read_only:false; response_revision:7812; number_of_response:1; }","duration":"114.656376ms","start":"2026-03-10T18:21:09.526691Z","end":"2026-03-10T18:21:09.641347Z","steps":["trace[213930722] 'process raft request' (duration: 114.34683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:21:09.641731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.43679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-03-10T18:21:09.641814Z","caller":"traceutil/trace.go:171","msg":"trace[2077551564] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:7812; }","duration":"103.554673ms","start":"2026-03-10T18:21:09.538231Z","end":"2026-03-10T18:21:09.641785Z","steps":["trace[2077551564] 'agreement among raft nodes before linearized reading' (duration: 103.314408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:21:09.641924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.194258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:227436"} {"level":"info","ts":"2026-03-10T18:21:09.642007Z","caller":"traceutil/trace.go:171","msg":"trace[710721923] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:7812; }","duration":"116.322751ms","start":"2026-03-10T18:21:09.525663Z","end":"2026-03-10T18:21:09.641986Z","steps":["trace[710721923] 'agreement among raft nodes before linearized reading' (duration: 115.829911ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:21:09.839899Z","caller":"traceutil/trace.go:171","msg":"trace[1711548151] transaction","detail":"{read_only:false; response_revision:7813; number_of_response:1; }","duration":"117.501924ms","start":"2026-03-10T18:21:09.722373Z","end":"2026-03-10T18:21:09.839875Z","steps":["trace[1711548151] 'process raft request' (duration: 117.363221ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:21:27.604487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5816} {"level":"info","ts":"2026-03-10T18:21:27.690312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5816,"took":"83.978602ms","hash":4252423344,"current-db-size-bytes":44232704,"current-db-size":"44 MB","current-db-size-in-use-bytes":24240128,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-10T18:21:27.690367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4252423344,"revision":5816,"compact-revision":2802} {"level":"info","ts":"2026-03-10T18:24:57.624813Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"79e65b4197a2a1a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-10T18:24:57.631578Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-10T18:24:57.631653Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-10T18:26:08.65633Z","caller":"traceutil/trace.go:171","msg":"trace[956324584] linearizableReadLoop","detail":"{readStateIndex:10613; appliedIndex:10612; }","duration":"103.11211ms","start":"2026-03-10T18:26:08.553193Z","end":"2026-03-10T18:26:08.656306Z","steps":["trace[956324584] 'read index received' (duration: 103.091089ms)","trace[956324584] 'applied index is now lower than readState.Index' (duration: 19.991µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:26:08.65642Z","caller":"traceutil/trace.go:171","msg":"trace[438589891] transaction","detail":"{read_only:false; response_revision:10249; number_of_response:1; }","duration":"103.312704ms","start":"2026-03-10T18:26:08.553071Z","end":"2026-03-10T18:26:08.656384Z","steps":["trace[438589891] 'process raft request' (duration: 103.052358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:26:08.656474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.260163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:26:08.656518Z","caller":"traceutil/trace.go:171","msg":"trace[1882521768] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:10249; }","duration":"103.354845ms","start":"2026-03-10T18:26:08.553149Z","end":"2026-03-10T18:26:08.656504Z","steps":["trace[1882521768] 'agreement among raft nodes before linearized reading' (duration: 103.262793ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:26:08.708555Z","caller":"traceutil/trace.go:171","msg":"trace[1613673503] transaction","detail":"{read_only:false; response_revision:10250; number_of_response:1; }","duration":"152.996828ms","start":"2026-03-10T18:26:08.555514Z","end":"2026-03-10T18:26:08.70851Z","steps":["trace[1613673503] 'process raft request' (duration: 134.725524ms)","trace[1613673503] 'compare' (duration: 17.975998ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:26:08.70903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.97975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-03-10T18:26:08.709157Z","caller":"traceutil/trace.go:171","msg":"trace[312923945] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10250; }","duration":"154.162963ms","start":"2026-03-10T18:26:08.554965Z","end":"2026-03-10T18:26:08.709128Z","steps":["trace[312923945] 'agreement among raft nodes before linearized reading' (duration: 153.54121ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:26:27.611371Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8018} {"level":"info","ts":"2026-03-10T18:26:27.702614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8018,"took":"88.621823ms","hash":36681693,"current-db-size-bytes":44232704,"current-db-size":"44 MB","current-db-size-in-use-bytes":23535616,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-10T18:26:27.702692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":36681693,"revision":8018,"compact-revision":5816} {"level":"info","ts":"2026-03-10T18:31:27.617164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10389} {"level":"info","ts":"2026-03-10T18:31:27.696272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10389,"took":"76.82266ms","hash":2551398492,"current-db-size-bytes":44232704,"current-db-size":"44 MB","current-db-size-in-use-bytes":24915968,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-10T18:31:27.696341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2551398492,"revision":10389,"compact-revision":8018} {"level":"info","ts":"2026-03-10T18:32:06.039332Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000032a0.wal"} {"level":"info","ts":"2026-03-10T18:32:33.641712Z","caller":"traceutil/trace.go:171","msg":"trace[1539628974] transaction","detail":"{read_only:false; response_revision:12649; number_of_response:1; }","duration":"126.676966ms","start":"2026-03-10T18:32:33.514996Z","end":"2026-03-10T18:32:33.641673Z","steps":["trace[1539628974] 'process raft request' (duration: 126.434921ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:32:34.580276Z","caller":"traceutil/trace.go:171","msg":"trace[2028535695] transaction","detail":"{read_only:false; response_revision:12654; number_of_response:1; }","duration":"151.020392ms","start":"2026-03-10T18:32:34.429228Z","end":"2026-03-10T18:32:34.580248Z","steps":["trace[2028535695] 'process raft request' (duration: 150.813708ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:32:46.752687Z","caller":"traceutil/trace.go:171","msg":"trace[383358214] transaction","detail":"{read_only:false; response_revision:12830; number_of_response:1; }","duration":"157.036789ms","start":"2026-03-10T18:32:46.595619Z","end":"2026-03-10T18:32:46.752656Z","steps":["trace[383358214] 'process raft request' (duration: 156.740053ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:32:46.885172Z","caller":"traceutil/trace.go:171","msg":"trace[607079546] linearizableReadLoop","detail":"{readStateIndex:13293; appliedIndex:13291; }","duration":"111.672988ms","start":"2026-03-10T18:32:46.773461Z","end":"2026-03-10T18:32:46.885134Z","steps":["trace[607079546] 'read index received' (duration: 38.720722ms)","trace[607079546] 'applied index is now lower than readState.Index' (duration: 72.950796ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:32:46.885237Z","caller":"traceutil/trace.go:171","msg":"trace[559339015] transaction","detail":"{read_only:false; response_revision:12832; number_of_response:1; }","duration":"121.979437ms","start":"2026-03-10T18:32:46.763227Z","end":"2026-03-10T18:32:46.885207Z","steps":["trace[559339015] 'process raft request' (duration: 121.66319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:32:46.885539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.044886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xnhkn\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-03-10T18:32:46.885607Z","caller":"traceutil/trace.go:171","msg":"trace[351508058] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xnhkn; range_end:; response_count:1; response_revision:12832; }","duration":"112.178649ms","start":"2026-03-10T18:32:46.773406Z","end":"2026-03-10T18:32:46.885585Z","steps":["trace[351508058] 'agreement among raft nodes before linearized reading' (duration: 111.906113ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:36:27.623859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12244} {"level":"info","ts":"2026-03-10T18:36:27.7146Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12244,"took":"88.248621ms","hash":3430124876,"current-db-size-bytes":44232704,"current-db-size":"44 MB","current-db-size-in-use-bytes":28864512,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-10T18:36:27.714664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3430124876,"revision":12244,"compact-revision":10389} {"level":"info","ts":"2026-03-10T18:38:59.73844Z","caller":"traceutil/trace.go:171","msg":"trace[1859402030] transaction","detail":"{read_only:false; response_revision:15772; number_of_response:1; }","duration":"115.918727ms","start":"2026-03-10T18:38:59.622488Z","end":"2026-03-10T18:38:59.738407Z","steps":["trace[1859402030] 'process raft request' (duration: 115.728013ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:40:15.262261Z","caller":"traceutil/trace.go:171","msg":"trace[360434014] transaction","detail":"{read_only:false; response_revision:16339; number_of_response:1; }","duration":"121.41661ms","start":"2026-03-10T18:40:15.140819Z","end":"2026-03-10T18:40:15.262236Z","steps":["trace[360434014] 'process raft request' (duration: 118.015368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:40:15.702817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.160185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T18:40:15.703088Z","caller":"traceutil/trace.go:171","msg":"trace[690877437] linearizableReadLoop","detail":"{readStateIndex:16918; appliedIndex:16917; }","duration":"259.089176ms","start":"2026-03-10T18:40:15.443969Z","end":"2026-03-10T18:40:15.703058Z","steps":["trace[690877437] 'read index received' (duration: 121.571323ms)","trace[690877437] 'applied index is now lower than readState.Index' (duration: 137.513942ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:40:15.703163Z","caller":"traceutil/trace.go:171","msg":"trace[687497918] transaction","detail":"{read_only:false; response_revision:16341; number_of_response:1; }","duration":"264.50971ms","start":"2026-03-10T18:40:15.438636Z","end":"2026-03-10T18:40:15.703146Z","steps":["trace[687497918] 'process raft request' (duration: 126.925866ms)","trace[687497918] 'compare' (duration: 136.522842ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:40:15.703361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.368582ms","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-03-10T18:40:15.703406Z","caller":"traceutil/trace.go:171","msg":"trace[457402200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16343; }","duration":"259.443824ms","start":"2026-03-10T18:40:15.443949Z","end":"2026-03-10T18:40:15.703392Z","steps":["trace[457402200] 'agreement among raft nodes before linearized reading' (duration: 259.256189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:40:16.375124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.155388ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-10T18:40:16.375237Z","caller":"traceutil/trace.go:171","msg":"trace[1575314990] linearizableReadLoop","detail":"{readStateIndex:16922; appliedIndex:16921; }","duration":"282.81578ms","start":"2026-03-10T18:40:16.092404Z","end":"2026-03-10T18:40:16.37522Z","steps":["trace[1575314990] 'read index received' (duration: 40.021µs)","trace[1575314990] 'applied index is now lower than readState.Index' (duration: 282.774179ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:40:16.375646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.223649ms","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-03-10T18:40:16.375752Z","caller":"traceutil/trace.go:171","msg":"trace[94931608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16344; }","duration":"283.370733ms","start":"2026-03-10T18:40:16.092358Z","end":"2026-03-10T18:40:16.375729Z","steps":["trace[94931608] 'agreement among raft nodes before linearized reading' (duration: 283.107976ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:40:16.594432Z","caller":"traceutil/trace.go:171","msg":"trace[943910384] linearizableReadLoop","detail":"{readStateIndex:16923; appliedIndex:16922; }","duration":"219.112837ms","start":"2026-03-10T18:40:16.375263Z","end":"2026-03-10T18:40:16.594375Z","steps":["trace[943910384] 'read index received' (duration: 218.779729ms)","trace[943910384] 'applied index is now lower than readState.Index' (duration: 331.208µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:40:16.594487Z","caller":"traceutil/trace.go:171","msg":"trace[134848321] transaction","detail":"{read_only:false; response_revision:16345; number_of_response:1; }","duration":"468.412745ms","start":"2026-03-10T18:40:16.12604Z","end":"2026-03-10T18:40:16.594452Z","steps":["trace[134848321] 'process raft request' (duration: 468.058537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:40:16.594835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.606227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:40:16.59495Z","caller":"traceutil/trace.go:171","msg":"trace[717231736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16345; }","duration":"371.7682ms","start":"2026-03-10T18:40:16.223142Z","end":"2026-03-10T18:40:16.594911Z","steps":["trace[717231736] 'agreement among raft nodes before linearized reading' (duration: 371.442394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:40:16.595036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:40:16.12602Z","time spent":"468.778123ms","remote":"127.0.0.1:39602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:40:16.595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:40:16.223122Z","time spent":"371.869993ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-10T18:40:16.605253Z","caller":"traceutil/trace.go:171","msg":"trace[1323297350] transaction","detail":"{read_only:false; response_revision:16346; number_of_response:1; }","duration":"225.42676ms","start":"2026-03-10T18:40:16.379801Z","end":"2026-03-10T18:40:16.605227Z","steps":["trace[1323297350] 'process raft request' (duration: 225.187435ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:40:16.605311Z","caller":"traceutil/trace.go:171","msg":"trace[1056942290] transaction","detail":"{read_only:false; response_revision:16347; number_of_response:1; }","duration":"200.588983ms","start":"2026-03-10T18:40:16.404697Z","end":"2026-03-10T18:40:16.605286Z","steps":["trace[1056942290] 'process raft request' (duration: 200.45812ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:41:27.63216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14666} {"level":"info","ts":"2026-03-10T18:41:27.72776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14666,"took":"92.489355ms","hash":740861497,"current-db-size-bytes":44675072,"current-db-size":"45 MB","current-db-size-in-use-bytes":30457856,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-10T18:41:27.727844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":740861497,"revision":14666,"compact-revision":12244} {"level":"info","ts":"2026-03-10T18:45:03.229559Z","caller":"traceutil/trace.go:171","msg":"trace[815846298] transaction","detail":"{read_only:false; response_revision:18457; number_of_response:1; }","duration":"113.46954ms","start":"2026-03-10T18:45:03.116029Z","end":"2026-03-10T18:45:03.229499Z","steps":["trace[815846298] 'process raft request' (duration: 113.255555ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:45:24.928235Z","caller":"traceutil/trace.go:171","msg":"trace[2116184310] transaction","detail":"{read_only:false; response_revision:18572; number_of_response:1; }","duration":"152.553436ms","start":"2026-03-10T18:45:24.775658Z","end":"2026-03-10T18:45:24.928211Z","steps":["trace[2116184310] 'process raft request' (duration: 152.392323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:45:25.214828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.3176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:45:25.214925Z","caller":"traceutil/trace.go:171","msg":"trace[551557216] linearizableReadLoop","detail":"{readStateIndex:19230; appliedIndex:19228; }","duration":"287.676114ms","start":"2026-03-10T18:45:24.927234Z","end":"2026-03-10T18:45:25.21491Z","steps":["trace[551557216] 'read index received' (duration: 824.377µs)","trace[551557216] 'applied index is now lower than readState.Index' (duration: 286.850697ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:45:25.215005Z","caller":"traceutil/trace.go:171","msg":"trace[246999725] transaction","detail":"{read_only:false; response_revision:18573; number_of_response:1; }","duration":"395.189459ms","start":"2026-03-10T18:45:24.819804Z","end":"2026-03-10T18:45:25.214993Z","steps":["trace[246999725] 'process raft request' (duration: 252.599574ms)","trace[246999725] 'compare' (duration: 142.169816ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:45:25.215089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:45:24.819783Z","time spent":"395.25084ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:45:25.215312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.055763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-10T18:45:25.215407Z","caller":"traceutil/trace.go:171","msg":"trace[359119100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18573; }","duration":"288.165135ms","start":"2026-03-10T18:45:24.927219Z","end":"2026-03-10T18:45:25.215384Z","steps":["trace[359119100] 'agreement among raft nodes before linearized reading' (duration: 287.880388ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:45:25.516307Z","caller":"traceutil/trace.go:171","msg":"trace[1967811830] transaction","detail":"{read_only:false; response_revision:18574; number_of_response:1; }","duration":"297.562424ms","start":"2026-03-10T18:45:25.218716Z","end":"2026-03-10T18:45:25.516278Z","steps":["trace[1967811830] 'process raft request' (duration: 297.316969ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:45:25.516293Z","caller":"traceutil/trace.go:171","msg":"trace[270215192] linearizableReadLoop","detail":"{readStateIndex:19231; appliedIndex:19230; }","duration":"214.528929ms","start":"2026-03-10T18:45:25.301736Z","end":"2026-03-10T18:45:25.516265Z","steps":["trace[270215192] 'read index received' (duration: 214.155881ms)","trace[270215192] 'applied index is now lower than readState.Index' (duration: 371.768µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:45:25.516617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.857685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-10T18:45:25.516711Z","caller":"traceutil/trace.go:171","msg":"trace[867424284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18574; }","duration":"214.988217ms","start":"2026-03-10T18:45:25.301701Z","end":"2026-03-10T18:45:25.516689Z","steps":["trace[867424284] 'agreement among raft nodes before linearized reading' (duration: 214.749002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:45:25.516763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.531939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-10T18:45:25.516808Z","caller":"traceutil/trace.go:171","msg":"trace[1562447002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18574; }","duration":"198.634472ms","start":"2026-03-10T18:45:25.318165Z","end":"2026-03-10T18:45:25.516799Z","steps":["trace[1562447002] 'agreement among raft nodes before linearized reading' (duration: 198.472348ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:45:25.516808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.784906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-10T18:45:25.516857Z","caller":"traceutil/trace.go:171","msg":"trace[1359477567] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:18574; }","duration":"139.865298ms","start":"2026-03-10T18:45:25.376975Z","end":"2026-03-10T18:45:25.51684Z","steps":["trace[1359477567] 'agreement among raft nodes before linearized reading' (duration: 139.718425ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:45:25.635Z","caller":"traceutil/trace.go:171","msg":"trace[962764850] linearizableReadLoop","detail":"{readStateIndex:19233; appliedIndex:19231; }","duration":"106.104164ms","start":"2026-03-10T18:45:25.528873Z","end":"2026-03-10T18:45:25.634977Z","steps":["trace[962764850] 'read index received' (duration: 63.519423ms)","trace[962764850] 'applied index is now lower than readState.Index' (duration: 42.583511ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:45:25.63503Z","caller":"traceutil/trace.go:171","msg":"trace[1735559321] transaction","detail":"{read_only:false; response_revision:18576; number_of_response:1; }","duration":"113.575962ms","start":"2026-03-10T18:45:25.521427Z","end":"2026-03-10T18:45:25.635003Z","steps":["trace[1735559321] 'process raft request' (duration: 113.447449ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:45:25.635086Z","caller":"traceutil/trace.go:171","msg":"trace[445087971] transaction","detail":"{read_only:false; response_revision:18575; number_of_response:1; }","duration":"114.027711ms","start":"2026-03-10T18:45:25.521025Z","end":"2026-03-10T18:45:25.635052Z","steps":["trace[445087971] 'process raft request' (duration: 71.280927ms)","trace[445087971] 'compare' (duration: 42.324545ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:45:25.635724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.252598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:45:25.635804Z","caller":"traceutil/trace.go:171","msg":"trace[2104161909] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:18576; }","duration":"106.927583ms","start":"2026-03-10T18:45:25.528856Z","end":"2026-03-10T18:45:25.635784Z","steps":["trace[2104161909] 'agreement among raft nodes before linearized reading' (duration: 106.226997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:45:26.104609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.503048ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-10T18:45:26.104942Z","caller":"traceutil/trace.go:171","msg":"trace[125968883] linearizableReadLoop","detail":"{readStateIndex:19234; appliedIndex:19233; }","duration":"306.083604ms","start":"2026-03-10T18:45:25.798821Z","end":"2026-03-10T18:45:26.104904Z","steps":["trace[125968883] 'read index received' (duration: 4.214509ms)","trace[125968883] 'applied index is now lower than readState.Index' (duration: 301.866495ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:45:26.109722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.867215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-10T18:45:26.109792Z","caller":"traceutil/trace.go:171","msg":"trace[1133673127] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18577; }","duration":"310.991148ms","start":"2026-03-10T18:45:25.798783Z","end":"2026-03-10T18:45:26.109774Z","steps":["trace[1133673127] 'agreement among raft nodes before linearized reading' (duration: 306.255818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:45:26.10983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:45:25.798767Z","time spent":"311.053289ms","remote":"127.0.0.1:33608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-03-10T18:46:27.645471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17071} {"level":"info","ts":"2026-03-10T18:46:27.757545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17071,"took":"109.259631ms","hash":2152376816,"current-db-size-bytes":46370816,"current-db-size":"46 MB","current-db-size-in-use-bytes":26902528,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-10T18:46:27.757628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2152376816,"revision":17071,"compact-revision":14666} {"level":"info","ts":"2026-03-10T18:46:54.502326Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"79e65b4197a2a1a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-10T18:46:54.50735Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-03-10T18:46:54.507464Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-03-10T18:49:06.5963Z","caller":"traceutil/trace.go:171","msg":"trace[272288460] transaction","detail":"{read_only:false; response_revision:20777; number_of_response:1; }","duration":"256.949447ms","start":"2026-03-10T18:49:06.339333Z","end":"2026-03-10T18:49:06.596283Z","steps":["trace[272288460] 'process raft request' (duration: 256.788853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:49:06.74449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.828828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:49:06.744679Z","caller":"traceutil/trace.go:171","msg":"trace[1419790926] linearizableReadLoop","detail":"{readStateIndex:21509; appliedIndex:21508; }","duration":"142.844076ms","start":"2026-03-10T18:49:06.601808Z","end":"2026-03-10T18:49:06.744652Z","steps":["trace[1419790926] 'read index received' (duration: 14.783373ms)","trace[1419790926] 'applied index is now lower than readState.Index' (duration: 128.058993ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:49:06.744718Z","caller":"traceutil/trace.go:171","msg":"trace[1365476326] transaction","detail":"{read_only:false; response_revision:20778; number_of_response:1; }","duration":"143.577964ms","start":"2026-03-10T18:49:06.601095Z","end":"2026-03-10T18:49:06.744673Z","steps":["trace[1365476326] 'process raft request' (duration: 15.405007ms)","trace[1365476326] 'compare' (duration: 127.562373ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:49:06.744956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.135903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5182"} {"level":"info","ts":"2026-03-10T18:49:06.745024Z","caller":"traceutil/trace.go:171","msg":"trace[1481544558] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:20778; }","duration":"143.200075ms","start":"2026-03-10T18:49:06.601804Z","end":"2026-03-10T18:49:06.745004Z","steps":["trace[1481544558] 'agreement among raft nodes before linearized reading' (duration: 142.9618ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:49:06.745878Z","caller":"traceutil/trace.go:171","msg":"trace[2032191047] transaction","detail":"{read_only:false; response_revision:20779; number_of_response:1; }","duration":"133.04834ms","start":"2026-03-10T18:49:06.612815Z","end":"2026-03-10T18:49:06.745863Z","steps":["trace[2032191047] 'process raft request' (duration: 132.799435ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:51:27.650805Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19148} {"level":"info","ts":"2026-03-10T18:51:27.86995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19148,"took":"215.483848ms","hash":2396573536,"current-db-size-bytes":49299456,"current-db-size":"49 MB","current-db-size-in-use-bytes":39325696,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-03-10T18:51:27.87Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2396573536,"revision":19148,"compact-revision":17071} {"level":"info","ts":"2026-03-10T18:52:37.960399Z","caller":"traceutil/trace.go:171","msg":"trace[1802257232] transaction","detail":"{read_only:false; response_revision:22820; number_of_response:1; }","duration":"210.023632ms","start":"2026-03-10T18:52:37.750337Z","end":"2026-03-10T18:52:37.960361Z","steps":["trace[1802257232] 'process raft request' (duration: 209.745006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:38.156343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.885124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:52:38.156853Z","caller":"traceutil/trace.go:171","msg":"trace[996187921] transaction","detail":"{read_only:false; response_revision:22821; number_of_response:1; }","duration":"192.00571ms","start":"2026-03-10T18:52:37.964823Z","end":"2026-03-10T18:52:38.156829Z","steps":["trace[996187921] 'process raft request' (duration: 36.530354ms)","trace[996187921] 'compare' (duration: 154.763311ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:52:38.157049Z","caller":"traceutil/trace.go:171","msg":"trace[1100795841] transaction","detail":"{read_only:false; response_revision:22823; number_of_response:1; }","duration":"191.310125ms","start":"2026-03-10T18:52:37.965702Z","end":"2026-03-10T18:52:38.157012Z","steps":["trace[1100795841] 'process raft request' (duration: 190.804214ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:38.15721Z","caller":"traceutil/trace.go:171","msg":"trace[458737434] transaction","detail":"{read_only:false; response_revision:22822; number_of_response:1; }","duration":"192.052311ms","start":"2026-03-10T18:52:37.965142Z","end":"2026-03-10T18:52:38.157195Z","steps":["trace[458737434] 'process raft request' (duration: 191.311045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:42.346253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.590251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-10T18:52:42.346322Z","caller":"traceutil/trace.go:171","msg":"trace[338402333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22847; }","duration":"166.700573ms","start":"2026-03-10T18:52:42.179604Z","end":"2026-03-10T18:52:42.346305Z","steps":["trace[338402333] 'range keys from in-memory index tree' (duration: 166.332015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:42.346317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.246179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:42.346406Z","caller":"traceutil/trace.go:171","msg":"trace[1386300197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22847; }","duration":"121.373612ms","start":"2026-03-10T18:52:42.225011Z","end":"2026-03-10T18:52:42.346385Z","steps":["trace[1386300197] 'range keys from in-memory index tree' (duration: 121.172528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:42.346519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.703195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-10T18:52:42.346577Z","caller":"traceutil/trace.go:171","msg":"trace[1068698400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22847; }","duration":"166.782906ms","start":"2026-03-10T18:52:42.179782Z","end":"2026-03-10T18:52:42.346564Z","steps":["trace[1068698400] 'range keys from in-memory index tree' (duration: 166.579992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:42.346595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.845147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-10T18:52:42.347626Z","caller":"traceutil/trace.go:171","msg":"trace[622364658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22847; }","duration":"167.902489ms","start":"2026-03-10T18:52:42.179706Z","end":"2026-03-10T18:52:42.347608Z","steps":["trace[622364658] 'range keys from in-memory index tree' (duration: 166.367756ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:42.661901Z","caller":"traceutil/trace.go:171","msg":"trace[1927760323] transaction","detail":"{read_only:false; response_revision:22849; number_of_response:1; }","duration":"309.462439ms","start":"2026-03-10T18:52:42.352412Z","end":"2026-03-10T18:52:42.661875Z","steps":["trace[1927760323] 'process raft request' (duration: 285.762027ms)","trace[1927760323] 'compare' (duration: 23.419106ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:52:42.662026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.352395Z","time spent":"309.574002ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:52:42.967854Z","caller":"traceutil/trace.go:171","msg":"trace[514866082] transaction","detail":"{read_only:false; response_revision:22850; number_of_response:1; }","duration":"615.237541ms","start":"2026-03-10T18:52:42.352587Z","end":"2026-03-10T18:52:42.967824Z","steps":["trace[514866082] 'process raft request' (duration: 598.948697ms)","trace[514866082] 'compare' (duration: 15.088799ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:52:42.967884Z","caller":"traceutil/trace.go:171","msg":"trace[1882566292] transaction","detail":"{read_only:false; response_revision:22853; number_of_response:1; }","duration":"561.300948ms","start":"2026-03-10T18:52:42.406551Z","end":"2026-03-10T18:52:42.967852Z","steps":["trace[1882566292] 'process raft request' (duration: 561.163545ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:42.967902Z","caller":"traceutil/trace.go:171","msg":"trace[1170190473] transaction","detail":"{read_only:false; response_revision:22854; number_of_response:1; }","duration":"542.877377ms","start":"2026-03-10T18:52:42.424988Z","end":"2026-03-10T18:52:42.967866Z","steps":["trace[1170190473] 'process raft request' (duration: 542.782555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:42.967989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.352575Z","time spent":"615.335293ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:52:42.968041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.406522Z","time spent":"561.43029ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:52:42.9681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.424964Z","time spent":"543.025649ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:52:42.968136Z","caller":"traceutil/trace.go:171","msg":"trace[183215306] linearizableReadLoop","detail":"{readStateIndex:23637; appliedIndex:23633; }","duration":"576.786835ms","start":"2026-03-10T18:52:42.391342Z","end":"2026-03-10T18:52:42.968129Z","steps":["trace[183215306] 'read index received' (duration: 246.820282ms)","trace[183215306] 'applied index is now lower than readState.Index' (duration: 329.965383ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:52:42.968207Z","caller":"traceutil/trace.go:171","msg":"trace[269553024] transaction","detail":"{read_only:false; response_revision:22855; number_of_response:1; }","duration":"376.987141ms","start":"2026-03-10T18:52:42.591204Z","end":"2026-03-10T18:52:42.968192Z","steps":["trace[269553024] 'process raft request' (duration: 376.614853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:42.968269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.936068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:42.968296Z","caller":"traceutil/trace.go:171","msg":"trace[755344678] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:22855; }","duration":"577.01651ms","start":"2026-03-10T18:52:42.391272Z","end":"2026-03-10T18:52:42.968288Z","steps":["trace[755344678] 'agreement among raft nodes before linearized reading' (duration: 576.964169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:42.968312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.391241Z","time spent":"577.067542ms","remote":"127.0.0.1:32950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-03-10T18:52:42.968297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.591185Z","time spent":"377.066272ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:52:42.968347Z","caller":"traceutil/trace.go:171","msg":"trace[665124536] transaction","detail":"{read_only:false; response_revision:22851; number_of_response:1; }","duration":"615.518018ms","start":"2026-03-10T18:52:42.352816Z","end":"2026-03-10T18:52:42.968334Z","steps":["trace[665124536] 'process raft request' (duration: 614.72006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:42.968415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.35277Z","time spent":"615.61229ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:52:42.967878Z","caller":"traceutil/trace.go:171","msg":"trace[48791947] transaction","detail":"{read_only:false; response_revision:22852; number_of_response:1; }","duration":"614.163069ms","start":"2026-03-10T18:52:42.353688Z","end":"2026-03-10T18:52:42.967851Z","steps":["trace[48791947] 'process raft request' (duration: 613.978455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:42.968631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.353676Z","time spent":"614.862134ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:52:43.033967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.690546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:43.034039Z","caller":"traceutil/trace.go:171","msg":"trace[616179565] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:22856; }","duration":"226.843909ms","start":"2026-03-10T18:52:42.807179Z","end":"2026-03-10T18:52:43.034023Z","steps":["trace[616179565] 'agreement among raft nodes before linearized reading' (duration: 226.675585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:43.034082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.131313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-10T18:52:43.034185Z","caller":"traceutil/trace.go:171","msg":"trace[154745342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22856; }","duration":"552.289646ms","start":"2026-03-10T18:52:42.48187Z","end":"2026-03-10T18:52:43.034159Z","steps":["trace[154745342] 'agreement among raft nodes before linearized reading' (duration: 551.947679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:43.034231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.48185Z","time spent":"552.372738ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-10T18:52:43.034348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.824836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:43.034376Z","caller":"traceutil/trace.go:171","msg":"trace[1112055578] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:22856; }","duration":"400.882538ms","start":"2026-03-10T18:52:42.633486Z","end":"2026-03-10T18:52:43.034369Z","steps":["trace[1112055578] 'agreement among raft nodes before linearized reading' (duration: 400.831547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:43.034399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:42.63347Z","time spent":"400.922238ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"info","ts":"2026-03-10T18:52:43.03473Z","caller":"traceutil/trace.go:171","msg":"trace[815723762] transaction","detail":"{read_only:false; response_revision:22856; number_of_response:1; }","duration":"296.642218ms","start":"2026-03-10T18:52:42.738073Z","end":"2026-03-10T18:52:43.034715Z","steps":["trace[815723762] 'process raft request' (duration: 295.525665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:43.271665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.943903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:52:43.271859Z","caller":"traceutil/trace.go:171","msg":"trace[1409487254] transaction","detail":"{read_only:false; response_revision:22858; number_of_response:1; }","duration":"232.633362ms","start":"2026-03-10T18:52:43.03919Z","end":"2026-03-10T18:52:43.271824Z","steps":["trace[1409487254] 'process raft request' (duration: 232.57513ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:43.271905Z","caller":"traceutil/trace.go:171","msg":"trace[703772968] transaction","detail":"{read_only:false; response_revision:22857; number_of_response:1; }","duration":"266.059ms","start":"2026-03-10T18:52:43.005812Z","end":"2026-03-10T18:52:43.271871Z","steps":["trace[703772968] 'process raft request' (duration: 120.829311ms)","trace[703772968] 'compare' (duration: 144.83744ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:52:44.035369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.545682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:44.035475Z","caller":"traceutil/trace.go:171","msg":"trace[1239843972] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:22859; }","duration":"159.694416ms","start":"2026-03-10T18:52:43.875754Z","end":"2026-03-10T18:52:44.035448Z","steps":["trace[1239843972] 'count revisions from in-memory index tree' (duration: 159.403379ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:44.298002Z","caller":"traceutil/trace.go:171","msg":"trace[1069062074] transaction","detail":"{read_only:false; response_revision:22860; number_of_response:1; }","duration":"319.458232ms","start":"2026-03-10T18:52:43.978518Z","end":"2026-03-10T18:52:44.297977Z","steps":["trace[1069062074] 'process raft request' (duration: 319.236057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:44.298155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:43.978495Z","time spent":"319.566964ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:52:44.298285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.920487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:44.29836Z","caller":"traceutil/trace.go:171","msg":"trace[403468451] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:22860; }","duration":"241.03887ms","start":"2026-03-10T18:52:44.057307Z","end":"2026-03-10T18:52:44.298346Z","steps":["trace[403468451] 'agreement among raft nodes before linearized reading' (duration: 240.912957ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:44.298168Z","caller":"traceutil/trace.go:171","msg":"trace[1180969358] linearizableReadLoop","detail":"{readStateIndex:23645; appliedIndex:23644; }","duration":"240.554459ms","start":"2026-03-10T18:52:44.057351Z","end":"2026-03-10T18:52:44.297905Z","steps":["trace[1180969358] 'read index received' (duration: 240.241123ms)","trace[1180969358] 'applied index is now lower than readState.Index' (duration: 312.376µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:52:44.298922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.039946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7749"} {"level":"info","ts":"2026-03-10T18:52:44.298984Z","caller":"traceutil/trace.go:171","msg":"trace[1645536274] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:22860; }","duration":"171.112469ms","start":"2026-03-10T18:52:44.127857Z","end":"2026-03-10T18:52:44.29897Z","steps":["trace[1645536274] 'agreement among raft nodes before linearized reading' (duration: 170.923734ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:44.489149Z","caller":"traceutil/trace.go:171","msg":"trace[1877311241] transaction","detail":"{read_only:false; response_revision:22861; number_of_response:1; }","duration":"129.75048ms","start":"2026-03-10T18:52:44.359372Z","end":"2026-03-10T18:52:44.489123Z","steps":["trace[1877311241] 'process raft request' (duration: 125.606843ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:44.778842Z","caller":"traceutil/trace.go:171","msg":"trace[1038354573] transaction","detail":"{read_only:false; response_revision:22862; number_of_response:1; }","duration":"109.076973ms","start":"2026-03-10T18:52:44.669745Z","end":"2026-03-10T18:52:44.778822Z","steps":["trace[1038354573] 'process raft request' (duration: 108.95201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:45.004698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.986012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:45.004759Z","caller":"traceutil/trace.go:171","msg":"trace[1523881808] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:22862; }","duration":"119.054233ms","start":"2026-03-10T18:52:44.885691Z","end":"2026-03-10T18:52:45.004745Z","steps":["trace[1523881808] 'range keys from in-memory index tree' (duration: 118.905ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:45.092707Z","caller":"traceutil/trace.go:171","msg":"trace[449259248] transaction","detail":"{read_only:false; response_revision:22863; number_of_response:1; }","duration":"118.322998ms","start":"2026-03-10T18:52:44.97436Z","end":"2026-03-10T18:52:45.092683Z","steps":["trace[449259248] 'process raft request' (duration: 118.137904ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:45.37423Z","caller":"traceutil/trace.go:171","msg":"trace[1918621792] transaction","detail":"{read_only:false; response_revision:22865; number_of_response:1; }","duration":"394.755717ms","start":"2026-03-10T18:52:44.979443Z","end":"2026-03-10T18:52:45.374198Z","steps":["trace[1918621792] 'process raft request' (duration: 394.641915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:45.374406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:44.979435Z","time spent":"394.87666ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:52:45.374444Z","caller":"traceutil/trace.go:171","msg":"trace[261725340] linearizableReadLoop","detail":"{readStateIndex:23650; appliedIndex:23647; }","duration":"390.037897ms","start":"2026-03-10T18:52:44.98439Z","end":"2026-03-10T18:52:45.374428Z","steps":["trace[261725340] 'read index received' (duration: 108.047221ms)","trace[261725340] 'applied index is now lower than readState.Index' (duration: 281.989616ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:52:45.37423Z","caller":"traceutil/trace.go:171","msg":"trace[871792493] transaction","detail":"{read_only:false; response_revision:22864; number_of_response:1; }","duration":"397.573267ms","start":"2026-03-10T18:52:44.976624Z","end":"2026-03-10T18:52:45.374197Z","steps":["trace[871792493] 'process raft request' (duration: 386.461472ms)","trace[871792493] 'compare' (duration: 10.88042ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:52:45.374521Z","caller":"traceutil/trace.go:171","msg":"trace[1165754815] transaction","detail":"{read_only:false; response_revision:22868; number_of_response:1; }","duration":"361.219887ms","start":"2026-03-10T18:52:45.013291Z","end":"2026-03-10T18:52:45.37451Z","steps":["trace[1165754815] 'process raft request' (duration: 361.058043ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:45.3745Z","caller":"traceutil/trace.go:171","msg":"trace[504718289] transaction","detail":"{read_only:false; response_revision:22867; number_of_response:1; }","duration":"365.296363ms","start":"2026-03-10T18:52:45.009171Z","end":"2026-03-10T18:52:45.374467Z","steps":["trace[504718289] 'process raft request' (duration: 365.089049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:45.374569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:44.9766Z","time spent":"397.934664ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:52:45.374619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.013268Z","time spent":"361.276919ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:52:45.374649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.009167Z","time spent":"365.398305ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:52:45.374672Z","caller":"traceutil/trace.go:171","msg":"trace[1670743371] transaction","detail":"{read_only:false; response_revision:22866; number_of_response:1; }","duration":"365.550638ms","start":"2026-03-10T18:52:45.009109Z","end":"2026-03-10T18:52:45.37466Z","steps":["trace[1670743371] 'process raft request' (duration: 365.050837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:45.374726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.009096Z","time spent":"365.601031ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:52:45.374847Z","caller":"traceutil/trace.go:171","msg":"trace[456738807] transaction","detail":"{read_only:false; response_revision:22869; number_of_response:1; }","duration":"335.070983ms","start":"2026-03-10T18:52:45.039766Z","end":"2026-03-10T18:52:45.374837Z","steps":["trace[456738807] 'process raft request' (duration: 334.623302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:45.374862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.475457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:45.374897Z","caller":"traceutil/trace.go:171","msg":"trace[445356900] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:22869; }","duration":"390.542109ms","start":"2026-03-10T18:52:44.984344Z","end":"2026-03-10T18:52:45.374886Z","steps":["trace[445356900] 'agreement among raft nodes before linearized reading' (duration: 390.456987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:45.374897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.039758Z","time spent":"335.109323ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:52:45.374926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:44.984328Z","time spent":"390.590629ms","remote":"127.0.0.1:39334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-03-10T18:52:45.375407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.121701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:45.37546Z","caller":"traceutil/trace.go:171","msg":"trace[1014390091] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:22870; }","duration":"366.186752ms","start":"2026-03-10T18:52:45.009259Z","end":"2026-03-10T18:52:45.375445Z","steps":["trace[1014390091] 'agreement among raft nodes before linearized reading' (duration: 366.08983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:45.375492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.009254Z","time spent":"366.230453ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-03-10T18:52:45.376108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.564733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:45.376151Z","caller":"traceutil/trace.go:171","msg":"trace[881896954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22870; }","duration":"152.645035ms","start":"2026-03-10T18:52:45.223497Z","end":"2026-03-10T18:52:45.376142Z","steps":["trace[881896954] 'agreement among raft nodes before linearized reading' (duration: 152.585854ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:45.702719Z","caller":"traceutil/trace.go:171","msg":"trace[565247223] linearizableReadLoop","detail":"{readStateIndex:23656; appliedIndex:23655; }","duration":"320.606297ms","start":"2026-03-10T18:52:45.382093Z","end":"2026-03-10T18:52:45.702699Z","steps":["trace[565247223] 'read index received' (duration: 292.077591ms)","trace[565247223] 'applied index is now lower than readState.Index' (duration: 28.527696ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:52:45.702771Z","caller":"traceutil/trace.go:171","msg":"trace[406980968] transaction","detail":"{read_only:false; response_revision:22871; number_of_response:1; }","duration":"323.464377ms","start":"2026-03-10T18:52:45.379245Z","end":"2026-03-10T18:52:45.702709Z","steps":["trace[406980968] 'process raft request' (duration: 294.910751ms)","trace[406980968] 'compare' (duration: 28.231998ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:52:45.702871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.75834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:45.702904Z","caller":"traceutil/trace.go:171","msg":"trace[90975993] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:22871; }","duration":"320.813571ms","start":"2026-03-10T18:52:45.38208Z","end":"2026-03-10T18:52:45.702893Z","steps":["trace[90975993] 'agreement among raft nodes before linearized reading' (duration: 320.742359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:45.702934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.382075Z","time spent":"320.851761ms","remote":"127.0.0.1:39394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-03-10T18:52:45.702924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.379234Z","time spent":"323.60556ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:52:45.991537Z","caller":"traceutil/trace.go:171","msg":"trace[1107718905] linearizableReadLoop","detail":"{readStateIndex:23657; appliedIndex:23656; }","duration":"288.709999ms","start":"2026-03-10T18:52:45.702798Z","end":"2026-03-10T18:52:45.991508Z","steps":["trace[1107718905] 'read index received' (duration: 264.445635ms)","trace[1107718905] 'applied index is now lower than readState.Index' (duration: 24.263104ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:52:45.992088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.534397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-03-10T18:52:45.992118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.015696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:45.992169Z","caller":"traceutil/trace.go:171","msg":"trace[84024353] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:22872; }","duration":"297.110538ms","start":"2026-03-10T18:52:45.695046Z","end":"2026-03-10T18:52:45.992157Z","steps":["trace[84024353] 'agreement among raft nodes before linearized reading' (duration: 296.997955ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:45.992172Z","caller":"traceutil/trace.go:171","msg":"trace[1941288533] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:22872; }","duration":"322.66478ms","start":"2026-03-10T18:52:45.669485Z","end":"2026-03-10T18:52:45.99215Z","steps":["trace[1941288533] 'agreement among raft nodes before linearized reading' (duration: 322.525817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:45.992226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.66945Z","time spent":"322.764612ms","remote":"127.0.0.1:43692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"info","ts":"2026-03-10T18:52:45.991815Z","caller":"traceutil/trace.go:171","msg":"trace[1303989643] transaction","detail":"{read_only:false; response_revision:22872; number_of_response:1; }","duration":"574.795524ms","start":"2026-03-10T18:52:45.417Z","end":"2026-03-10T18:52:45.991796Z","steps":["trace[1303989643] 'process raft request' (duration: 550.321945ms)","trace[1303989643] 'compare' (duration: 24.05219ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:52:45.9927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.416834Z","time spent":"575.806165ms","remote":"127.0.0.1:43692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7732,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:52:46.169985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.585031ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-10T18:52:46.170111Z","caller":"traceutil/trace.go:171","msg":"trace[1464550317] linearizableReadLoop","detail":"{readStateIndex:23658; appliedIndex:23657; }","duration":"178.487233ms","start":"2026-03-10T18:52:45.991603Z","end":"2026-03-10T18:52:46.17009Z","steps":["trace[1464550317] 'read index received' (duration: 2.731758ms)","trace[1464550317] 'applied index is now lower than readState.Index' (duration: 175.753465ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:52:46.170403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.103853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-10T18:52:46.170495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.09615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-03-10T18:52:46.170504Z","caller":"traceutil/trace.go:171","msg":"trace[655140722] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:22872; }","duration":"292.259616ms","start":"2026-03-10T18:52:45.878221Z","end":"2026-03-10T18:52:46.170481Z","steps":["trace[655140722] 'agreement among raft nodes before linearized reading' (duration: 292.090842ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:52:46.170534Z","caller":"traceutil/trace.go:171","msg":"trace[425923505] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:22872; }","duration":"399.163002ms","start":"2026-03-10T18:52:45.771359Z","end":"2026-03-10T18:52:46.170522Z","steps":["trace[425923505] 'agreement among raft nodes before linearized reading' (duration: 399.002639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:46.170576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.771349Z","time spent":"399.219083ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-03-10T18:52:46.170798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.76815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:52:46.17084Z","caller":"traceutil/trace.go:171","msg":"trace[1602784165] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:22872; }","duration":"462.846691ms","start":"2026-03-10T18:52:45.707983Z","end":"2026-03-10T18:52:46.170829Z","steps":["trace[1602784165] 'agreement among raft nodes before linearized reading' (duration: 462.758089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:52:46.170876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:52:45.707962Z","time spent":"462.903423ms","remote":"127.0.0.1:39394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-03-10T18:52:46.171133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.284058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18835"} {"level":"info","ts":"2026-03-10T18:52:46.171175Z","caller":"traceutil/trace.go:171","msg":"trace[1845400193] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:22872; }","duration":"169.328829ms","start":"2026-03-10T18:52:46.001837Z","end":"2026-03-10T18:52:46.171166Z","steps":["trace[1845400193] 'agreement among raft nodes before linearized reading' (duration: 169.202077ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:00.539322Z","caller":"traceutil/trace.go:171","msg":"trace[667381967] transaction","detail":"{read_only:false; response_revision:23035; number_of_response:1; }","duration":"152.958883ms","start":"2026-03-10T18:53:00.386341Z","end":"2026-03-10T18:53:00.5393Z","steps":["trace[667381967] 'process raft request' (duration: 152.828991ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:00.831951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.650999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:53:00.832108Z","caller":"traceutil/trace.go:171","msg":"trace[884020166] transaction","detail":"{read_only:false; response_revision:23036; number_of_response:1; }","duration":"262.182397ms","start":"2026-03-10T18:53:00.569896Z","end":"2026-03-10T18:53:00.832078Z","steps":["trace[884020166] 'process raft request' (duration: 89.337254ms)","trace[884020166] 'compare' (duration: 172.483105ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:01.259932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.694578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:53:01.260013Z","caller":"traceutil/trace.go:171","msg":"trace[46301578] linearizableReadLoop","detail":"{readStateIndex:23827; appliedIndex:23826; }","duration":"350.753565ms","start":"2026-03-10T18:53:00.909246Z","end":"2026-03-10T18:53:01.26Z","steps":["trace[46301578] 'read index received' (duration: 188.872523ms)","trace[46301578] 'applied index is now lower than readState.Index' (duration: 161.878892ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:01.260065Z","caller":"traceutil/trace.go:171","msg":"trace[1969420148] transaction","detail":"{read_only:false; response_revision:23038; number_of_response:1; }","duration":"350.907608ms","start":"2026-03-10T18:53:00.909096Z","end":"2026-03-10T18:53:01.260004Z","steps":["trace[1969420148] 'process raft request' (duration: 189.071217ms)","trace[1969420148] 'compare' (duration: 161.349571ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:01.260147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.898718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-03-10T18:53:01.260168Z","caller":"traceutil/trace.go:171","msg":"trace[277912056] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:23038; }","duration":"350.939879ms","start":"2026-03-10T18:53:00.909221Z","end":"2026-03-10T18:53:01.260161Z","steps":["trace[277912056] 'agreement among raft nodes before linearized reading' (duration: 350.829777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.260191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:00.909205Z","time spent":"350.98119ms","remote":"127.0.0.1:39142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-03-10T18:53:01.260251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:00.909074Z","time spent":"351.063592ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:53:01.41151Z","caller":"traceutil/trace.go:171","msg":"trace[414879432] linearizableReadLoop","detail":"{readStateIndex:23828; appliedIndex:23827; }","duration":"151.455091ms","start":"2026-03-10T18:53:01.26003Z","end":"2026-03-10T18:53:01.411485Z","steps":["trace[414879432] 'read index received' (duration: 151.362179ms)","trace[414879432] 'applied index is now lower than readState.Index' (duration: 91.432µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:01.411634Z","caller":"traceutil/trace.go:171","msg":"trace[1190621494] transaction","detail":"{read_only:false; response_revision:23039; number_of_response:1; }","duration":"250.301436ms","start":"2026-03-10T18:53:01.161279Z","end":"2026-03-10T18:53:01.411581Z","steps":["trace[1190621494] 'process raft request' (duration: 250.02488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.411675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.182618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:01.411847Z","caller":"traceutil/trace.go:171","msg":"trace[679554409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23039; }","duration":"187.395812ms","start":"2026-03-10T18:53:01.224419Z","end":"2026-03-10T18:53:01.411815Z","steps":["trace[679554409] 'agreement among raft nodes before linearized reading' (duration: 187.203028ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:01.412087Z","caller":"traceutil/trace.go:171","msg":"trace[91869408] transaction","detail":"{read_only:false; response_revision:23040; number_of_response:1; }","duration":"117.741966ms","start":"2026-03-10T18:53:01.294331Z","end":"2026-03-10T18:53:01.412072Z","steps":["trace[91869408] 'process raft request' (duration: 117.602933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.41213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.629872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-03-10T18:53:01.412181Z","caller":"traceutil/trace.go:171","msg":"trace[1654890474] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:23040; }","duration":"150.709704ms","start":"2026-03-10T18:53:01.261459Z","end":"2026-03-10T18:53:01.412169Z","steps":["trace[1654890474] 'agreement among raft nodes before linearized reading' (duration: 150.54124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.412379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.521299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1db57ef7-f832-476f-9d78-0b1affa1cdbb\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-03-10T18:53:01.412425Z","caller":"traceutil/trace.go:171","msg":"trace[915599307] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1db57ef7-f832-476f-9d78-0b1affa1cdbb; range_end:; response_count:1; response_revision:23040; }","duration":"116.609061ms","start":"2026-03-10T18:53:01.295802Z","end":"2026-03-10T18:53:01.412411Z","steps":["trace[915599307] 'agreement among raft nodes before linearized reading' (duration: 116.440467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.706599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.644739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:53:01.706724Z","caller":"traceutil/trace.go:171","msg":"trace[1555410264] linearizableReadLoop","detail":"{readStateIndex:23830; appliedIndex:23829; }","duration":"277.556774ms","start":"2026-03-10T18:53:01.429145Z","end":"2026-03-10T18:53:01.706702Z","steps":["trace[1555410264] 'read index received' (duration: 137.645729ms)","trace[1555410264] 'applied index is now lower than readState.Index' (duration: 139.908935ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:01.7068Z","caller":"traceutil/trace.go:171","msg":"trace[478354979] transaction","detail":"{read_only:false; response_revision:23041; number_of_response:1; }","duration":"279.910483ms","start":"2026-03-10T18:53:01.4268Z","end":"2026-03-10T18:53:01.706711Z","steps":["trace[478354979] 'process raft request' (duration: 140.040849ms)","trace[478354979] 'compare' (duration: 138.921643ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:01.707098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.942612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-03-10T18:53:01.707153Z","caller":"traceutil/trace.go:171","msg":"trace[979500990] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:23041; }","duration":"278.033184ms","start":"2026-03-10T18:53:01.429109Z","end":"2026-03-10T18:53:01.707142Z","steps":["trace[979500990] 'agreement among raft nodes before linearized reading' (duration: 277.83889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.708645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.466643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-10T18:53:01.708711Z","caller":"traceutil/trace.go:171","msg":"trace[1244519180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23045; }","duration":"237.568836ms","start":"2026-03-10T18:53:01.471126Z","end":"2026-03-10T18:53:01.708695Z","steps":["trace[1244519180] 'agreement among raft nodes before linearized reading' (duration: 237.404842ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:01.708715Z","caller":"traceutil/trace.go:171","msg":"trace[1238077862] transaction","detail":"{read_only:false; response_revision:23045; number_of_response:1; }","duration":"247.647269ms","start":"2026-03-10T18:53:01.461039Z","end":"2026-03-10T18:53:01.708687Z","steps":["trace[1238077862] 'process raft request' (duration: 247.432975ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:01.708794Z","caller":"traceutil/trace.go:171","msg":"trace[1634032440] transaction","detail":"{read_only:false; response_revision:23042; number_of_response:1; }","duration":"259.51817ms","start":"2026-03-10T18:53:01.449247Z","end":"2026-03-10T18:53:01.708766Z","steps":["trace[1634032440] 'process raft request' (duration: 259.006089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.708896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.276013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:01.708927Z","caller":"traceutil/trace.go:171","msg":"trace[212329632] transaction","detail":"{read_only:false; response_revision:23043; number_of_response:1; }","duration":"252.87273ms","start":"2026-03-10T18:53:01.456048Z","end":"2026-03-10T18:53:01.708921Z","steps":["trace[212329632] 'process raft request' (duration: 252.327339ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:01.708954Z","caller":"traceutil/trace.go:171","msg":"trace[1110077527] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:23045; }","duration":"124.361686ms","start":"2026-03-10T18:53:01.584576Z","end":"2026-03-10T18:53:01.708937Z","steps":["trace[1110077527] 'agreement among raft nodes before linearized reading' (duration: 124.277794ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:01.70899Z","caller":"traceutil/trace.go:171","msg":"trace[340804896] transaction","detail":"{read_only:false; response_revision:23044; number_of_response:1; }","duration":"249.445828ms","start":"2026-03-10T18:53:01.459539Z","end":"2026-03-10T18:53:01.708985Z","steps":["trace[340804896] 'process raft request' (duration: 248.889796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.709258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.194536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-10T18:53:01.709309Z","caller":"traceutil/trace.go:171","msg":"trace[554472185] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23045; }","duration":"161.281928ms","start":"2026-03-10T18:53:01.548014Z","end":"2026-03-10T18:53:01.709296Z","steps":["trace[554472185] 'agreement among raft nodes before linearized reading' (duration: 161.140555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.709386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.207328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-10T18:53:01.709414Z","caller":"traceutil/trace.go:171","msg":"trace[705696502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23045; }","duration":"237.283478ms","start":"2026-03-10T18:53:01.472124Z","end":"2026-03-10T18:53:01.709408Z","steps":["trace[705696502] 'agreement among raft nodes before linearized reading' (duration: 237.086874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.709495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.726478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-10T18:53:01.709514Z","caller":"traceutil/trace.go:171","msg":"trace[426671811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23045; }","duration":"236.760848ms","start":"2026-03-10T18:53:01.472747Z","end":"2026-03-10T18:53:01.709508Z","steps":["trace[426671811] 'agreement among raft nodes before linearized reading' (duration: 236.717318ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:01.979571Z","caller":"traceutil/trace.go:171","msg":"trace[2048312323] transaction","detail":"{read_only:false; response_revision:23048; number_of_response:1; }","duration":"264.323602ms","start":"2026-03-10T18:53:01.715226Z","end":"2026-03-10T18:53:01.979549Z","steps":["trace[2048312323] 'process raft request' (duration: 264.264661ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:01.979601Z","caller":"traceutil/trace.go:171","msg":"trace[29862926] transaction","detail":"{read_only:false; response_revision:23047; number_of_response:1; }","duration":"265.335985ms","start":"2026-03-10T18:53:01.714234Z","end":"2026-03-10T18:53:01.97957Z","steps":["trace[29862926] 'process raft request' (duration: 265.10611ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:01.979774Z","caller":"traceutil/trace.go:171","msg":"trace[396484278] linearizableReadLoop","detail":"{readStateIndex:23838; appliedIndex:23835; }","duration":"220.30816ms","start":"2026-03-10T18:53:01.75944Z","end":"2026-03-10T18:53:01.979748Z","steps":["trace[396484278] 'read index received' (duration: 219.81865ms)","trace[396484278] 'applied index is now lower than readState.Index' (duration: 488.37µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:01.97995Z","caller":"traceutil/trace.go:171","msg":"trace[985462994] transaction","detail":"{read_only:false; response_revision:23049; number_of_response:1; }","duration":"262.854821ms","start":"2026-03-10T18:53:01.717039Z","end":"2026-03-10T18:53:01.979894Z","steps":["trace[985462994] 'process raft request' (duration: 262.483324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.980024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.962188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"warn","ts":"2026-03-10T18:53:01.980051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.164725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18835"} {"level":"info","ts":"2026-03-10T18:53:01.980062Z","caller":"traceutil/trace.go:171","msg":"trace[1668459689] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:23049; }","duration":"259.045741ms","start":"2026-03-10T18:53:01.721003Z","end":"2026-03-10T18:53:01.980049Z","steps":["trace[1668459689] 'agreement among raft nodes before linearized reading' (duration: 258.878567ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:01.980098Z","caller":"traceutil/trace.go:171","msg":"trace[1499627332] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23049; }","duration":"219.222316ms","start":"2026-03-10T18:53:01.760852Z","end":"2026-03-10T18:53:01.980075Z","steps":["trace[1499627332] 'agreement among raft nodes before linearized reading' (duration: 219.093913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:01.98033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.177833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-10T18:53:01.980374Z","caller":"traceutil/trace.go:171","msg":"trace[998956696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23049; }","duration":"208.284044ms","start":"2026-03-10T18:53:01.772077Z","end":"2026-03-10T18:53:01.980361Z","steps":["trace[998956696] 'agreement among raft nodes before linearized reading' (duration: 208.042419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:02.189723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.719595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:02.189823Z","caller":"traceutil/trace.go:171","msg":"trace[1199749889] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23051; }","duration":"108.862638ms","start":"2026-03-10T18:53:02.080939Z","end":"2026-03-10T18:53:02.189802Z","steps":["trace[1199749889] 'range keys from in-memory index tree' (duration: 108.632782ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:02.462647Z","caller":"traceutil/trace.go:171","msg":"trace[186155466] transaction","detail":"{read_only:false; response_revision:23052; number_of_response:1; }","duration":"124.696423ms","start":"2026-03-10T18:53:02.324377Z","end":"2026-03-10T18:53:02.449074Z","steps":["trace[186155466] 'process raft request' (duration: 124.59304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:02.849266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.539341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:02.849364Z","caller":"traceutil/trace.go:171","msg":"trace[160272457] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23053; }","duration":"233.683043ms","start":"2026-03-10T18:53:02.615656Z","end":"2026-03-10T18:53:02.849339Z","steps":["trace[160272457] 'count revisions from in-memory index tree' (duration: 233.463168ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:03.242383Z","caller":"traceutil/trace.go:171","msg":"trace[2069640842] linearizableReadLoop","detail":"{readStateIndex:23843; appliedIndex:23842; }","duration":"387.831632ms","start":"2026-03-10T18:53:02.85453Z","end":"2026-03-10T18:53:03.242362Z","steps":["trace[2069640842] 'read index received' (duration: 387.348922ms)","trace[2069640842] 'applied index is now lower than readState.Index' (duration: 481.77µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:03.242591Z","caller":"traceutil/trace.go:171","msg":"trace[1755756418] transaction","detail":"{read_only:false; response_revision:23054; number_of_response:1; }","duration":"406.386274ms","start":"2026-03-10T18:53:02.836172Z","end":"2026-03-10T18:53:03.242558Z","steps":["trace[1755756418] 'process raft request' (duration: 405.798782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:03.242714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.178048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:03.24276Z","caller":"traceutil/trace.go:171","msg":"trace[1073183692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23054; }","duration":"388.303042ms","start":"2026-03-10T18:53:02.854449Z","end":"2026-03-10T18:53:03.242753Z","steps":["trace[1073183692] 'agreement among raft nodes before linearized reading' (duration: 388.20857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:03.242791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:02.8544Z","time spent":"388.383003ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-10T18:53:03.242731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:02.836154Z","time spent":"406.503457ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:03.384782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.138998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-03-10T18:53:03.384855Z","caller":"traceutil/trace.go:171","msg":"trace[1470738919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23054; }","duration":"121.22197ms","start":"2026-03-10T18:53:03.263618Z","end":"2026-03-10T18:53:03.38484Z","steps":["trace[1470738919] 'range keys from in-memory index tree' (duration: 120.938544ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:09.955217Z","caller":"traceutil/trace.go:171","msg":"trace[734708613] transaction","detail":"{read_only:false; response_revision:23123; number_of_response:1; }","duration":"218.508641ms","start":"2026-03-10T18:53:09.736685Z","end":"2026-03-10T18:53:09.955194Z","steps":["trace[734708613] 'process raft request' (duration: 218.344318ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:09.984897Z","caller":"traceutil/trace.go:171","msg":"trace[1438359129] transaction","detail":"{read_only:false; response_revision:23124; number_of_response:1; }","duration":"245.398352ms","start":"2026-03-10T18:53:09.739476Z","end":"2026-03-10T18:53:09.984874Z","steps":["trace[1438359129] 'process raft request' (duration: 245.265269ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:09.985244Z","caller":"traceutil/trace.go:171","msg":"trace[714315185] transaction","detail":"{read_only:false; response_revision:23125; number_of_response:1; }","duration":"244.379721ms","start":"2026-03-10T18:53:09.74083Z","end":"2026-03-10T18:53:09.98521Z","steps":["trace[714315185] 'process raft request' (duration: 244.010443ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:09.985256Z","caller":"traceutil/trace.go:171","msg":"trace[1262856289] transaction","detail":"{read_only:false; response_revision:23126; number_of_response:1; }","duration":"243.993811ms","start":"2026-03-10T18:53:09.741239Z","end":"2026-03-10T18:53:09.985233Z","steps":["trace[1262856289] 'process raft request' (duration: 243.849808ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:10.159383Z","caller":"traceutil/trace.go:171","msg":"trace[1134877744] linearizableReadLoop","detail":"{readStateIndex:23917; appliedIndex:23916; }","duration":"145.062565ms","start":"2026-03-10T18:53:10.014293Z","end":"2026-03-10T18:53:10.159355Z","steps":["trace[1134877744] 'read index received' (duration: 109.165874ms)","trace[1134877744] 'applied index is now lower than readState.Index' (duration: 35.895391ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:10.159452Z","caller":"traceutil/trace.go:171","msg":"trace[1517637826] transaction","detail":"{read_only:false; response_revision:23127; number_of_response:1; }","duration":"191.190612ms","start":"2026-03-10T18:53:09.96823Z","end":"2026-03-10T18:53:10.15942Z","steps":["trace[1517637826] 'process raft request' (duration: 155.24379ms)","trace[1517637826] 'compare' (duration: 35.702937ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:10.159682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.376241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-10T18:53:10.159735Z","caller":"traceutil/trace.go:171","msg":"trace[1633418360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23127; }","duration":"145.466314ms","start":"2026-03-10T18:53:10.014256Z","end":"2026-03-10T18:53:10.159723Z","steps":["trace[1633418360] 'agreement among raft nodes before linearized reading' (duration: 145.245799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:10.159786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.502009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-10T18:53:10.159837Z","caller":"traceutil/trace.go:171","msg":"trace[1412665196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23127; }","duration":"141.566381ms","start":"2026-03-10T18:53:10.018259Z","end":"2026-03-10T18:53:10.159825Z","steps":["trace[1412665196] 'agreement among raft nodes before linearized reading' (duration: 141.407337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:10.159875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.486158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-10T18:53:10.15995Z","caller":"traceutil/trace.go:171","msg":"trace[2145794697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23127; }","duration":"141.599051ms","start":"2026-03-10T18:53:10.018331Z","end":"2026-03-10T18:53:10.15993Z","steps":["trace[2145794697] 'agreement among raft nodes before linearized reading' (duration: 141.382696ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:10.427148Z","caller":"traceutil/trace.go:171","msg":"trace[1637948869] transaction","detail":"{read_only:false; response_revision:23128; number_of_response:1; }","duration":"262.848591ms","start":"2026-03-10T18:53:10.164249Z","end":"2026-03-10T18:53:10.427098Z","steps":["trace[1637948869] 'process raft request' (duration: 230.885364ms)","trace[1637948869] 'compare' (duration: 31.15705ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:10.427495Z","caller":"traceutil/trace.go:171","msg":"trace[1928172177] transaction","detail":"{read_only:false; response_revision:23129; number_of_response:1; }","duration":"263.053897ms","start":"2026-03-10T18:53:10.164402Z","end":"2026-03-10T18:53:10.427456Z","steps":["trace[1928172177] 'process raft request' (duration: 262.742139ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:10.427796Z","caller":"traceutil/trace.go:171","msg":"trace[1618881962] transaction","detail":"{read_only:false; response_revision:23130; number_of_response:1; }","duration":"262.75094ms","start":"2026-03-10T18:53:10.165019Z","end":"2026-03-10T18:53:10.42777Z","steps":["trace[1618881962] 'process raft request' (duration: 262.284219ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:10.428243Z","caller":"traceutil/trace.go:171","msg":"trace[28128793] transaction","detail":"{read_only:false; response_revision:23131; number_of_response:1; }","duration":"262.75551ms","start":"2026-03-10T18:53:10.165467Z","end":"2026-03-10T18:53:10.428222Z","steps":["trace[28128793] 'process raft request' (duration: 262.158468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:10.428381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.33177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:10.428428Z","caller":"traceutil/trace.go:171","msg":"trace[445469082] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:23131; }","duration":"178.432032ms","start":"2026-03-10T18:53:10.249983Z","end":"2026-03-10T18:53:10.428415Z","steps":["trace[445469082] 'agreement among raft nodes before linearized reading' (duration: 178.341751ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:10.428645Z","caller":"traceutil/trace.go:171","msg":"trace[526717796] linearizableReadLoop","detail":"{readStateIndex:23921; appliedIndex:23917; }","duration":"178.106236ms","start":"2026-03-10T18:53:10.250039Z","end":"2026-03-10T18:53:10.428145Z","steps":["trace[526717796] 'read index received' (duration: 145.086106ms)","trace[526717796] 'applied index is now lower than readState.Index' (duration: 33.01885ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:12.128657Z","caller":"traceutil/trace.go:171","msg":"trace[769403326] transaction","detail":"{read_only:false; response_revision:23142; number_of_response:1; }","duration":"167.837528ms","start":"2026-03-10T18:53:11.960749Z","end":"2026-03-10T18:53:12.128586Z","steps":["trace[769403326] 'process raft request' (duration: 167.664884ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:12.130082Z","caller":"traceutil/trace.go:171","msg":"trace[657589301] transaction","detail":"{read_only:false; response_revision:23143; number_of_response:1; }","duration":"140.414586ms","start":"2026-03-10T18:53:11.989642Z","end":"2026-03-10T18:53:12.130056Z","steps":["trace[657589301] 'process raft request' (duration: 140.246603ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:12.130206Z","caller":"traceutil/trace.go:171","msg":"trace[2098037653] transaction","detail":"{read_only:false; response_revision:23144; number_of_response:1; }","duration":"140.484908ms","start":"2026-03-10T18:53:11.989708Z","end":"2026-03-10T18:53:12.130193Z","steps":["trace[2098037653] 'process raft request' (duration: 140.310204ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:12.130493Z","caller":"traceutil/trace.go:171","msg":"trace[1248961800] transaction","detail":"{read_only:false; response_revision:23145; number_of_response:1; }","duration":"140.201742ms","start":"2026-03-10T18:53:11.99028Z","end":"2026-03-10T18:53:12.130482Z","steps":["trace[1248961800] 'process raft request' (duration: 139.860105ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:36.564012Z","caller":"traceutil/trace.go:171","msg":"trace[870447356] transaction","detail":"{read_only:false; response_revision:23326; number_of_response:1; }","duration":"108.183792ms","start":"2026-03-10T18:53:36.455811Z","end":"2026-03-10T18:53:36.563995Z","steps":["trace[870447356] 'process raft request' (duration: 107.970368ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:40.321255Z","caller":"traceutil/trace.go:171","msg":"trace[574724655] transaction","detail":"{read_only:false; response_revision:23349; number_of_response:1; }","duration":"134.090352ms","start":"2026-03-10T18:53:40.187138Z","end":"2026-03-10T18:53:40.321229Z","steps":["trace[574724655] 'process raft request' (duration: 133.639793ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:40.344827Z","caller":"traceutil/trace.go:171","msg":"trace[33269284] transaction","detail":"{read_only:false; response_revision:23350; number_of_response:1; }","duration":"113.758931ms","start":"2026-03-10T18:53:40.231043Z","end":"2026-03-10T18:53:40.344802Z","steps":["trace[33269284] 'process raft request' (duration: 113.173159ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:40.345036Z","caller":"traceutil/trace.go:171","msg":"trace[1159296573] transaction","detail":"{read_only:false; response_revision:23351; number_of_response:1; }","duration":"108.124591ms","start":"2026-03-10T18:53:40.236904Z","end":"2026-03-10T18:53:40.345028Z","steps":["trace[1159296573] 'process raft request' (duration: 107.438127ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:40.345114Z","caller":"traceutil/trace.go:171","msg":"trace[1570562829] transaction","detail":"{read_only:false; response_revision:23352; number_of_response:1; }","duration":"103.423913ms","start":"2026-03-10T18:53:40.241681Z","end":"2026-03-10T18:53:40.345105Z","steps":["trace[1570562829] 'process raft request' (duration: 102.694967ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:40.345174Z","caller":"traceutil/trace.go:171","msg":"trace[1108358184] transaction","detail":"{read_only:false; response_revision:23353; number_of_response:1; }","duration":"101.496521ms","start":"2026-03-10T18:53:40.243672Z","end":"2026-03-10T18:53:40.345168Z","steps":["trace[1108358184] 'process raft request' (duration: 100.735145ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:42.713097Z","caller":"traceutil/trace.go:171","msg":"trace[1354956885] transaction","detail":"{read_only:false; response_revision:23368; number_of_response:1; }","duration":"123.018456ms","start":"2026-03-10T18:53:42.590046Z","end":"2026-03-10T18:53:42.713065Z","steps":["trace[1354956885] 'process raft request' (duration: 122.850543ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:43.026242Z","caller":"traceutil/trace.go:171","msg":"trace[1878620310] transaction","detail":"{read_only:false; response_revision:23370; number_of_response:1; }","duration":"433.738485ms","start":"2026-03-10T18:53:42.592481Z","end":"2026-03-10T18:53:43.026219Z","steps":["trace[1878620310] 'process raft request' (duration: 433.660253ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:43.026335Z","caller":"traceutil/trace.go:171","msg":"trace[159284535] transaction","detail":"{read_only:false; response_revision:23369; number_of_response:1; }","duration":"434.531681ms","start":"2026-03-10T18:53:42.591754Z","end":"2026-03-10T18:53:43.026286Z","steps":["trace[159284535] 'process raft request' (duration: 434.232305ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:43.026378Z","caller":"traceutil/trace.go:171","msg":"trace[867521087] linearizableReadLoop","detail":"{readStateIndex:24168; appliedIndex:24165; }","duration":"433.780296ms","start":"2026-03-10T18:53:42.592576Z","end":"2026-03-10T18:53:43.026356Z","steps":["trace[867521087] 'read index received' (duration: 120.438653ms)","trace[867521087] 'applied index is now lower than readState.Index' (duration: 313.340783ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:43.02641Z","caller":"traceutil/trace.go:171","msg":"trace[657711731] transaction","detail":"{read_only:false; response_revision:23371; number_of_response:1; }","duration":"358.760025ms","start":"2026-03-10T18:53:42.667643Z","end":"2026-03-10T18:53:43.026403Z","steps":["trace[657711731] 'process raft request' (duration: 358.54712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:43.026451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:42.667618Z","time spent":"358.815016ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:43.02654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.957599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-03-10T18:53:43.026543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:42.591728Z","time spent":"434.705114ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:53:43.02657Z","caller":"traceutil/trace.go:171","msg":"trace[856428333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23371; }","duration":"434.01992ms","start":"2026-03-10T18:53:42.592539Z","end":"2026-03-10T18:53:43.026559Z","steps":["trace[856428333] 'agreement among raft nodes before linearized reading' (duration: 433.914288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:43.026602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:42.592531Z","time spent":"434.064862ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-10T18:53:43.026363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:42.592463Z","time spent":"433.840456ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:43.026683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.508565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-10T18:53:43.026762Z","caller":"traceutil/trace.go:171","msg":"trace[435748792] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23371; }","duration":"145.600936ms","start":"2026-03-10T18:53:42.881121Z","end":"2026-03-10T18:53:43.026722Z","steps":["trace[435748792] 'agreement among raft nodes before linearized reading' (duration: 145.432463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:43.026812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.309723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-03-10T18:53:43.026877Z","caller":"traceutil/trace.go:171","msg":"trace[857731015] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:23371; }","duration":"281.536458ms","start":"2026-03-10T18:53:42.745322Z","end":"2026-03-10T18:53:43.026859Z","steps":["trace[857731015] 'agreement among raft nodes before linearized reading' (duration: 281.2062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:43.324884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.880554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:53:43.32496Z","caller":"traceutil/trace.go:171","msg":"trace[1989746161] linearizableReadLoop","detail":"{readStateIndex:24170; appliedIndex:24169; }","duration":"292.591203ms","start":"2026-03-10T18:53:43.032358Z","end":"2026-03-10T18:53:43.324949Z","steps":["trace[1989746161] 'read index received' (duration: 61.485724ms)","trace[1989746161] 'applied index is now lower than readState.Index' (duration: 231.104699ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:43.32511Z","caller":"traceutil/trace.go:171","msg":"trace[450627212] transaction","detail":"{read_only:false; response_revision:23372; number_of_response:1; }","duration":"294.294678ms","start":"2026-03-10T18:53:43.030806Z","end":"2026-03-10T18:53:43.3251Z","steps":["trace[450627212] 'process raft request' (duration: 63.124528ms)","trace[450627212] 'compare' (duration: 230.780872ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:43.325299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.93989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18835"} {"level":"info","ts":"2026-03-10T18:53:43.325335Z","caller":"traceutil/trace.go:171","msg":"trace[1815502497] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23372; }","duration":"292.990131ms","start":"2026-03-10T18:53:43.032328Z","end":"2026-03-10T18:53:43.325318Z","steps":["trace[1815502497] 'agreement among raft nodes before linearized reading' (duration: 292.895969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:43.325547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.193784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-03-10T18:53:43.325626Z","caller":"traceutil/trace.go:171","msg":"trace[1402425902] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:23372; }","duration":"292.314206ms","start":"2026-03-10T18:53:43.033293Z","end":"2026-03-10T18:53:43.325607Z","steps":["trace[1402425902] 'agreement among raft nodes before linearized reading' (duration: 292.05886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:43.325627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.944191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:43.325737Z","caller":"traceutil/trace.go:171","msg":"trace[890990064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23372; }","duration":"102.061273ms","start":"2026-03-10T18:53:43.223623Z","end":"2026-03-10T18:53:43.325684Z","steps":["trace[890990064] 'agreement among raft nodes before linearized reading' (duration: 101.91739ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:43.512832Z","caller":"traceutil/trace.go:171","msg":"trace[2029823396] linearizableReadLoop","detail":"{readStateIndex:24171; appliedIndex:24170; }","duration":"121.997536ms","start":"2026-03-10T18:53:43.390799Z","end":"2026-03-10T18:53:43.512797Z","steps":["trace[2029823396] 'read index received' (duration: 121.305451ms)","trace[2029823396] 'applied index is now lower than readState.Index' (duration: 690.885µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:43.512899Z","caller":"traceutil/trace.go:171","msg":"trace[342754832] transaction","detail":"{read_only:false; response_revision:23373; number_of_response:1; }","duration":"127.502383ms","start":"2026-03-10T18:53:43.38537Z","end":"2026-03-10T18:53:43.512872Z","steps":["trace[342754832] 'process raft request' (duration: 126.808579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:43.512955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.135189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:43.512993Z","caller":"traceutil/trace.go:171","msg":"trace[1645246257] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23373; }","duration":"122.244892ms","start":"2026-03-10T18:53:43.390736Z","end":"2026-03-10T18:53:43.512981Z","steps":["trace[1645246257] 'agreement among raft nodes before linearized reading' (duration: 122.15697ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:43.795891Z","caller":"traceutil/trace.go:171","msg":"trace[1209881590] linearizableReadLoop","detail":"{readStateIndex:24172; appliedIndex:24171; }","duration":"203.899682ms","start":"2026-03-10T18:53:43.591966Z","end":"2026-03-10T18:53:43.795865Z","steps":["trace[1209881590] 'read index received' (duration: 203.615476ms)","trace[1209881590] 'applied index is now lower than readState.Index' (duration: 282.906µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:43.795996Z","caller":"traceutil/trace.go:171","msg":"trace[200012794] transaction","detail":"{read_only:false; response_revision:23374; number_of_response:1; }","duration":"234.490571ms","start":"2026-03-10T18:53:43.56147Z","end":"2026-03-10T18:53:43.79596Z","steps":["trace[200012794] 'process raft request' (duration: 234.204795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:43.796166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.182378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-03-10T18:53:43.796223Z","caller":"traceutil/trace.go:171","msg":"trace[2100285967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23374; }","duration":"204.27193ms","start":"2026-03-10T18:53:43.591933Z","end":"2026-03-10T18:53:43.796205Z","steps":["trace[2100285967] 'agreement among raft nodes before linearized reading' (duration: 204.088646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:44.130894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.732737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:53:44.131128Z","caller":"traceutil/trace.go:171","msg":"trace[1212964795] transaction","detail":"{read_only:false; response_revision:23376; number_of_response:1; }","duration":"330.067007ms","start":"2026-03-10T18:53:43.801037Z","end":"2026-03-10T18:53:44.131104Z","steps":["trace[1212964795] 'process raft request' (duration: 329.973305ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:44.131118Z","caller":"traceutil/trace.go:171","msg":"trace[784656434] transaction","detail":"{read_only:false; response_revision:23375; number_of_response:1; }","duration":"331.126649ms","start":"2026-03-10T18:53:43.799955Z","end":"2026-03-10T18:53:44.131082Z","steps":["trace[784656434] 'process raft request' (duration: 212.112646ms)","trace[784656434] 'compare' (duration: 118.548553ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:44.131273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:43.80102Z","time spent":"330.160608ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:44.131369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:43.799941Z","time spent":"331.347234ms","remote":"127.0.0.1:39186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:53:44.751604Z","caller":"traceutil/trace.go:171","msg":"trace[641745912] linearizableReadLoop","detail":"{readStateIndex:24178; appliedIndex:24175; }","duration":"325.937319ms","start":"2026-03-10T18:53:44.425645Z","end":"2026-03-10T18:53:44.751583Z","steps":["trace[641745912] 'read index received' (duration: 36.072234ms)","trace[641745912] 'applied index is now lower than readState.Index' (duration: 289.863775ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:44.751693Z","caller":"traceutil/trace.go:171","msg":"trace[42352693] transaction","detail":"{read_only:false; response_revision:23380; number_of_response:1; }","duration":"353.224887ms","start":"2026-03-10T18:53:44.398445Z","end":"2026-03-10T18:53:44.75167Z","steps":["trace[42352693] 'process raft request' (duration: 353.034153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:44.75177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.102872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:44.75181Z","caller":"traceutil/trace.go:171","msg":"trace[1890553576] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:23380; }","duration":"326.196854ms","start":"2026-03-10T18:53:44.425601Z","end":"2026-03-10T18:53:44.751798Z","steps":["trace[1890553576] 'agreement among raft nodes before linearized reading' (duration: 326.105952ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:44.751829Z","caller":"traceutil/trace.go:171","msg":"trace[672368464] transaction","detail":"{read_only:false; response_revision:23379; number_of_response:1; }","duration":"353.3578ms","start":"2026-03-10T18:53:44.398432Z","end":"2026-03-10T18:53:44.75179Z","steps":["trace[672368464] 'process raft request' (duration: 335.121954ms)","trace[672368464] 'compare' (duration: 17.556182ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:44.751849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:44.425587Z","time spent":"326.251365ms","remote":"127.0.0.1:60968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-10T18:53:44.751876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:44.398415Z","time spent":"353.357231ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:44.752156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.782698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:44.752754Z","caller":"traceutil/trace.go:171","msg":"trace[165859322] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:23380; }","duration":"169.422152ms","start":"2026-03-10T18:53:44.583297Z","end":"2026-03-10T18:53:44.752719Z","steps":["trace[165859322] 'agreement among raft nodes before linearized reading' (duration: 168.770678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:44.752562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:44.398405Z","time spent":"354.068485ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:45.057821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.629338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:53:45.057896Z","caller":"traceutil/trace.go:171","msg":"trace[1089704182] linearizableReadLoop","detail":"{readStateIndex:24179; appliedIndex:24178; }","duration":"265.051529ms","start":"2026-03-10T18:53:44.79283Z","end":"2026-03-10T18:53:45.057882Z","steps":["trace[1089704182] 'read index received' (duration: 93.284987ms)","trace[1089704182] 'applied index is now lower than readState.Index' (duration: 171.763742ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:45.057947Z","caller":"traceutil/trace.go:171","msg":"trace[1068714605] transaction","detail":"{read_only:false; response_revision:23381; number_of_response:1; }","duration":"301.369109ms","start":"2026-03-10T18:53:44.75657Z","end":"2026-03-10T18:53:45.057939Z","steps":["trace[1068714605] 'process raft request' (duration: 129.809952ms)","trace[1068714605] 'compare' (duration: 171.26995ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:45.057983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:44.756553Z","time spent":"301.409969ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:45.058142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.304264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:45.05837Z","caller":"traceutil/trace.go:171","msg":"trace[855329915] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:23381; }","duration":"265.407096ms","start":"2026-03-10T18:53:44.792786Z","end":"2026-03-10T18:53:45.058193Z","steps":["trace[855329915] 'agreement among raft nodes before linearized reading' (duration: 265.293294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:45.394026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.142985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-03-10T18:53:45.394299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.277538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-10T18:53:45.394314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.938404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-10T18:53:45.394351Z","caller":"traceutil/trace.go:171","msg":"trace[2066718065] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:23382; }","duration":"495.37252ms","start":"2026-03-10T18:53:44.898965Z","end":"2026-03-10T18:53:45.394337Z","steps":["trace[2066718065] 'agreement among raft nodes before linearized reading' (duration: 495.281039ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:45.394354Z","caller":"traceutil/trace.go:171","msg":"trace[1973940163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23382; }","duration":"363.019936ms","start":"2026-03-10T18:53:45.031323Z","end":"2026-03-10T18:53:45.394343Z","steps":["trace[1973940163] 'agreement among raft nodes before linearized reading' (duration: 362.889243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:45.39438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:44.898942Z","time spent":"495.430332ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-03-10T18:53:45.394362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.491065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-03-10T18:53:45.394389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.031295Z","time spent":"363.085606ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-10T18:53:45.394421Z","caller":"traceutil/trace.go:171","msg":"trace[231718413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23382; }","duration":"363.592648ms","start":"2026-03-10T18:53:45.030814Z","end":"2026-03-10T18:53:45.394406Z","steps":["trace[231718413] 'agreement among raft nodes before linearized reading' (duration: 363.418294ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:45.394388Z","caller":"traceutil/trace.go:171","msg":"trace[337325429] transaction","detail":"{read_only:false; response_revision:23382; number_of_response:1; }","duration":"361.436941ms","start":"2026-03-10T18:53:45.032902Z","end":"2026-03-10T18:53:45.394339Z","steps":["trace[337325429] 'process raft request' (duration: 166.892967ms)","trace[337325429] 'compare' (duration: 193.773617ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:45.394427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.382435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:45.394498Z","caller":"traceutil/trace.go:171","msg":"trace[1089271222] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:23382; }","duration":"507.491326ms","start":"2026-03-10T18:53:44.886993Z","end":"2026-03-10T18:53:45.394484Z","steps":["trace[1089271222] 'agreement among raft nodes before linearized reading' (duration: 507.385904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:45.394458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.030795Z","time spent":"363.657049ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-10T18:53:45.394542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:44.886974Z","time spent":"507.558338ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-03-10T18:53:45.394615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.032885Z","time spent":"361.610005ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:53:45.394162Z","caller":"traceutil/trace.go:171","msg":"trace[1852083646] linearizableReadLoop","detail":"{readStateIndex:24180; appliedIndex:24179; }","duration":"336.234337ms","start":"2026-03-10T18:53:45.057912Z","end":"2026-03-10T18:53:45.394146Z","steps":["trace[1852083646] 'read index received' (duration: 141.856467ms)","trace[1852083646] 'applied index is now lower than readState.Index' (duration: 194.37642ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:45.395047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.305813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-10T18:53:45.395078Z","caller":"traceutil/trace.go:171","msg":"trace[193367973] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:23382; }","duration":"155.371074ms","start":"2026-03-10T18:53:45.2397Z","end":"2026-03-10T18:53:45.395071Z","steps":["trace[193367973] 'agreement among raft nodes before linearized reading' (duration: 155.266442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:45.396933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.841604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:45.396981Z","caller":"traceutil/trace.go:171","msg":"trace[284321896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23382; }","duration":"172.984557ms","start":"2026-03-10T18:53:45.223984Z","end":"2026-03-10T18:53:45.396969Z","steps":["trace[284321896] 'agreement among raft nodes before linearized reading' (duration: 170.899513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:45.802436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.183961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:53:45.802556Z","caller":"traceutil/trace.go:171","msg":"trace[1779523727] transaction","detail":"{read_only:false; response_revision:23383; number_of_response:1; }","duration":"402.92105ms","start":"2026-03-10T18:53:45.399612Z","end":"2026-03-10T18:53:45.802533Z","steps":["trace[1779523727] 'process raft request' (duration: 145.605586ms)","trace[1779523727] 'compare' (duration: 257.000028ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:45.802636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.399594Z","time spent":"403.002693ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:53:45.96082Z","caller":"traceutil/trace.go:171","msg":"trace[1540495648] linearizableReadLoop","detail":"{readStateIndex:24184; appliedIndex:24181; }","duration":"378.422552ms","start":"2026-03-10T18:53:45.582364Z","end":"2026-03-10T18:53:45.960787Z","steps":["trace[1540495648] 'read index received' (duration: 341.848336ms)","trace[1540495648] 'applied index is now lower than readState.Index' (duration: 36.573135ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:45.960876Z","caller":"traceutil/trace.go:171","msg":"trace[415119687] transaction","detail":"{read_only:false; response_revision:23385; number_of_response:1; }","duration":"560.389018ms","start":"2026-03-10T18:53:45.400442Z","end":"2026-03-10T18:53:45.960831Z","steps":["trace[415119687] 'process raft request' (duration: 560.143033ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:45.96093Z","caller":"traceutil/trace.go:171","msg":"trace[1672649988] transaction","detail":"{read_only:false; response_revision:23384; number_of_response:1; }","duration":"560.504171ms","start":"2026-03-10T18:53:45.400396Z","end":"2026-03-10T18:53:45.9609Z","steps":["trace[1672649988] 'process raft request' (duration: 523.801903ms)","trace[1672649988] 'compare' (duration: 36.102825ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:45.960909Z","caller":"traceutil/trace.go:171","msg":"trace[383924967] transaction","detail":"{read_only:false; response_revision:23386; number_of_response:1; }","duration":"441.149521ms","start":"2026-03-10T18:53:45.519721Z","end":"2026-03-10T18:53:45.96087Z","steps":["trace[383924967] 'process raft request' (duration: 440.970948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:45.961068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.400418Z","time spent":"560.549532ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:45.961068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.400375Z","time spent":"560.608584ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:45.961109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.519693Z","time spent":"441.302065ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:53:45.961243Z","caller":"traceutil/trace.go:171","msg":"trace[1944968449] transaction","detail":"{read_only:false; response_revision:23387; number_of_response:1; }","duration":"159.620674ms","start":"2026-03-10T18:53:45.80161Z","end":"2026-03-10T18:53:45.961231Z","steps":["trace[1944968449] 'process raft request' (duration: 159.139164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:46.254155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.096253ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-10T18:53:46.254228Z","caller":"traceutil/trace.go:171","msg":"trace[791166956] linearizableReadLoop","detail":"{readStateIndex:24186; appliedIndex:24184; }","duration":"293.276987ms","start":"2026-03-10T18:53:45.960939Z","end":"2026-03-10T18:53:46.254216Z","steps":["trace[791166956] 'read index received' (duration: 57.921µs)","trace[791166956] 'applied index is now lower than readState.Index' (duration: 293.217906ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:46.254522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.150518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-03-10T18:53:46.254545Z","caller":"traceutil/trace.go:171","msg":"trace[1900042734] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:23387; }","duration":"672.21613ms","start":"2026-03-10T18:53:45.582323Z","end":"2026-03-10T18:53:46.254539Z","steps":["trace[1900042734] 'agreement among raft nodes before linearized reading' (duration: 378.657877ms)","trace[1900042734] 'range keys from in-memory index tree' (duration: 293.472951ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:46.254566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.582302Z","time spent":"672.25769ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-03-10T18:53:46.255663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.400344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-03-10T18:53:46.255717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.741509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-10T18:53:46.255736Z","caller":"traceutil/trace.go:171","msg":"trace[1209610545] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23387; }","duration":"472.519427ms","start":"2026-03-10T18:53:45.783199Z","end":"2026-03-10T18:53:46.255719Z","steps":["trace[1209610545] 'agreement among raft nodes before linearized reading' (duration: 472.372724ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:46.255785Z","caller":"traceutil/trace.go:171","msg":"trace[1719214431] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:23387; }","duration":"488.855742ms","start":"2026-03-10T18:53:45.766912Z","end":"2026-03-10T18:53:46.255768Z","steps":["trace[1719214431] 'agreement among raft nodes before linearized reading' (duration: 488.720479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:46.255792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.783188Z","time spent":"472.593118ms","remote":"127.0.0.1:33612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-03-10T18:53:46.255789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.129427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-03-10T18:53:46.255826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:45.766894Z","time spent":"488.921483ms","remote":"127.0.0.1:39498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-03-10T18:53:46.255854Z","caller":"traceutil/trace.go:171","msg":"trace[1215508255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23387; }","duration":"120.234739ms","start":"2026-03-10T18:53:46.135603Z","end":"2026-03-10T18:53:46.255838Z","steps":["trace[1215508255] 'agreement among raft nodes before linearized reading' (duration: 120.021194ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:46.544229Z","caller":"traceutil/trace.go:171","msg":"trace[1545064980] transaction","detail":"{read_only:false; response_revision:23389; number_of_response:1; }","duration":"147.999368ms","start":"2026-03-10T18:53:46.396201Z","end":"2026-03-10T18:53:46.5442Z","steps":["trace[1545064980] 'process raft request' (duration: 147.824304ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:46.84261Z","caller":"traceutil/trace.go:171","msg":"trace[1595919428] transaction","detail":"{read_only:false; response_revision:23390; number_of_response:1; }","duration":"376.072623ms","start":"2026-03-10T18:53:46.466516Z","end":"2026-03-10T18:53:46.842589Z","steps":["trace[1595919428] 'process raft request' (duration: 357.10768ms)","trace[1595919428] 'compare' (duration: 18.688967ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:46.842756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:46.466491Z","time spent":"376.179605ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:53:46.871474Z","caller":"traceutil/trace.go:171","msg":"trace[1536760728] transaction","detail":"{read_only:false; response_revision:23391; number_of_response:1; }","duration":"297.22499ms","start":"2026-03-10T18:53:46.574226Z","end":"2026-03-10T18:53:46.871451Z","steps":["trace[1536760728] 'process raft request' (duration: 296.570536ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:46.87165Z","caller":"traceutil/trace.go:171","msg":"trace[915615740] transaction","detail":"{read_only:false; response_revision:23392; number_of_response:1; }","duration":"113.350002ms","start":"2026-03-10T18:53:46.758291Z","end":"2026-03-10T18:53:46.871641Z","steps":["trace[915615740] 'process raft request' (duration: 112.637627ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:46.871726Z","caller":"traceutil/trace.go:171","msg":"trace[995748233] transaction","detail":"{read_only:false; response_revision:23393; number_of_response:1; }","duration":"113.366822ms","start":"2026-03-10T18:53:46.758352Z","end":"2026-03-10T18:53:46.871719Z","steps":["trace[995748233] 'process raft request' (duration: 112.613447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:47.124433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.975246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:47.124529Z","caller":"traceutil/trace.go:171","msg":"trace[2070817491] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:23393; }","duration":"131.077439ms","start":"2026-03-10T18:53:46.993432Z","end":"2026-03-10T18:53:47.124509Z","steps":["trace[2070817491] 'count revisions from in-memory index tree' (duration: 130.872464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:47.338762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.315333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:47.338834Z","caller":"traceutil/trace.go:171","msg":"trace[1287766588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23394; }","duration":"114.451426ms","start":"2026-03-10T18:53:47.224367Z","end":"2026-03-10T18:53:47.338818Z","steps":["trace[1287766588] 'range keys from in-memory index tree' (duration: 114.244872ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:47.646469Z","caller":"traceutil/trace.go:171","msg":"trace[392081253] linearizableReadLoop","detail":"{readStateIndex:24194; appliedIndex:24193; }","duration":"220.636757ms","start":"2026-03-10T18:53:47.425809Z","end":"2026-03-10T18:53:47.646446Z","steps":["trace[392081253] 'read index received' (duration: 220.464452ms)","trace[392081253] 'applied index is now lower than readState.Index' (duration: 171.445µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:47.646605Z","caller":"traceutil/trace.go:171","msg":"trace[634117872] transaction","detail":"{read_only:false; response_revision:23395; number_of_response:1; }","duration":"246.497775ms","start":"2026-03-10T18:53:47.400079Z","end":"2026-03-10T18:53:47.646576Z","steps":["trace[634117872] 'process raft request' (duration: 246.213688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:47.646652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.82484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:47.64669Z","caller":"traceutil/trace.go:171","msg":"trace[507248915] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:23395; }","duration":"220.910183ms","start":"2026-03-10T18:53:47.425767Z","end":"2026-03-10T18:53:47.646677Z","steps":["trace[507248915] 'agreement among raft nodes before linearized reading' (duration: 220.806431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:47.646819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.093369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-03-10T18:53:47.646904Z","caller":"traceutil/trace.go:171","msg":"trace[206802615] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:23395; }","duration":"198.225282ms","start":"2026-03-10T18:53:47.448661Z","end":"2026-03-10T18:53:47.646886Z","steps":["trace[206802615] 'agreement among raft nodes before linearized reading' (duration: 197.965607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.007188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.841182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-10T18:53:48.007352Z","caller":"traceutil/trace.go:171","msg":"trace[1338204856] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23395; }","duration":"264.048227ms","start":"2026-03-10T18:53:47.743279Z","end":"2026-03-10T18:53:48.007327Z","steps":["trace[1338204856] 'count revisions from in-memory index tree' (duration: 263.771791ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:48.007376Z","caller":"traceutil/trace.go:171","msg":"trace[872274986] linearizableReadLoop","detail":"{readStateIndex:24195; appliedIndex:24194; }","duration":"199.59595ms","start":"2026-03-10T18:53:47.807759Z","end":"2026-03-10T18:53:48.007355Z","steps":["trace[872274986] 'read index received' (duration: 156.940146ms)","trace[872274986] 'applied index is now lower than readState.Index' (duration: 42.654874ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:48.007479Z","caller":"traceutil/trace.go:171","msg":"trace[650477725] transaction","detail":"{read_only:false; response_revision:23396; number_of_response:1; }","duration":"234.50126ms","start":"2026-03-10T18:53:47.772969Z","end":"2026-03-10T18:53:48.007471Z","steps":["trace[650477725] 'process raft request' (duration: 191.716103ms)","trace[650477725] 'compare' (duration: 42.522641ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:48.007695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.926668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-10T18:53:48.007748Z","caller":"traceutil/trace.go:171","msg":"trace[100174996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23396; }","duration":"200.00358ms","start":"2026-03-10T18:53:47.807731Z","end":"2026-03-10T18:53:48.007735Z","steps":["trace[100174996] 'agreement among raft nodes before linearized reading' (duration: 199.843496ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:48.259547Z","caller":"traceutil/trace.go:171","msg":"trace[1703309092] transaction","detail":"{read_only:false; response_revision:23397; number_of_response:1; }","duration":"432.967597ms","start":"2026-03-10T18:53:47.826552Z","end":"2026-03-10T18:53:48.25952Z","steps":["trace[1703309092] 'process raft request' (duration: 432.805574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.259773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:47.826524Z","time spent":"433.1097ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:53:48.266784Z","caller":"traceutil/trace.go:171","msg":"trace[1473556856] linearizableReadLoop","detail":"{readStateIndex:24198; appliedIndex:24196; }","duration":"259.324228ms","start":"2026-03-10T18:53:48.007442Z","end":"2026-03-10T18:53:48.266766Z","steps":["trace[1473556856] 'read index received' (duration: 252.720828ms)","trace[1473556856] 'applied index is now lower than readState.Index' (duration: 6.60235ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:48.266848Z","caller":"traceutil/trace.go:171","msg":"trace[175654486] transaction","detail":"{read_only:false; response_revision:23399; number_of_response:1; }","duration":"298.088559ms","start":"2026-03-10T18:53:47.968726Z","end":"2026-03-10T18:53:48.266814Z","steps":["trace[175654486] 'process raft request' (duration: 297.946686ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:48.26688Z","caller":"traceutil/trace.go:171","msg":"trace[2093836012] transaction","detail":"{read_only:false; response_revision:23400; number_of_response:1; }","duration":"255.270601ms","start":"2026-03-10T18:53:48.011586Z","end":"2026-03-10T18:53:48.266857Z","steps":["trace[2093836012] 'process raft request' (duration: 255.125508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.266995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.886448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-10T18:53:48.267015Z","caller":"traceutil/trace.go:171","msg":"trace[544250143] transaction","detail":"{read_only:false; response_revision:23398; number_of_response:1; }","duration":"299.52972ms","start":"2026-03-10T18:53:47.967476Z","end":"2026-03-10T18:53:48.267006Z","steps":["trace[544250143] 'process raft request' (duration: 299.08601ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:48.267033Z","caller":"traceutil/trace.go:171","msg":"trace[1459728489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23400; }","duration":"300.953639ms","start":"2026-03-10T18:53:47.966068Z","end":"2026-03-10T18:53:48.267021Z","steps":["trace[1459728489] 'agreement among raft nodes before linearized reading' (duration: 300.794446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.267064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:47.96606Z","time spent":"300.99705ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-10T18:53:48.267196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.643218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18835"} {"level":"info","ts":"2026-03-10T18:53:48.267249Z","caller":"traceutil/trace.go:171","msg":"trace[138974684] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:23400; }","duration":"254.736909ms","start":"2026-03-10T18:53:48.012501Z","end":"2026-03-10T18:53:48.267238Z","steps":["trace[138974684] 'agreement among raft nodes before linearized reading' (duration: 254.553266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.267317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.604143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-10T18:53:48.267359Z","caller":"traceutil/trace.go:171","msg":"trace[1146954302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23400; }","duration":"301.682905ms","start":"2026-03-10T18:53:47.965664Z","end":"2026-03-10T18:53:48.267347Z","steps":["trace[1146954302] 'agreement among raft nodes before linearized reading' (duration: 301.593013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.267394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:47.965639Z","time spent":"301.745006ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-10T18:53:48.544751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.700035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:53:48.544869Z","caller":"traceutil/trace.go:171","msg":"trace[1608344953] transaction","detail":"{read_only:false; response_revision:23401; number_of_response:1; }","duration":"274.09119ms","start":"2026-03-10T18:53:48.270755Z","end":"2026-03-10T18:53:48.544846Z","steps":["trace[1608344953] 'process raft request' (duration: 131.213451ms)","trace[1608344953] 'compare' (duration: 142.44039ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:48.595846Z","caller":"traceutil/trace.go:171","msg":"trace[1968592136] linearizableReadLoop","detail":"{readStateIndex:24201; appliedIndex:24199; }","duration":"292.116942ms","start":"2026-03-10T18:53:48.3037Z","end":"2026-03-10T18:53:48.595817Z","steps":["trace[1968592136] 'read index received' (duration: 98.268903ms)","trace[1968592136] 'applied index is now lower than readState.Index' (duration: 193.846769ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:48.596156Z","caller":"traceutil/trace.go:171","msg":"trace[762640572] transaction","detail":"{read_only:false; response_revision:23402; number_of_response:1; }","duration":"324.56439ms","start":"2026-03-10T18:53:48.271573Z","end":"2026-03-10T18:53:48.596138Z","steps":["trace[762640572] 'process raft request' (duration: 324.105209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.596305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:48.27155Z","time spent":"324.671553ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:48.59647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.766606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-10T18:53:48.596504Z","caller":"traceutil/trace.go:171","msg":"trace[600293506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23402; }","duration":"292.813177ms","start":"2026-03-10T18:53:48.303683Z","end":"2026-03-10T18:53:48.596496Z","steps":["trace[600293506] 'agreement among raft nodes before linearized reading' (duration: 292.687165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.730552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.882687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:48.730587Z","caller":"traceutil/trace.go:171","msg":"trace[5338682] transaction","detail":"{read_only:false; response_revision:23403; number_of_response:1; }","duration":"181.883555ms","start":"2026-03-10T18:53:48.548661Z","end":"2026-03-10T18:53:48.730545Z","steps":["trace[5338682] 'process raft request' (duration: 181.197ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:48.730711Z","caller":"traceutil/trace.go:171","msg":"trace[759112198] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23403; }","duration":"191.102202ms","start":"2026-03-10T18:53:48.539586Z","end":"2026-03-10T18:53:48.730689Z","steps":["trace[759112198] 'agreement among raft nodes before linearized reading' (duration: 190.833655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.730587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.297879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-10T18:53:48.730788Z","caller":"traceutil/trace.go:171","msg":"trace[1271560308] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23403; }","duration":"194.553805ms","start":"2026-03-10T18:53:48.536225Z","end":"2026-03-10T18:53:48.730779Z","steps":["trace[1271560308] 'agreement among raft nodes before linearized reading' (duration: 194.224038ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:48.73041Z","caller":"traceutil/trace.go:171","msg":"trace[1902704163] linearizableReadLoop","detail":"{readStateIndex:24202; appliedIndex:24201; }","duration":"134.461601ms","start":"2026-03-10T18:53:48.595923Z","end":"2026-03-10T18:53:48.730385Z","steps":["trace[1902704163] 'read index received' (duration: 133.92875ms)","trace[1902704163] 'applied index is now lower than readState.Index' (duration: 531.811µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:48.730952Z","caller":"traceutil/trace.go:171","msg":"trace[2079388352] transaction","detail":"{read_only:false; response_revision:23404; number_of_response:1; }","duration":"131.026978ms","start":"2026-03-10T18:53:48.599899Z","end":"2026-03-10T18:53:48.730926Z","steps":["trace[2079388352] 'process raft request' (duration: 130.885045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.731259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.119914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-58c895578f-mdjjj.189b8f963b237212\" ","response":"range_response_count:1 size:782"} {"level":"info","ts":"2026-03-10T18:53:48.731298Z","caller":"traceutil/trace.go:171","msg":"trace[1418590666] range","detail":"{range_begin:/registry/events/openstack/magnum-api-58c895578f-mdjjj.189b8f963b237212; range_end:; response_count:1; response_revision:23403; }","duration":"426.671865ms","start":"2026-03-10T18:53:48.304612Z","end":"2026-03-10T18:53:48.731284Z","steps":["trace[1418590666] 'agreement among raft nodes before linearized reading' (duration: 426.047812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:48.731329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:48.304569Z","time spent":"426.744217ms","remote":"127.0.0.1:39186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":805,"request content":"key:\"/registry/events/openstack/magnum-api-58c895578f-mdjjj.189b8f963b237212\" "} {"level":"info","ts":"2026-03-10T18:53:52.452994Z","caller":"traceutil/trace.go:171","msg":"trace[667909829] transaction","detail":"{read_only:false; response_revision:23429; number_of_response:1; }","duration":"175.212674ms","start":"2026-03-10T18:53:52.277749Z","end":"2026-03-10T18:53:52.452962Z","steps":["trace[667909829] 'process raft request' (duration: 175.059541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:52.677019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.36892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:53:52.677127Z","caller":"traceutil/trace.go:171","msg":"trace[1086804067] linearizableReadLoop","detail":"{readStateIndex:24230; appliedIndex:24228; }","duration":"397.280061ms","start":"2026-03-10T18:53:52.279826Z","end":"2026-03-10T18:53:52.677107Z","steps":["trace[1086804067] 'read index received' (duration: 173.097929ms)","trace[1086804067] 'applied index is now lower than readState.Index' (duration: 224.180512ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:53:52.677215Z","caller":"traceutil/trace.go:171","msg":"trace[631732187] transaction","detail":"{read_only:false; response_revision:23430; number_of_response:1; }","duration":"397.69555ms","start":"2026-03-10T18:53:52.279509Z","end":"2026-03-10T18:53:52.677204Z","steps":["trace[631732187] 'process raft request' (duration: 270.042955ms)","trace[631732187] 'compare' (duration: 127.044132ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:53:52.677274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:52.279495Z","time spent":"397.745902ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:53:52.677516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.718491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:53:52.677602Z","caller":"traceutil/trace.go:171","msg":"trace[424183001] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:23430; }","duration":"164.887846ms","start":"2026-03-10T18:53:52.51269Z","end":"2026-03-10T18:53:52.677578Z","steps":["trace[424183001] 'agreement among raft nodes before linearized reading' (duration: 164.698361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:52.677609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.766232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-10T18:53:52.677691Z","caller":"traceutil/trace.go:171","msg":"trace[1077790269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23430; }","duration":"397.897255ms","start":"2026-03-10T18:53:52.279775Z","end":"2026-03-10T18:53:52.677672Z","steps":["trace[1077790269] 'agreement among raft nodes before linearized reading' (duration: 397.636328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:53:52.677745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:53:52.279723Z","time spent":"398.005648ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-10T18:53:52.677728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.943087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-10T18:53:52.677854Z","caller":"traceutil/trace.go:171","msg":"trace[816040304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23430; }","duration":"107.109081ms","start":"2026-03-10T18:53:52.570699Z","end":"2026-03-10T18:53:52.677808Z","steps":["trace[816040304] 'agreement among raft nodes before linearized reading' (duration: 106.809163ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:53.017264Z","caller":"traceutil/trace.go:171","msg":"trace[572923516] transaction","detail":"{read_only:false; response_revision:23438; number_of_response:1; }","duration":"122.557449ms","start":"2026-03-10T18:53:52.894684Z","end":"2026-03-10T18:53:53.017242Z","steps":["trace[572923516] 'process raft request' (duration: 122.511218ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:53:53.017326Z","caller":"traceutil/trace.go:171","msg":"trace[1547564195] transaction","detail":"{read_only:false; response_revision:23437; number_of_response:1; }","duration":"129.755091ms","start":"2026-03-10T18:53:52.887547Z","end":"2026-03-10T18:53:53.017302Z","steps":["trace[1547564195] 'process raft request' (duration: 127.768648ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:54:09.93153Z","caller":"traceutil/trace.go:171","msg":"trace[663332291] transaction","detail":"{read_only:false; response_revision:23672; number_of_response:1; }","duration":"220.390342ms","start":"2026-03-10T18:54:09.711116Z","end":"2026-03-10T18:54:09.931506Z","steps":["trace[663332291] 'process raft request' (duration: 220.247229ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:54:09.976309Z","caller":"traceutil/trace.go:171","msg":"trace[292939084] linearizableReadLoop","detail":"{readStateIndex:24477; appliedIndex:24475; }","duration":"182.220053ms","start":"2026-03-10T18:54:09.794056Z","end":"2026-03-10T18:54:09.976276Z","steps":["trace[292939084] 'read index received' (duration: 137.427283ms)","trace[292939084] 'applied index is now lower than readState.Index' (duration: 44.79147ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:54:09.976347Z","caller":"traceutil/trace.go:171","msg":"trace[858968891] transaction","detail":"{read_only:false; response_revision:23673; number_of_response:1; }","duration":"229.981375ms","start":"2026-03-10T18:54:09.746335Z","end":"2026-03-10T18:54:09.976316Z","steps":["trace[858968891] 'process raft request' (duration: 229.73514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:54:09.977451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.388307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210990"} {"level":"info","ts":"2026-03-10T18:54:09.977506Z","caller":"traceutil/trace.go:171","msg":"trace[393859679] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:23673; }","duration":"183.509019ms","start":"2026-03-10T18:54:09.793982Z","end":"2026-03-10T18:54:09.977491Z","steps":["trace[393859679] 'agreement among raft nodes before linearized reading' (duration: 182.435607ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:54:10.35949Z","caller":"traceutil/trace.go:171","msg":"trace[1163467448] linearizableReadLoop","detail":"{readStateIndex:24478; appliedIndex:24477; }","duration":"132.414797ms","start":"2026-03-10T18:54:10.227056Z","end":"2026-03-10T18:54:10.359471Z","steps":["trace[1163467448] 'read index received' (duration: 132.277914ms)","trace[1163467448] 'applied index is now lower than readState.Index' (duration: 136.163µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:54:10.359578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.507469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-10T18:54:10.359599Z","caller":"traceutil/trace.go:171","msg":"trace[846981252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23674; }","duration":"132.573251ms","start":"2026-03-10T18:54:10.22702Z","end":"2026-03-10T18:54:10.359593Z","steps":["trace[846981252] 'agreement among raft nodes before linearized reading' (duration: 132.52449ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:54:10.359754Z","caller":"traceutil/trace.go:171","msg":"trace[80985911] transaction","detail":"{read_only:false; response_revision:23674; number_of_response:1; }","duration":"225.54102ms","start":"2026-03-10T18:54:10.134189Z","end":"2026-03-10T18:54:10.35973Z","steps":["trace[80985911] 'process raft request' (duration: 225.162633ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:54:10.94866Z","caller":"traceutil/trace.go:171","msg":"trace[231148964] transaction","detail":"{read_only:false; response_revision:23678; number_of_response:1; }","duration":"141.381258ms","start":"2026-03-10T18:54:10.807258Z","end":"2026-03-10T18:54:10.948639Z","steps":["trace[231148964] 'process raft request' (duration: 141.188513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:54:10.948854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.553055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-10T18:54:10.948899Z","caller":"traceutil/trace.go:171","msg":"trace[1781407253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23679; }","duration":"137.637268ms","start":"2026-03-10T18:54:10.811251Z","end":"2026-03-10T18:54:10.948888Z","steps":["trace[1781407253] 'agreement among raft nodes before linearized reading' (duration: 137.456084ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:54:10.948659Z","caller":"traceutil/trace.go:171","msg":"trace[2058826265] linearizableReadLoop","detail":"{readStateIndex:24483; appliedIndex:24481; }","duration":"137.348951ms","start":"2026-03-10T18:54:10.811274Z","end":"2026-03-10T18:54:10.948623Z","steps":["trace[2058826265] 'read index received' (duration: 72.769562ms)","trace[2058826265] 'applied index is now lower than readState.Index' (duration: 64.578319ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:54:10.949028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.270719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-10T18:54:10.94905Z","caller":"traceutil/trace.go:171","msg":"trace[1591997510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23679; }","duration":"137.328041ms","start":"2026-03-10T18:54:10.811715Z","end":"2026-03-10T18:54:10.949043Z","steps":["trace[1591997510] 'agreement among raft nodes before linearized reading' (duration: 137.26312ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:56:27.657115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22142} {"level":"info","ts":"2026-03-10T18:56:27.795111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22142,"took":"134.287917ms","hash":2747308699,"current-db-size-bytes":51015680,"current-db-size":"51 MB","current-db-size-in-use-bytes":33439744,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-03-10T18:56:27.795156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2747308699,"revision":22142,"compact-revision":19148} {"level":"info","ts":"2026-03-10T18:57:31.600578Z","caller":"traceutil/trace.go:171","msg":"trace[560189494] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"232.423543ms","start":"2026-03-10T18:57:31.368128Z","end":"2026-03-10T18:57:31.600551Z","steps":["trace[560189494] 'process raft request' (duration: 232.162828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:57:31.990185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.579074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-10T18:57:31.991242Z","caller":"traceutil/trace.go:171","msg":"trace[1014238437] transaction","detail":"{read_only:false; response_revision:25574; number_of_response:1; }","duration":"522.918808ms","start":"2026-03-10T18:57:31.468285Z","end":"2026-03-10T18:57:31.991204Z","steps":["trace[1014238437] 'process raft request' (duration: 282.23797ms)","trace[1014238437] 'compare' (duration: 239.123015ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:57:31.991312Z","caller":"traceutil/trace.go:171","msg":"trace[138499589] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"288.020564ms","start":"2026-03-10T18:57:31.703251Z","end":"2026-03-10T18:57:31.991272Z","steps":["trace[138499589] 'process raft request' (duration: 287.030562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:57:31.991397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:31.468262Z","time spent":"523.054692ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:57:31.992988Z","caller":"traceutil/trace.go:171","msg":"trace[1349901623] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"288.614206ms","start":"2026-03-10T18:57:31.703383Z","end":"2026-03-10T18:57:31.991997Z","steps":["trace[1349901623] 'process raft request' (duration: 287.560553ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:57:32.053182Z","caller":"traceutil/trace.go:171","msg":"trace[1006949625] linearizableReadLoop","detail":"{readStateIndex:26432; appliedIndex:26430; }","duration":"108.175726ms","start":"2026-03-10T18:57:31.94498Z","end":"2026-03-10T18:57:32.053156Z","steps":["trace[1006949625] 'read index received' (duration: 107.997602ms)","trace[1006949625] 'applied index is now lower than readState.Index' (duration: 177.054µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:57:32.053251Z","caller":"traceutil/trace.go:171","msg":"trace[558787344] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"180.89345ms","start":"2026-03-10T18:57:31.872321Z","end":"2026-03-10T18:57:32.053215Z","steps":["trace[558787344] 'process raft request' (duration: 180.628764ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:57:32.053265Z","caller":"traceutil/trace.go:171","msg":"trace[906584524] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"174.515954ms","start":"2026-03-10T18:57:31.878717Z","end":"2026-03-10T18:57:32.053233Z","steps":["trace[906584524] 'process raft request' (duration: 174.388272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:57:32.053517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.509994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-10T18:57:32.053575Z","caller":"traceutil/trace.go:171","msg":"trace[1985797340] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25578; }","duration":"108.623315ms","start":"2026-03-10T18:57:31.944938Z","end":"2026-03-10T18:57:32.053561Z","steps":["trace[1985797340] 'agreement among raft nodes before linearized reading' (duration: 108.36229ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T19:01:27.663717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24903} {"level":"info","ts":"2026-03-10T19:01:27.797049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24903,"took":"129.342105ms","hash":291078517,"current-db-size-bytes":51867648,"current-db-size":"52 MB","current-db-size-in-use-bytes":33271808,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-03-10T19:01:27.79711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":291078517,"revision":24903,"compact-revision":22142} {"level":"info","ts":"2026-03-10T19:05:31.791874Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"79e65b4197a2a1a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-10T19:05:31.796245Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-03-10T19:05:31.796408Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-03-10T19:06:27.673505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27325} {"level":"info","ts":"2026-03-10T19:06:27.802177Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27325,"took":"124.724457ms","hash":2151865646,"current-db-size-bytes":51867648,"current-db-size":"52 MB","current-db-size-in-use-bytes":30003200,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-10T19:06:27.802254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2151865646,"revision":27325,"compact-revision":24903} {"level":"info","ts":"2026-03-10T19:11:27.679628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29427} {"level":"info","ts":"2026-03-10T19:11:27.801387Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29427,"took":"116.847799ms","hash":4009382999,"current-db-size-bytes":51867648,"current-db-size":"52 MB","current-db-size-in-use-bytes":30339072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-10T19:11:27.801454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4009382999,"revision":29427,"compact-revision":27325} {"level":"info","ts":"2026-03-10T19:16:27.689613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31612} {"level":"info","ts":"2026-03-10T19:16:27.840345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31612,"took":"145.736991ms","hash":2233746859,"current-db-size-bytes":51867648,"current-db-size":"52 MB","current-db-size-in-use-bytes":29184000,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-10T19:16:27.840428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2233746859,"revision":31612,"compact-revision":29427} {"level":"info","ts":"2026-03-10T19:17:06.019241Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000088fb.wal"} {"level":"info","ts":"2026-03-10T19:21:27.694613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33697} {"level":"info","ts":"2026-03-10T19:21:27.823568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33697,"took":"125.360738ms","hash":2878527654,"current-db-size-bytes":53182464,"current-db-size":"53 MB","current-db-size-in-use-bytes":30052352,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-10T19:21:27.823625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2878527654,"revision":33697,"compact-revision":31612}