{"level":"warn","ts":"2026-06-04T11:59:30.974572Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:59:30.97468Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-04T11:59:30.974759Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:59:30.974772Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-06-04T11:59:30.974799Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-04T11:59:30.975525Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-06-04T11:59:30.975617Z","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.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-04T11:59:30.978484Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.59658ms"} {"level":"info","ts":"2026-06-04T11:59:30.983613Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-06-04T11:59:30.98376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-04T11:59:30.983788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-06-04T11:59:30.9838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-04T11:59:30.983813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-06-04T11:59:30.983864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-06-04T11:59:30.989352Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-04T11:59:30.991054Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-04T11:59:30.993015Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-04T11:59:30.995403Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-04T11:59:30.995554Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-04T11:59:30.995615Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-04T11:59:30.995778Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-04T11:59:30.995797Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-04T11:59:30.997556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-06-04T11:59:30.998378Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-06-04T11:59:31.000783Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-04T11:59:31.000896Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-06-04T11:59:31.001039Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-06-04T11:59:31.001389Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-04T11:59:31.001459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-04T11:59:31.185149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-04T11:59:31.185222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-04T11:59:31.185316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-06-04T11:59:31.185357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-06-04T11:59:31.185371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-06-04T11:59:31.185381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-06-04T11:59:31.1854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-06-04T11:59:31.193334Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-06-04T11:59:31.193381Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:59:31.193365Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:31.193449Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:59:31.193744Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-04T11:59:31.193814Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-04T11:59:31.19459Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:31.194732Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:31.194792Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:31.197133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-06-04T11:59:31.198197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-04T11:59:34.540883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.29417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-04T11:59:34.541102Z","caller":"traceutil/trace.go:171","msg":"trace[322688732] transaction","detail":"{read_only:false; response_revision:142; number_of_response:1; }","duration":"219.591542ms","start":"2026-06-04T11:59:34.321458Z","end":"2026-06-04T11:59:34.54105Z","steps":["trace[322688732] 'process raft request' (duration: 54.50206ms)","trace[322688732] 'compare' (duration: 164.145266ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:00:08.93176Z","caller":"traceutil/trace.go:171","msg":"trace[911379062] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"129.221921ms","start":"2026-06-04T12:00:08.802515Z","end":"2026-06-04T12:00:08.931736Z","steps":["trace[911379062] 'process raft request' (duration: 129.103428ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.964786Z","caller":"traceutil/trace.go:171","msg":"trace[456721373] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"154.297123ms","start":"2026-06-04T12:00:08.810461Z","end":"2026-06-04T12:00:08.964758Z","steps":["trace[456721373] 'process raft request' (duration: 154.043527ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.965135Z","caller":"traceutil/trace.go:171","msg":"trace[447607894] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"152.363592ms","start":"2026-06-04T12:00:08.812739Z","end":"2026-06-04T12:00:08.965103Z","steps":["trace[447607894] 'process raft request' (duration: 151.90807ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.965129Z","caller":"traceutil/trace.go:171","msg":"trace[46049366] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"146.263767ms","start":"2026-06-04T12:00:08.818825Z","end":"2026-06-04T12:00:08.965088Z","steps":["trace[46049366] 'process raft request' (duration: 145.939119ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.965216Z","caller":"traceutil/trace.go:171","msg":"trace[1488285640] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"147.416228ms","start":"2026-06-04T12:00:08.817747Z","end":"2026-06-04T12:00:08.965163Z","steps":["trace[1488285640] 'process raft request' (duration: 146.962696ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.965264Z","caller":"traceutil/trace.go:171","msg":"trace[1492575931] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"144.971623ms","start":"2026-06-04T12:00:08.820256Z","end":"2026-06-04T12:00:08.965228Z","steps":["trace[1492575931] 'process raft request' (duration: 144.597343ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.965469Z","caller":"traceutil/trace.go:171","msg":"trace[412600505] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"144.252904ms","start":"2026-06-04T12:00:08.821196Z","end":"2026-06-04T12:00:08.965449Z","steps":["trace[412600505] 'process raft request' (duration: 143.71117ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.96546Z","caller":"traceutil/trace.go:171","msg":"trace[1695524520] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"146.499834ms","start":"2026-06-04T12:00:08.818815Z","end":"2026-06-04T12:00:08.965315Z","steps":["trace[1695524520] 'process raft request' (duration: 145.986211ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.96608Z","caller":"traceutil/trace.go:171","msg":"trace[638949215] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"102.614768ms","start":"2026-06-04T12:00:08.86345Z","end":"2026-06-04T12:00:08.966064Z","steps":["trace[638949215] 'process raft request' (duration: 102.506886ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.966271Z","caller":"traceutil/trace.go:171","msg":"trace[1635295174] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"132.485888ms","start":"2026-06-04T12:00:08.833758Z","end":"2026-06-04T12:00:08.966244Z","steps":["trace[1635295174] 'process raft request' (duration: 132.036466ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:08.966286Z","caller":"traceutil/trace.go:171","msg":"trace[310698] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"113.941502ms","start":"2026-06-04T12:00:08.852333Z","end":"2026-06-04T12:00:08.966275Z","steps":["trace[310698] 'process raft request' (duration: 113.569822ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:15.571348Z","caller":"traceutil/trace.go:171","msg":"trace[138582064] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"292.769773ms","start":"2026-06-04T12:00:15.278555Z","end":"2026-06-04T12:00:15.571324Z","steps":["trace[138582064] 'read index received' (duration: 292.037374ms)","trace[138582064] 'applied index is now lower than readState.Index' (duration: 731.509µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:00:15.571479Z","caller":"traceutil/trace.go:171","msg":"trace[2108468223] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"433.267813ms","start":"2026-06-04T12:00:15.138176Z","end":"2026-06-04T12:00:15.571444Z","steps":["trace[2108468223] 'process raft request' (duration: 432.672027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:00:15.571563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.982108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-04T12:00:15.571624Z","caller":"traceutil/trace.go:171","msg":"trace[93526157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"293.085151ms","start":"2026-06-04T12:00:15.278518Z","end":"2026-06-04T12:00:15.571603Z","steps":["trace[93526157] 'agreement among raft nodes before linearized reading' (duration: 292.930587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:00:15.571683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.065214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-04T12:00:15.571764Z","caller":"traceutil/trace.go:171","msg":"trace[76848213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"292.162317ms","start":"2026-06-04T12:00:15.279584Z","end":"2026-06-04T12:00:15.571746Z","steps":["trace[76848213] 'agreement among raft nodes before linearized reading' (duration: 291.928351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:00:15.571865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:00:15.138146Z","time spent":"433.417707ms","remote":"127.0.0.1:44778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:01:45.596886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.996836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-04T12:01:45.597006Z","caller":"traceutil/trace.go:171","msg":"trace[2060811667] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1831; }","duration":"386.162611ms","start":"2026-06-04T12:01:45.210822Z","end":"2026-06-04T12:01:45.596985Z","steps":["trace[2060811667] 'range keys from in-memory index tree' (duration: 385.75316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:45.597045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:01:45.210803Z","time spent":"386.233003ms","remote":"127.0.0.1:40498","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-06-04T12:01:45.597019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.685857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-04T12:01:45.59715Z","caller":"traceutil/trace.go:171","msg":"trace[123861330] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1831; }","duration":"338.952385ms","start":"2026-06-04T12:01:45.258181Z","end":"2026-06-04T12:01:45.597133Z","steps":["trace[123861330] 'range keys from in-memory index tree' (duration: 338.486172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:45.597463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:01:45.258154Z","time spent":"339.026627ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-04T12:01:45.611362Z","caller":"traceutil/trace.go:171","msg":"trace[894071859] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"228.982859ms","start":"2026-06-04T12:01:45.382355Z","end":"2026-06-04T12:01:45.611337Z","steps":["trace[894071859] 'read index received' (duration: 228.748182ms)","trace[894071859] 'applied index is now lower than readState.Index' (duration: 233.797µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:01:45.611592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.225626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ba7adfde-8aeb-4e5c-82a8-1ed72f38b462\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-04T12:01:45.611474Z","caller":"traceutil/trace.go:171","msg":"trace[1245815025] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"336.93975ms","start":"2026-06-04T12:01:45.274491Z","end":"2026-06-04T12:01:45.611431Z","steps":["trace[1245815025] 'process raft request' (duration: 336.692943ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:45.611671Z","caller":"traceutil/trace.go:171","msg":"trace[1801475484] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ba7adfde-8aeb-4e5c-82a8-1ed72f38b462; range_end:; response_count:1; response_revision:1832; }","duration":"229.353769ms","start":"2026-06-04T12:01:45.3823Z","end":"2026-06-04T12:01:45.611654Z","steps":["trace[1801475484] 'agreement among raft nodes before linearized reading' (duration: 229.146174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:45.611878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:01:45.274475Z","time spent":"337.207057ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:02:24.436598Z","caller":"traceutil/trace.go:171","msg":"trace[1068036382] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"113.305591ms","start":"2026-06-04T12:02:24.32327Z","end":"2026-06-04T12:02:24.436575Z","steps":["trace[1068036382] 'process raft request' (duration: 113.179018ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:24.478729Z","caller":"traceutil/trace.go:171","msg":"trace[952813025] linearizableReadLoop","detail":"{readStateIndex:2175; appliedIndex:2174; }","duration":"151.046707ms","start":"2026-06-04T12:02:24.327659Z","end":"2026-06-04T12:02:24.478706Z","steps":["trace[952813025] 'read index received' (duration: 108.946121ms)","trace[952813025] 'applied index is now lower than readState.Index' (duration: 42.099696ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:24.479078Z","caller":"traceutil/trace.go:171","msg":"trace[1034432371] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"155.214882ms","start":"2026-06-04T12:02:24.323854Z","end":"2026-06-04T12:02:24.479069Z","steps":["trace[1034432371] 'process raft request' (duration: 154.708648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:24.479305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.651784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-04T12:02:24.479334Z","caller":"traceutil/trace.go:171","msg":"trace[1857724783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2093; }","duration":"151.715306ms","start":"2026-06-04T12:02:24.327612Z","end":"2026-06-04T12:02:24.479327Z","steps":["trace[1857724783] 'agreement among raft nodes before linearized reading' (duration: 151.595853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:24.479469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.780138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-04T12:02:24.479491Z","caller":"traceutil/trace.go:171","msg":"trace[866966276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2093; }","duration":"151.823548ms","start":"2026-06-04T12:02:24.327662Z","end":"2026-06-04T12:02:24.479485Z","steps":["trace[866966276] 'agreement among raft nodes before linearized reading' (duration: 151.768947ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:36.62442Z","caller":"traceutil/trace.go:171","msg":"trace[1906761025] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"129.248969ms","start":"2026-06-04T12:02:36.495147Z","end":"2026-06-04T12:02:36.624396Z","steps":["trace[1906761025] 'process raft request' (duration: 129.026773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:36.64663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.279395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-04T12:02:36.646724Z","caller":"traceutil/trace.go:171","msg":"trace[1513536447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2155; }","duration":"115.409929ms","start":"2026-06-04T12:02:36.531293Z","end":"2026-06-04T12:02:36.646703Z","steps":["trace[1513536447] 'agreement among raft nodes before linearized reading' (duration: 115.153672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:36.646765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.71767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-04T12:02:36.646865Z","caller":"traceutil/trace.go:171","msg":"trace[1361704232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2155; }","duration":"110.851064ms","start":"2026-06-04T12:02:36.535992Z","end":"2026-06-04T12:02:36.646843Z","steps":["trace[1361704232] 'agreement among raft nodes before linearized reading' (duration: 110.584186ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:36.878554Z","caller":"traceutil/trace.go:171","msg":"trace[1813684876] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"249.609413ms","start":"2026-06-04T12:02:36.628909Z","end":"2026-06-04T12:02:36.878519Z","steps":["trace[1813684876] 'process raft request' (duration: 175.725965ms)","trace[1813684876] 'compare' (duration: 73.754195ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:36.880131Z","caller":"traceutil/trace.go:171","msg":"trace[4604559] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"227.383854ms","start":"2026-06-04T12:02:36.652721Z","end":"2026-06-04T12:02:36.880104Z","steps":["trace[4604559] 'process raft request' (duration: 227.160388ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:36.880204Z","caller":"traceutil/trace.go:171","msg":"trace[938262902] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"226.647853ms","start":"2026-06-04T12:02:36.653531Z","end":"2026-06-04T12:02:36.880179Z","steps":["trace[938262902] 'process raft request' (duration: 226.419577ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:36.880111Z","caller":"traceutil/trace.go:171","msg":"trace[1362947983] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"228.265638ms","start":"2026-06-04T12:02:36.6518Z","end":"2026-06-04T12:02:36.880066Z","steps":["trace[1362947983] 'process raft request' (duration: 227.943819ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:36.880612Z","caller":"traceutil/trace.go:171","msg":"trace[1682300387] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"183.867559ms","start":"2026-06-04T12:02:36.696725Z","end":"2026-06-04T12:02:36.880593Z","steps":["trace[1682300387] 'process raft request' (duration: 183.410997ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:36.880619Z","caller":"traceutil/trace.go:171","msg":"trace[105045275] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"105.647811ms","start":"2026-06-04T12:02:36.774955Z","end":"2026-06-04T12:02:36.880603Z","steps":["trace[105045275] 'process raft request' (duration: 105.510008ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:36.880703Z","caller":"traceutil/trace.go:171","msg":"trace[497704750] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2240; }","duration":"213.502452ms","start":"2026-06-04T12:02:36.667176Z","end":"2026-06-04T12:02:36.880678Z","steps":["trace[497704750] 'read index received' (duration: 137.610539ms)","trace[497704750] 'applied index is now lower than readState.Index' (duration: 75.890943ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:36.880766Z","caller":"traceutil/trace.go:171","msg":"trace[842422177] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"105.422684ms","start":"2026-06-04T12:02:36.775324Z","end":"2026-06-04T12:02:36.880747Z","steps":["trace[842422177] 'process raft request' (duration: 105.232159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:36.880867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.682777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:02:36.8809Z","caller":"traceutil/trace.go:171","msg":"trace[1293362965] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2162; }","duration":"213.758929ms","start":"2026-06-04T12:02:36.667133Z","end":"2026-06-04T12:02:36.880892Z","steps":["trace[1293362965] 'agreement among raft nodes before linearized reading' (duration: 213.689797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:36.880945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.785125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:02:36.881009Z","caller":"traceutil/trace.go:171","msg":"trace[1859921957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2162; }","duration":"203.898848ms","start":"2026-06-04T12:02:36.677093Z","end":"2026-06-04T12:02:36.880992Z","steps":["trace[1859921957] 'agreement among raft nodes before linearized reading' (duration: 203.779615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:36.881109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.964026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:02:36.88119Z","caller":"traceutil/trace.go:171","msg":"trace[692249603] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2162; }","duration":"194.148081ms","start":"2026-06-04T12:02:36.687023Z","end":"2026-06-04T12:02:36.881171Z","steps":["trace[692249603] 'agreement among raft nodes before linearized reading' (duration: 193.947475ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:04:05.128937Z","caller":"traceutil/trace.go:171","msg":"trace[1734351498] linearizableReadLoop","detail":"{readStateIndex:2938; appliedIndex:2936; }","duration":"105.595163ms","start":"2026-06-04T12:04:05.023317Z","end":"2026-06-04T12:04:05.128912Z","steps":["trace[1734351498] 'read index received' (duration: 43.240659ms)","trace[1734351498] 'applied index is now lower than readState.Index' (duration: 62.353524ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:04:05.128952Z","caller":"traceutil/trace.go:171","msg":"trace[2109623881] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"165.611763ms","start":"2026-06-04T12:04:04.963293Z","end":"2026-06-04T12:04:05.128905Z","steps":["trace[2109623881] 'process raft request' (duration: 103.168826ms)","trace[2109623881] 'compare' (duration: 62.19624ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:04:05.129264Z","caller":"traceutil/trace.go:171","msg":"trace[909144938] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"153.467189ms","start":"2026-06-04T12:04:04.975767Z","end":"2026-06-04T12:04:05.129234Z","steps":["trace[909144938] 'process raft request' (duration: 153.063598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:04:05.129393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.068156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-04T12:04:05.129441Z","caller":"traceutil/trace.go:171","msg":"trace[1380066301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2826; }","duration":"106.171439ms","start":"2026-06-04T12:04:05.023256Z","end":"2026-06-04T12:04:05.129428Z","steps":["trace[1380066301] 'agreement among raft nodes before linearized reading' (duration: 105.987944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:04:05.641143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.666696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:04:05.641338Z","caller":"traceutil/trace.go:171","msg":"trace[1906090612] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"313.418746ms","start":"2026-06-04T12:04:05.327903Z","end":"2026-06-04T12:04:05.641321Z","steps":["trace[1906090612] 'process raft request' (duration: 313.337944ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:04:05.641368Z","caller":"traceutil/trace.go:171","msg":"trace[698199996] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"313.488978ms","start":"2026-06-04T12:04:05.327849Z","end":"2026-06-04T12:04:05.641338Z","steps":["trace[698199996] 'process raft request' (duration: 176.531883ms)","trace[698199996] 'compare' (duration: 136.548653ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:04:05.641406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:04:05.327876Z","time spent":"313.500688ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:04:05.641493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:04:05.327824Z","time spent":"313.611061ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:04:05.641559Z","caller":"traceutil/trace.go:171","msg":"trace[546150356] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"313.635852ms","start":"2026-06-04T12:04:05.327903Z","end":"2026-06-04T12:04:05.641539Z","steps":["trace[546150356] 'process raft request' (duration: 313.388445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:04:05.641654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:04:05.327876Z","time spent":"313.733294ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:04:11.449119Z","caller":"traceutil/trace.go:171","msg":"trace[918585372] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"103.661099ms","start":"2026-06-04T12:04:11.345428Z","end":"2026-06-04T12:04:11.449089Z","steps":["trace[918585372] 'process raft request' (duration: 103.590767ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:04:11.449251Z","caller":"traceutil/trace.go:171","msg":"trace[1709033496] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"106.075876ms","start":"2026-06-04T12:04:11.343105Z","end":"2026-06-04T12:04:11.449181Z","steps":["trace[1709033496] 'process raft request' (duration: 105.733236ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:58.935081Z","caller":"traceutil/trace.go:171","msg":"trace[1156246923] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"126.021032ms","start":"2026-06-04T12:07:58.809038Z","end":"2026-06-04T12:07:58.935052Z","steps":["trace[1156246923] 'process raft request' (duration: 125.854258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:08:04.869093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.411558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:08:04.869201Z","caller":"traceutil/trace.go:171","msg":"trace[247521400] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5034; }","duration":"246.601294ms","start":"2026-06-04T12:08:04.622563Z","end":"2026-06-04T12:08:04.869179Z","steps":["trace[247521400] 'range keys from in-memory index tree' (duration: 246.328647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:08:04.869143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.649723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6509f723-62dc-4a81-a804-c204162a87db\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-04T12:08:04.869348Z","caller":"traceutil/trace.go:171","msg":"trace[258164421] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6509f723-62dc-4a81-a804-c204162a87db; range_end:; response_count:1; response_revision:5034; }","duration":"132.907421ms","start":"2026-06-04T12:08:04.736426Z","end":"2026-06-04T12:08:04.869334Z","steps":["trace[258164421] 'range keys from in-memory index tree' (duration: 132.444958ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:08:05.165281Z","caller":"traceutil/trace.go:171","msg":"trace[390680346] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"112.555833ms","start":"2026-06-04T12:08:05.052688Z","end":"2026-06-04T12:08:05.165244Z","steps":["trace[390680346] 'process raft request' (duration: 46.307893ms)","trace[390680346] 'compare' (duration: 65.959702ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:08:26.601539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.886481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:08:26.601725Z","caller":"traceutil/trace.go:171","msg":"trace[288784051] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"273.414341ms","start":"2026-06-04T12:08:26.328279Z","end":"2026-06-04T12:08:26.601693Z","steps":["trace[288784051] 'process raft request' (duration: 144.206442ms)","trace[288784051] 'compare' (duration: 128.687505ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:08:26.631733Z","caller":"traceutil/trace.go:171","msg":"trace[2023079797] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"294.021318ms","start":"2026-06-04T12:08:26.337684Z","end":"2026-06-04T12:08:26.631705Z","steps":["trace[2023079797] 'process raft request' (duration: 293.73957ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:08:26.631773Z","caller":"traceutil/trace.go:171","msg":"trace[799819229] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"273.32973ms","start":"2026-06-04T12:08:26.358404Z","end":"2026-06-04T12:08:26.631734Z","steps":["trace[799819229] 'process raft request' (duration: 273.202196ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:08:26.632024Z","caller":"traceutil/trace.go:171","msg":"trace[790968568] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"266.738158ms","start":"2026-06-04T12:08:26.365226Z","end":"2026-06-04T12:08:26.631964Z","steps":["trace[790968568] 'process raft request' (duration: 266.44097ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:09:31.651463Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-06-04T12:09:31.725188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"70.814738ms","hash":3256191016,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":33734656,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-04T12:09:31.725295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3256191016,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-06-04T12:13:31.882346Z","caller":"traceutil/trace.go:171","msg":"trace[535892458] transaction","detail":"{read_only:false; response_revision:7741; number_of_response:1; }","duration":"440.447487ms","start":"2026-06-04T12:13:31.441875Z","end":"2026-06-04T12:13:31.882322Z","steps":["trace[535892458] 'process raft request' (duration: 440.18952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:31.882592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:31.441855Z","time spent":"440.573821ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:32.877906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879701333,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-04T12:13:32.931695Z","caller":"traceutil/trace.go:171","msg":"trace[790781891] linearizableReadLoop","detail":"{readStateIndex:8026; appliedIndex:8025; }","duration":"602.256314ms","start":"2026-06-04T12:13:32.329419Z","end":"2026-06-04T12:13:32.931675Z","steps":["trace[790781891] 'read index received' (duration: 602.11915ms)","trace[790781891] 'applied index is now lower than readState.Index' (duration: 136.324µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:13:32.931912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.473569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-04T12:13:32.931946Z","caller":"traceutil/trace.go:171","msg":"trace[1513988693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7745; }","duration":"602.568982ms","start":"2026-06-04T12:13:32.329369Z","end":"2026-06-04T12:13:32.931938Z","steps":["trace[1513988693] 'agreement among raft nodes before linearized reading' (duration: 602.403357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:32.931966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.329349Z","time spent":"602.610453ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-04T12:13:32.93212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.986333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-04T12:13:32.932226Z","caller":"traceutil/trace.go:171","msg":"trace[1173787448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7745; }","duration":"244.081226ms","start":"2026-06-04T12:13:32.688092Z","end":"2026-06-04T12:13:32.932173Z","steps":["trace[1173787448] 'agreement among raft nodes before linearized reading' (duration: 243.816729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:32.9323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.980291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-04T12:13:32.932429Z","caller":"traceutil/trace.go:171","msg":"trace[992698068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7745; }","duration":"245.166076ms","start":"2026-06-04T12:13:32.687235Z","end":"2026-06-04T12:13:32.932401Z","steps":["trace[992698068] 'agreement among raft nodes before linearized reading' (duration: 244.731464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:32.932291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.172507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:13:32.932415Z","caller":"traceutil/trace.go:171","msg":"trace[807445307] transaction","detail":"{read_only:false; response_revision:7745; number_of_response:1; }","duration":"633.092807ms","start":"2026-06-04T12:13:32.298694Z","end":"2026-06-04T12:13:32.931786Z","steps":["trace[807445307] 'process raft request' (duration: 632.84344ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:32.93254Z","caller":"traceutil/trace.go:171","msg":"trace[790124202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7745; }","duration":"256.421334ms","start":"2026-06-04T12:13:32.676103Z","end":"2026-06-04T12:13:32.932525Z","steps":["trace[790124202] 'agreement among raft nodes before linearized reading' (duration: 255.708534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:32.932963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.298674Z","time spent":"633.947781ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:33.510004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879701337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:13:34.010448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879701337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:13:34.510956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879701337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:13:34.541082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.225717Z","time spent":"1.315355497s","remote":"127.0.0.1:33746","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-04T12:13:34.541225Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.604253s","expected-duration":"1s"} {"level":"info","ts":"2026-06-04T12:13:34.542216Z","caller":"traceutil/trace.go:171","msg":"trace[1943885684] transaction","detail":"{read_only:false; response_revision:7746; number_of_response:1; }","duration":"1.606125231s","start":"2026-06-04T12:13:32.936062Z","end":"2026-06-04T12:13:34.542188Z","steps":["trace[1943885684] 'process raft request' (duration: 1.605661438s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.542329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.936022Z","time spent":"1.606235384s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:13:34.545788Z","caller":"traceutil/trace.go:171","msg":"trace[1464696504] transaction","detail":"{read_only:false; response_revision:7747; number_of_response:1; }","duration":"1.607556541s","start":"2026-06-04T12:13:32.938199Z","end":"2026-06-04T12:13:34.545756Z","steps":["trace[1464696504] 'process raft request' (duration: 1.607349705s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546182Z","caller":"traceutil/trace.go:171","msg":"trace[154580829] transaction","detail":"{read_only:false; response_revision:7748; number_of_response:1; }","duration":"1.607660603s","start":"2026-06-04T12:13:32.938489Z","end":"2026-06-04T12:13:34.546149Z","steps":["trace[154580829] 'process raft request' (duration: 1.607225532s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546276Z","caller":"traceutil/trace.go:171","msg":"trace[100054488] transaction","detail":"{read_only:false; response_revision:7751; number_of_response:1; }","duration":"658.491531ms","start":"2026-06-04T12:13:33.887768Z","end":"2026-06-04T12:13:34.54626Z","steps":["trace[100054488] 'process raft request' (duration: 658.341857ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546262Z","caller":"traceutil/trace.go:171","msg":"trace[1815197494] linearizableReadLoop","detail":"{readStateIndex:8035; appliedIndex:8026; }","duration":"1.537066012s","start":"2026-06-04T12:13:33.009178Z","end":"2026-06-04T12:13:34.546244Z","steps":["trace[1815197494] 'read index received' (duration: 1.532486307s)","trace[1815197494] 'applied index is now lower than readState.Index' (duration: 4.578955ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:13:34.546357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.88774Z","time spent":"658.575804ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:13:34.546393Z","caller":"traceutil/trace.go:171","msg":"trace[842211674] transaction","detail":"{read_only:false; response_revision:7752; number_of_response:1; }","duration":"655.52833ms","start":"2026-06-04T12:13:33.890856Z","end":"2026-06-04T12:13:34.546384Z","steps":["trace[842211674] 'process raft request' (duration: 655.288033ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546474Z","caller":"traceutil/trace.go:171","msg":"trace[1564346818] transaction","detail":"{read_only:false; response_revision:7749; number_of_response:1; }","duration":"1.607951361s","start":"2026-06-04T12:13:32.938514Z","end":"2026-06-04T12:13:34.546465Z","steps":["trace[1564346818] 'process raft request' (duration: 1.607438947s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.546544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.938495Z","time spent":"1.608007253s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:13:34.546554Z","caller":"traceutil/trace.go:171","msg":"trace[408087469] transaction","detail":"{read_only:false; response_revision:7750; number_of_response:1; }","duration":"843.309207ms","start":"2026-06-04T12:13:33.703224Z","end":"2026-06-04T12:13:34.546534Z","steps":["trace[408087469] 'process raft request' (duration: 842.815733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.546566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.890842Z","time spent":"655.642054ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:34.546627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.703195Z","time spent":"843.396498ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:34.546634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.938186Z","time spent":"1.607681464s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:34.546656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537479494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:13:34.546695Z","caller":"traceutil/trace.go:171","msg":"trace[110191478] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:7754; }","duration":"1.537555455s","start":"2026-06-04T12:13:33.00913Z","end":"2026-06-04T12:13:34.546685Z","steps":["trace[110191478] 'agreement among raft nodes before linearized reading' (duration: 1.537482323s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.546732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.009109Z","time spent":"1.537614857s","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":5,"response size":30,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:13:34.546758Z","caller":"traceutil/trace.go:171","msg":"trace[1276221957] transaction","detail":"{read_only:false; response_revision:7753; number_of_response:1; }","duration":"655.775667ms","start":"2026-06-04T12:13:33.890964Z","end":"2026-06-04T12:13:34.54674Z","steps":["trace[1276221957] 'process raft request' (duration: 655.218131ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546798Z","caller":"traceutil/trace.go:171","msg":"trace[511954012] transaction","detail":"{read_only:false; response_revision:7754; number_of_response:1; }","duration":"655.566331ms","start":"2026-06-04T12:13:33.891195Z","end":"2026-06-04T12:13:34.546762Z","steps":["trace[511954012] 'process raft request' (duration: 655.018166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.546809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.270135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T12:13:34.546837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.890937Z","time spent":"655.861839ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:34.546893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097107308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:13:34.546887Z","caller":"traceutil/trace.go:171","msg":"trace[443095424] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:7754; }","duration":"394.354627ms","start":"2026-06-04T12:13:34.15251Z","end":"2026-06-04T12:13:34.546865Z","steps":["trace[443095424] 'agreement among raft nodes before linearized reading' (duration: 394.184692ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546924Z","caller":"traceutil/trace.go:171","msg":"trace[480153514] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:7754; }","duration":"1.097142299s","start":"2026-06-04T12:13:33.44977Z","end":"2026-06-04T12:13:34.546913Z","steps":["trace[480153514] 'agreement among raft nodes before linearized reading' (duration: 1.097091218s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.546922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097369636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T12:13:34.54684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395608833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cxc5j\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-06-04T12:13:34.546939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.938483Z","time spent":"1.607754046s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:34.546956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.449707Z","time spent":"1.097241362s","remote":"127.0.0.1:47622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:13:34.54695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.891165Z","time spent":"655.689795ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:13:34.546971Z","caller":"traceutil/trace.go:171","msg":"trace[439120637] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7754; }","duration":"1.097465298s","start":"2026-06-04T12:13:33.449493Z","end":"2026-06-04T12:13:34.546958Z","steps":["trace[439120637] 'agreement among raft nodes before linearized reading' (duration: 1.097382326s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.546939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:34.152434Z","time spent":"394.490281ms","remote":"127.0.0.1:44796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:13:34.546973Z","caller":"traceutil/trace.go:171","msg":"trace[2032080134] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cxc5j; range_end:; response_count:1; response_revision:7754; }","duration":"1.395785088s","start":"2026-06-04T12:13:33.151178Z","end":"2026-06-04T12:13:34.546963Z","steps":["trace[2032080134] 'agreement among raft nodes before linearized reading' (duration: 1.3955047s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.547006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.449472Z","time spent":"1.097524219s","remote":"127.0.0.1:40484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:13:34.547011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.151158Z","time spent":"1.39584405s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-cxc5j\" "} {"level":"warn","ts":"2026-06-04T12:13:34.547058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195633533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-04T12:13:34.547085Z","caller":"traceutil/trace.go:171","msg":"trace[160506536] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7754; }","duration":"1.195702926s","start":"2026-06-04T12:13:33.351374Z","end":"2026-06-04T12:13:34.547077Z","steps":["trace[160506536] 'agreement among raft nodes before linearized reading' (duration: 1.195612253s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.54711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.351348Z","time spent":"1.195756556s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-04T12:14:24.058156Z","caller":"traceutil/trace.go:171","msg":"trace[444686094] linearizableReadLoop","detail":"{readStateIndex:8354; appliedIndex:8353; }","duration":"173.442094ms","start":"2026-06-04T12:14:23.884694Z","end":"2026-06-04T12:14:24.058136Z","steps":["trace[444686094] 'read index received' (duration: 173.271229ms)","trace[444686094] 'applied index is now lower than readState.Index' (duration: 169.775µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:24.058299Z","caller":"traceutil/trace.go:171","msg":"trace[134391341] transaction","detail":"{read_only:false; response_revision:8063; number_of_response:1; }","duration":"708.931271ms","start":"2026-06-04T12:14:23.349355Z","end":"2026-06-04T12:14:24.058286Z","steps":["trace[134391341] 'process raft request' (duration: 708.630863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:24.058408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:23.349338Z","time spent":"708.990422ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:14:24.058676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.948928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:14:24.058732Z","caller":"traceutil/trace.go:171","msg":"trace[1113051691] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8063; }","duration":"174.077392ms","start":"2026-06-04T12:14:23.884642Z","end":"2026-06-04T12:14:24.058719Z","steps":["trace[1113051691] 'agreement among raft nodes before linearized reading' (duration: 173.974159ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:31.658746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6071} {"level":"info","ts":"2026-06-04T12:14:31.761217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6071,"took":"99.218054ms","hash":1046501246,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":21762048,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-04T12:14:31.761306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1046501246,"revision":6071,"compact-revision":2974} {"level":"info","ts":"2026-06-04T12:14:54.427595Z","caller":"traceutil/trace.go:171","msg":"trace[508633180] transaction","detail":"{read_only:false; response_revision:8401; number_of_response:1; }","duration":"346.858698ms","start":"2026-06-04T12:14:54.080707Z","end":"2026-06-04T12:14:54.427566Z","steps":["trace[508633180] 'process raft request' (duration: 346.339034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:54.42788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:54.080682Z","time spent":"347.010942ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:15:00.891937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.891459ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-04T12:15:00.892031Z","caller":"traceutil/trace.go:171","msg":"trace[504175450] linearizableReadLoop","detail":"{readStateIndex:8735; appliedIndex:8734; }","duration":"523.89848ms","start":"2026-06-04T12:15:00.368118Z","end":"2026-06-04T12:15:00.892016Z","steps":["trace[504175450] 'read index received' (duration: 199.850157ms)","trace[504175450] 'applied index is now lower than readState.Index' (duration: 324.047223ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:15:00.892104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.986282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:00.892128Z","caller":"traceutil/trace.go:171","msg":"trace[713492484] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8432; }","duration":"524.082405ms","start":"2026-06-04T12:15:00.368035Z","end":"2026-06-04T12:15:00.892117Z","steps":["trace[713492484] 'agreement among raft nodes before linearized reading' (duration: 524.016383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.892156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:15:00.368001Z","time spent":"524.147557ms","remote":"127.0.0.1:51060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:15:05.060703Z","caller":"traceutil/trace.go:171","msg":"trace[53758481] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"100.338384ms","start":"2026-06-04T12:15:04.96034Z","end":"2026-06-04T12:15:05.060678Z","steps":["trace[53758481] 'process raft request' (duration: 100.251892ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:05.060769Z","caller":"traceutil/trace.go:171","msg":"trace[2143167427] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"100.55795ms","start":"2026-06-04T12:15:04.96016Z","end":"2026-06-04T12:15:05.060718Z","steps":["trace[2143167427] 'process raft request' (duration: 67.865046ms)","trace[2143167427] 'compare' (duration: 32.437667ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:06.026826Z","caller":"traceutil/trace.go:171","msg":"trace[1265196077] linearizableReadLoop","detail":"{readStateIndex:8770; appliedIndex:8769; }","duration":"171.09015ms","start":"2026-06-04T12:15:05.855702Z","end":"2026-06-04T12:15:06.026792Z","steps":["trace[1265196077] 'read index received' (duration: 170.976327ms)","trace[1265196077] 'applied index is now lower than readState.Index' (duration: 112.233µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:06.026933Z","caller":"traceutil/trace.go:171","msg":"trace[1920803373] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"173.30061ms","start":"2026-06-04T12:15:05.853598Z","end":"2026-06-04T12:15:06.026898Z","steps":["trace[1920803373] 'process raft request' (duration: 173.004962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:06.027078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.342917ms","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:5"} {"level":"info","ts":"2026-06-04T12:15:06.027143Z","caller":"traceutil/trace.go:171","msg":"trace[1674344850] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8466; }","duration":"171.46486ms","start":"2026-06-04T12:15:05.855661Z","end":"2026-06-04T12:15:06.027126Z","steps":["trace[1674344850] 'agreement among raft nodes before linearized reading' (duration: 171.336097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:06.027485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.666506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:06.027622Z","caller":"traceutil/trace.go:171","msg":"trace[1024825373] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8466; }","duration":"171.825121ms","start":"2026-06-04T12:15:05.855747Z","end":"2026-06-04T12:15:06.027572Z","steps":["trace[1024825373] 'agreement among raft nodes before linearized reading' (duration: 171.596134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:06.027677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.461136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:06.027732Z","caller":"traceutil/trace.go:171","msg":"trace[328542292] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8466; }","duration":"135.569198ms","start":"2026-06-04T12:15:05.892147Z","end":"2026-06-04T12:15:06.027716Z","steps":["trace[328542292] 'agreement among raft nodes before linearized reading' (duration: 135.484186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:06.027989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.600782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:06.028046Z","caller":"traceutil/trace.go:171","msg":"trace[811291215] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8466; }","duration":"100.693014ms","start":"2026-06-04T12:15:05.92734Z","end":"2026-06-04T12:15:06.028033Z","steps":["trace[811291215] 'agreement among raft nodes before linearized reading' (duration: 100.608112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:06.028058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.222375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:06.028183Z","caller":"traceutil/trace.go:171","msg":"trace[605629398] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8466; }","duration":"155.38222ms","start":"2026-06-04T12:15:05.872772Z","end":"2026-06-04T12:15:06.028154Z","steps":["trace[605629398] 'agreement among raft nodes before linearized reading' (duration: 155.185055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:06.028291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.748128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:15:06.028421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.761283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:15:06.028409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.272318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:06.028465Z","caller":"traceutil/trace.go:171","msg":"trace[1523996469] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8466; }","duration":"154.818835ms","start":"2026-06-04T12:15:05.873637Z","end":"2026-06-04T12:15:06.028456Z","steps":["trace[1523996469] 'agreement among raft nodes before linearized reading' (duration: 154.750803ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:06.028512Z","caller":"traceutil/trace.go:171","msg":"trace[101012853] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8466; }","duration":"136.438423ms","start":"2026-06-04T12:15:05.89205Z","end":"2026-06-04T12:15:06.028489Z","steps":["trace[101012853] 'agreement among raft nodes before linearized reading' (duration: 136.221637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:06.02847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.408236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:15:06.028101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.272887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:06.028432Z","caller":"traceutil/trace.go:171","msg":"trace[958515449] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8466; }","duration":"171.878862ms","start":"2026-06-04T12:15:05.856505Z","end":"2026-06-04T12:15:06.028384Z","steps":["trace[958515449] 'agreement among raft nodes before linearized reading' (duration: 171.645305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:06.028707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.663763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:15:06.028291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.010383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:06.028806Z","caller":"traceutil/trace.go:171","msg":"trace[1591815493] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8466; }","duration":"119.764706ms","start":"2026-06-04T12:15:05.909017Z","end":"2026-06-04T12:15:06.028782Z","steps":["trace[1591815493] 'agreement among raft nodes before linearized reading' (duration: 119.651893ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:06.028882Z","caller":"traceutil/trace.go:171","msg":"trace[1852195932] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8466; }","duration":"101.597439ms","start":"2026-06-04T12:15:05.927249Z","end":"2026-06-04T12:15:06.028847Z","steps":["trace[1852195932] 'agreement among raft nodes before linearized reading' (duration: 100.995943ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:06.028609Z","caller":"traceutil/trace.go:171","msg":"trace[1917534923] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8466; }","duration":"155.840163ms","start":"2026-06-04T12:15:05.872758Z","end":"2026-06-04T12:15:06.028598Z","steps":["trace[1917534923] 'agreement among raft nodes before linearized reading' (duration: 155.247876ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:06.028605Z","caller":"traceutil/trace.go:171","msg":"trace[607423308] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8466; }","duration":"119.598931ms","start":"2026-06-04T12:15:05.908975Z","end":"2026-06-04T12:15:06.028574Z","steps":["trace[607423308] 'agreement among raft nodes before linearized reading' (duration: 119.331534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:06.0286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.448622ms","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:5"} {"level":"warn","ts":"2026-06-04T12:15:06.028675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.19834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:06.029108Z","caller":"traceutil/trace.go:171","msg":"trace[1907091615] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8466; }","duration":"119.643812ms","start":"2026-06-04T12:15:05.90945Z","end":"2026-06-04T12:15:06.029094Z","steps":["trace[1907091615] 'agreement among raft nodes before linearized reading' (duration: 119.19147ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:06.029106Z","caller":"traceutil/trace.go:171","msg":"trace[499600246] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8466; }","duration":"136.921376ms","start":"2026-06-04T12:15:05.892115Z","end":"2026-06-04T12:15:06.029036Z","steps":["trace[499600246] 'agreement among raft nodes before linearized reading' (duration: 136.443503ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.258282Z","caller":"traceutil/trace.go:171","msg":"trace[890635830] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"194.677679ms","start":"2026-06-04T12:15:33.063577Z","end":"2026-06-04T12:15:33.258254Z","steps":["trace[890635830] 'process raft request' (duration: 194.510645ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.282535Z","caller":"traceutil/trace.go:171","msg":"trace[1042176646] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"163.64827ms","start":"2026-06-04T12:15:33.118869Z","end":"2026-06-04T12:15:33.282518Z","steps":["trace[1042176646] 'process raft request' (duration: 163.006902ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.28281Z","caller":"traceutil/trace.go:171","msg":"trace[1566077652] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"114.937696ms","start":"2026-06-04T12:15:33.167842Z","end":"2026-06-04T12:15:33.28278Z","steps":["trace[1566077652] 'process raft request' (duration: 114.178345ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.282992Z","caller":"traceutil/trace.go:171","msg":"trace[503438397] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"114.921016ms","start":"2026-06-04T12:15:33.168061Z","end":"2026-06-04T12:15:33.282982Z","steps":["trace[503438397] 'process raft request' (duration: 113.98963ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.283192Z","caller":"traceutil/trace.go:171","msg":"trace[1923701098] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"111.576934ms","start":"2026-06-04T12:15:33.171607Z","end":"2026-06-04T12:15:33.283184Z","steps":["trace[1923701098] 'process raft request' (duration: 110.494065ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.470655Z","caller":"traceutil/trace.go:171","msg":"trace[1734094343] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"174.860457ms","start":"2026-06-04T12:15:33.295778Z","end":"2026-06-04T12:15:33.470638Z","steps":["trace[1734094343] 'process raft request' (duration: 174.764825ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.471921Z","caller":"traceutil/trace.go:171","msg":"trace[1697587749] linearizableReadLoop","detail":"{readStateIndex:8980; appliedIndex:8976; }","duration":"145.805812ms","start":"2026-06-04T12:15:33.326083Z","end":"2026-06-04T12:15:33.471888Z","steps":["trace[1697587749] 'read index received' (duration: 144.883956ms)","trace[1697587749] 'applied index is now lower than readState.Index' (duration: 920.425µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:33.472157Z","caller":"traceutil/trace.go:171","msg":"trace[826756608] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"174.216719ms","start":"2026-06-04T12:15:33.297918Z","end":"2026-06-04T12:15:33.472135Z","steps":["trace[826756608] 'process raft request' (duration: 173.815308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:33.47233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.219542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cxc5j\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-04T12:15:33.472388Z","caller":"traceutil/trace.go:171","msg":"trace[2043815712] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cxc5j; range_end:; response_count:1; response_revision:8668; }","duration":"146.340586ms","start":"2026-06-04T12:15:33.326032Z","end":"2026-06-04T12:15:33.472372Z","steps":["trace[2043815712] 'agreement among raft nodes before linearized reading' (duration: 146.000116ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.472447Z","caller":"traceutil/trace.go:171","msg":"trace[1050803513] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"174.681772ms","start":"2026-06-04T12:15:33.29775Z","end":"2026-06-04T12:15:33.472431Z","steps":["trace[1050803513] 'process raft request' (duration: 173.802968ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.472799Z","caller":"traceutil/trace.go:171","msg":"trace[630939937] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"174.825396ms","start":"2026-06-04T12:15:33.297959Z","end":"2026-06-04T12:15:33.472784Z","steps":["trace[630939937] 'process raft request' (duration: 173.826729ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:33.472868Z","caller":"traceutil/trace.go:171","msg":"trace[176648274] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"173.607413ms","start":"2026-06-04T12:15:33.299249Z","end":"2026-06-04T12:15:33.472857Z","steps":["trace[176648274] 'process raft request' (duration: 172.590785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:37.834131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.437075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:15:37.834247Z","caller":"traceutil/trace.go:171","msg":"trace[124582446] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"335.822853ms","start":"2026-06-04T12:15:37.498411Z","end":"2026-06-04T12:15:37.834233Z","steps":["trace[124582446] 'process raft request' (duration: 199.199483ms)","trace[124582446] 'compare' (duration: 136.332922ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:15:37.834287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:15:37.498389Z","time spent":"335.881425ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:15:37.834485Z","caller":"traceutil/trace.go:171","msg":"trace[604688997] linearizableReadLoop","detail":"{readStateIndex:9004; appliedIndex:8999; }","duration":"333.740266ms","start":"2026-06-04T12:15:37.500736Z","end":"2026-06-04T12:15:37.834476Z","steps":["trace[604688997] 'read index received' (duration: 70.810618ms)","trace[604688997] 'applied index is now lower than readState.Index' (duration: 262.928498ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:15:37.834607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.868969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cxc5j\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-04T12:15:37.834558Z","caller":"traceutil/trace.go:171","msg":"trace[813563820] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"336.06271ms","start":"2026-06-04T12:15:37.498455Z","end":"2026-06-04T12:15:37.834517Z","steps":["trace[813563820] 'process raft request' (duration: 335.745301ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:37.834629Z","caller":"traceutil/trace.go:171","msg":"trace[170986994] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cxc5j; range_end:; response_count:1; response_revision:8691; }","duration":"333.906731ms","start":"2026-06-04T12:15:37.500713Z","end":"2026-06-04T12:15:37.83462Z","steps":["trace[170986994] 'agreement among raft nodes before linearized reading' (duration: 333.789358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:37.834646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:15:37.500704Z","time spent":"333.938242ms","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-cxc5j\" "} {"level":"info","ts":"2026-06-04T12:15:37.834744Z","caller":"traceutil/trace.go:171","msg":"trace[1818518127] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"335.114684ms","start":"2026-06-04T12:15:37.499608Z","end":"2026-06-04T12:15:37.834722Z","steps":["trace[1818518127] 'process raft request' (duration: 334.789665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:37.834773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:15:37.498442Z","time spent":"336.207474ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:15:37.834838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:15:37.499594Z","time spent":"335.199587ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:15:37.83477Z","caller":"traceutil/trace.go:171","msg":"trace[963394372] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"334.424645ms","start":"2026-06-04T12:15:37.500339Z","end":"2026-06-04T12:15:37.834764Z","steps":["trace[963394372] 'process raft request' (duration: 334.099686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:37.834974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:15:37.500326Z","time spent":"334.627121ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:15:45.924679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.755081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:15:45.924883Z","caller":"traceutil/trace.go:171","msg":"trace[1443805163] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8770; }","duration":"238.458608ms","start":"2026-06-04T12:15:45.686397Z","end":"2026-06-04T12:15:45.924856Z","steps":["trace[1443805163] 'process raft request' (duration: 119.336307ms)","trace[1443805163] 'compare' (duration: 118.446713ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:46.080492Z","caller":"traceutil/trace.go:171","msg":"trace[1736267884] transaction","detail":"{read_only:false; response_revision:8771; number_of_response:1; }","duration":"151.000864ms","start":"2026-06-04T12:15:45.929453Z","end":"2026-06-04T12:15:46.080454Z","steps":["trace[1736267884] 'process raft request' (duration: 123.527721ms)","trace[1736267884] 'compare' (duration: 27.01345ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:46.089955Z","caller":"traceutil/trace.go:171","msg":"trace[1254637484] linearizableReadLoop","detail":"{readStateIndex:9092; appliedIndex:9088; }","duration":"158.198231ms","start":"2026-06-04T12:15:45.931724Z","end":"2026-06-04T12:15:46.089922Z","steps":["trace[1254637484] 'read index received' (duration: 121.542907ms)","trace[1254637484] 'applied index is now lower than readState.Index' (duration: 36.654204ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:46.090334Z","caller":"traceutil/trace.go:171","msg":"trace[722052255] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"159.664361ms","start":"2026-06-04T12:15:45.930654Z","end":"2026-06-04T12:15:46.090318Z","steps":["trace[722052255] 'process raft request' (duration: 158.90316ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:46.090798Z","caller":"traceutil/trace.go:171","msg":"trace[163169234] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"159.976809ms","start":"2026-06-04T12:15:45.930807Z","end":"2026-06-04T12:15:46.090784Z","steps":["trace[163169234] 'process raft request' (duration: 158.935411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:46.091003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.248549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228796"} {"level":"info","ts":"2026-06-04T12:15:46.091263Z","caller":"traceutil/trace.go:171","msg":"trace[1897651461] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8775; }","duration":"159.512486ms","start":"2026-06-04T12:15:45.931709Z","end":"2026-06-04T12:15:46.091221Z","steps":["trace[1897651461] 'agreement among raft nodes before linearized reading' (duration: 158.864159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:46.092125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.37519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-5bqkf\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-06-04T12:15:46.092221Z","caller":"traceutil/trace.go:171","msg":"trace[98517976] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-5bqkf; range_end:; response_count:1; response_revision:8775; }","duration":"160.508004ms","start":"2026-06-04T12:15:45.931701Z","end":"2026-06-04T12:15:46.092209Z","steps":["trace[98517976] 'agreement among raft nodes before linearized reading' (duration: 158.397887ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:46.092504Z","caller":"traceutil/trace.go:171","msg":"trace[2082572222] transaction","detail":"{read_only:false; response_revision:8774; number_of_response:1; }","duration":"160.865734ms","start":"2026-06-04T12:15:45.931618Z","end":"2026-06-04T12:15:46.092483Z","steps":["trace[2082572222] 'process raft request' (duration: 158.17346ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:16:05.637236Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243c.wal"} {"level":"info","ts":"2026-06-04T12:17:06.282255Z","caller":"traceutil/trace.go:171","msg":"trace[656842454] transaction","detail":"{read_only:false; response_revision:9542; number_of_response:1; }","duration":"101.706164ms","start":"2026-06-04T12:17:06.180518Z","end":"2026-06-04T12:17:06.282224Z","steps":["trace[656842454] 'process raft request' (duration: 101.469088ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:17:06.607097Z","caller":"traceutil/trace.go:171","msg":"trace[702132364] transaction","detail":"{read_only:false; response_revision:9543; number_of_response:1; }","duration":"235.287722ms","start":"2026-06-04T12:17:06.371761Z","end":"2026-06-04T12:17:06.607048Z","steps":["trace[702132364] 'process raft request' (duration: 212.040895ms)","trace[702132364] 'compare' (duration: 22.972269ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:17:19.742251Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:17:19.747937Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-04T12:17:19.748031Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-04T12:19:31.666031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8141} {"level":"info","ts":"2026-06-04T12:19:31.729592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8141,"took":"60.657961ms","hash":1342154587,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":28196864,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-04T12:19:31.729658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1342154587,"revision":8141,"compact-revision":6071} {"level":"warn","ts":"2026-06-04T12:20:43.178232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879709735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:20:43.679176Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879709735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:20:44.180002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879709735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:20:44.687804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010522358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-04T12:20:44.687884Z","caller":"traceutil/trace.go:171","msg":"trace[1752263525] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.010656462s","start":"2026-06-04T12:20:42.677211Z","end":"2026-06-04T12:20:44.687868Z","steps":["trace[1752263525] 'agreement among raft nodes before linearized reading' (duration: 2.010542039s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:44.68792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.677194Z","time spent":"2.010719254s","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T12:20:44.687965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879709735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:20:45.033067Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.447363643s","expected-duration":"1s"} {"level":"info","ts":"2026-06-04T12:20:45.033899Z","caller":"traceutil/trace.go:171","msg":"trace[1798250003] transaction","detail":"{read_only:false; response_revision:10868; number_of_response:1; }","duration":"2.448325799s","start":"2026-06-04T12:20:42.585548Z","end":"2026-06-04T12:20:45.033874Z","steps":["trace[1798250003] 'process raft request' (duration: 2.448166164s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.034068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.585523Z","time spent":"2.448450303s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:20:45.034229Z","caller":"traceutil/trace.go:171","msg":"trace[825586871] transaction","detail":"{read_only:false; response_revision:10869; number_of_response:1; }","duration":"2.447162408s","start":"2026-06-04T12:20:42.587032Z","end":"2026-06-04T12:20:45.034194Z","steps":["trace[825586871] 'process raft request' (duration: 2.447004933s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:20:45.034435Z","caller":"traceutil/trace.go:171","msg":"trace[728279416] transaction","detail":"{read_only:false; response_revision:10871; number_of_response:1; }","duration":"2.441385489s","start":"2026-06-04T12:20:42.59304Z","end":"2026-06-04T12:20:45.034425Z","steps":["trace[728279416] 'process raft request' (duration: 2.441264126s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:20:45.034429Z","caller":"traceutil/trace.go:171","msg":"trace[100727910] transaction","detail":"{read_only:false; response_revision:10870; number_of_response:1; }","duration":"2.444846413s","start":"2026-06-04T12:20:42.58956Z","end":"2026-06-04T12:20:45.034406Z","steps":["trace[100727910] 'process raft request' (duration: 2.444583746s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.034492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.593031Z","time spent":"2.44143555s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:20:45.034438Z","caller":"traceutil/trace.go:171","msg":"trace[576735773] linearizableReadLoop","detail":"{readStateIndex:11260; appliedIndex:11256; }","duration":"2.357179462s","start":"2026-06-04T12:20:42.677247Z","end":"2026-06-04T12:20:45.034426Z","steps":["trace[576735773] 'read index received' (duration: 2.356419571s)","trace[576735773] 'applied index is now lower than readState.Index' (duration: 758.961µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:20:45.034551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.589544Z","time spent":"2.444940667s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:20:45.034653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.332908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:20:45.034665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.806727937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-04T12:20:45.03468Z","caller":"traceutil/trace.go:171","msg":"trace[1336090910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10872; }","duration":"345.369729ms","start":"2026-06-04T12:20:44.689302Z","end":"2026-06-04T12:20:45.034672Z","steps":["trace[1336090910] 'agreement among raft nodes before linearized reading' (duration: 345.324878ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:20:45.034684Z","caller":"traceutil/trace.go:171","msg":"trace[190967982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10872; }","duration":"1.806800119s","start":"2026-06-04T12:20:43.227878Z","end":"2026-06-04T12:20:45.034678Z","steps":["trace[190967982] 'agreement among raft nodes before linearized reading' (duration: 1.806704426s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.034695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:44.689295Z","time spent":"345.396749ms","remote":"127.0.0.1:44696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T12:20:45.034705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.227852Z","time spent":"1.8068456s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-04T12:20:45.034924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158830865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-04T12:20:45.034957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159610906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-04T12:20:45.034978Z","caller":"traceutil/trace.go:171","msg":"trace[1372638679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10872; }","duration":"1.159669758s","start":"2026-06-04T12:20:43.8753Z","end":"2026-06-04T12:20:45.03497Z","steps":["trace[1372638679] 'agreement among raft nodes before linearized reading' (duration: 1.159609876s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.034998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.718348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T12:20:45.034997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.875276Z","time spent":"1.15971791s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-04T12:20:45.035025Z","caller":"traceutil/trace.go:171","msg":"trace[1242886925] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:10872; }","duration":"345.770239ms","start":"2026-06-04T12:20:44.689247Z","end":"2026-06-04T12:20:45.035017Z","steps":["trace[1242886925] 'agreement among raft nodes before linearized reading' (duration: 345.722578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.03505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:44.689232Z","time spent":"345.810681ms","remote":"127.0.0.1:40498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:20:45.035106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16773675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-04T12:20:45.035125Z","caller":"traceutil/trace.go:171","msg":"trace[1770389282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10872; }","duration":"1.167794031s","start":"2026-06-04T12:20:43.867326Z","end":"2026-06-04T12:20:45.03512Z","steps":["trace[1770389282] 'agreement among raft nodes before linearized reading' (duration: 1.16774357s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.035138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.867307Z","time spent":"1.167828261s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-04T12:20:45.034956Z","caller":"traceutil/trace.go:171","msg":"trace[642146413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10872; }","duration":"1.158920767s","start":"2026-06-04T12:20:43.876028Z","end":"2026-06-04T12:20:45.034949Z","steps":["trace[642146413] 'agreement among raft nodes before linearized reading' (duration: 1.158840315s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.035193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.876003Z","time spent":"1.159185075s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:20:45.035215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020541258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-04T12:20:45.035237Z","caller":"traceutil/trace.go:171","msg":"trace[1032840558] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10872; }","duration":"1.02059861s","start":"2026-06-04T12:20:44.014632Z","end":"2026-06-04T12:20:45.035231Z","steps":["trace[1032840558] 'agreement among raft nodes before linearized reading' (duration: 1.020526438s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.035253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:44.014614Z","time spent":"1.020634781s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-04T12:20:45.035464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.587005Z","time spent":"2.44725898s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:24:31.681153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10510} {"level":"info","ts":"2026-06-04T12:24:31.761282Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10510,"took":"76.982903ms","hash":1027829856,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":25927680,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-04T12:24:31.761336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1027829856,"revision":10510,"compact-revision":8141} {"level":"warn","ts":"2026-06-04T12:29:15.403267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.376306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:29:15.403386Z","caller":"traceutil/trace.go:171","msg":"trace[2027520932] transaction","detail":"{read_only:false; response_revision:14570; number_of_response:1; }","duration":"243.814933ms","start":"2026-06-04T12:29:15.159554Z","end":"2026-06-04T12:29:15.403369Z","steps":["trace[2027520932] 'process raft request' (duration: 130.236952ms)","trace[2027520932] 'compare' (duration: 113.096508ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:29:15.404782Z","caller":"traceutil/trace.go:171","msg":"trace[744612216] linearizableReadLoop","detail":"{readStateIndex:15082; appliedIndex:15079; }","duration":"164.266619ms","start":"2026-06-04T12:29:15.240479Z","end":"2026-06-04T12:29:15.404746Z","steps":["trace[744612216] 'read index received' (duration: 49.351511ms)","trace[744612216] 'applied index is now lower than readState.Index' (duration: 114.913748ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:29:15.40516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.62497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:29:15.405204Z","caller":"traceutil/trace.go:171","msg":"trace[1847240364] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:14572; }","duration":"164.774834ms","start":"2026-06-04T12:29:15.240418Z","end":"2026-06-04T12:29:15.405193Z","steps":["trace[1847240364] 'agreement among raft nodes before linearized reading' (duration: 164.455545ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:15.40556Z","caller":"traceutil/trace.go:171","msg":"trace[613407709] transaction","detail":"{read_only:false; response_revision:14572; number_of_response:1; }","duration":"215.602825ms","start":"2026-06-04T12:29:15.189931Z","end":"2026-06-04T12:29:15.405534Z","steps":["trace[613407709] 'process raft request' (duration: 214.754322ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:15.405763Z","caller":"traceutil/trace.go:171","msg":"trace[1380320435] transaction","detail":"{read_only:false; response_revision:14571; number_of_response:1; }","duration":"228.885351ms","start":"2026-06-04T12:29:15.176867Z","end":"2026-06-04T12:29:15.405752Z","steps":["trace[1380320435] 'process raft request' (duration: 227.658077ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:24.142822Z","caller":"traceutil/trace.go:171","msg":"trace[1675462143] transaction","detail":"{read_only:false; response_revision:14615; number_of_response:1; }","duration":"109.1617ms","start":"2026-06-04T12:29:24.033626Z","end":"2026-06-04T12:29:24.142788Z","steps":["trace[1675462143] 'process raft request' (duration: 108.970735ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:24.143784Z","caller":"traceutil/trace.go:171","msg":"trace[904762020] transaction","detail":"{read_only:false; response_revision:14616; number_of_response:1; }","duration":"109.782218ms","start":"2026-06-04T12:29:24.033963Z","end":"2026-06-04T12:29:24.143745Z","steps":["trace[904762020] 'process raft request' (duration: 109.52448ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:24.14394Z","caller":"traceutil/trace.go:171","msg":"trace[1889128103] linearizableReadLoop","detail":"{readStateIndex:15128; appliedIndex:15125; }","duration":"105.808408ms","start":"2026-06-04T12:29:24.038086Z","end":"2026-06-04T12:29:24.143894Z","steps":["trace[1889128103] 'read index received' (duration: 104.488331ms)","trace[1889128103] 'applied index is now lower than readState.Index' (duration: 1.314787ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:29:24.144012Z","caller":"traceutil/trace.go:171","msg":"trace[979567221] transaction","detail":"{read_only:false; response_revision:14617; number_of_response:1; }","duration":"109.719855ms","start":"2026-06-04T12:29:24.034274Z","end":"2026-06-04T12:29:24.143993Z","steps":["trace[979567221] 'process raft request' (duration: 109.398897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:29:24.144314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.136207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-06-04T12:29:24.144396Z","caller":"traceutil/trace.go:171","msg":"trace[666073217] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:14617; }","duration":"106.335613ms","start":"2026-06-04T12:29:24.038034Z","end":"2026-06-04T12:29:24.14437Z","steps":["trace[666073217] 'agreement among raft nodes before linearized reading' (duration: 105.975263ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:31.718189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12687} {"level":"info","ts":"2026-06-04T12:29:31.880711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12687,"took":"159.715239ms","hash":2130619864,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":27172864,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-04T12:29:31.880795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2130619864,"revision":12687,"compact-revision":10510} {"level":"info","ts":"2026-06-04T12:29:32.45107Z","caller":"traceutil/trace.go:171","msg":"trace[1528467296] transaction","detail":"{read_only:false; response_revision:14675; number_of_response:1; }","duration":"250.728805ms","start":"2026-06-04T12:29:32.200314Z","end":"2026-06-04T12:29:32.451042Z","steps":["trace[1528467296] 'process raft request' (duration: 250.504989ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:32.536059Z","caller":"traceutil/trace.go:171","msg":"trace[1314299005] transaction","detail":"{read_only:false; response_revision:14676; number_of_response:1; }","duration":"335.569641ms","start":"2026-06-04T12:29:32.200409Z","end":"2026-06-04T12:29:32.535979Z","steps":["trace[1314299005] 'process raft request' (duration: 330.255275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:29:32.536322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:29:32.200374Z","time spent":"335.823938ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:29:32.53652Z","caller":"traceutil/trace.go:171","msg":"trace[1575765902] transaction","detail":"{read_only:false; response_revision:14677; number_of_response:1; }","duration":"335.697724ms","start":"2026-06-04T12:29:32.200775Z","end":"2026-06-04T12:29:32.536473Z","steps":["trace[1575765902] 'process raft request' (duration: 335.143099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:29:32.536879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:29:32.200751Z","time spent":"335.868269ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:29:56.25966Z","caller":"traceutil/trace.go:171","msg":"trace[1994661611] linearizableReadLoop","detail":"{readStateIndex:15519; appliedIndex:15518; }","duration":"281.240531ms","start":"2026-06-04T12:29:55.978387Z","end":"2026-06-04T12:29:56.259627Z","steps":["trace[1994661611] 'read index received' (duration: 280.970574ms)","trace[1994661611] 'applied index is now lower than readState.Index' (duration: 268.697µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:29:56.260119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.701664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b843af4b-9efd-4634-9a8d-1cdb247a8d1f\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-06-04T12:29:56.260211Z","caller":"traceutil/trace.go:171","msg":"trace[698324860] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b843af4b-9efd-4634-9a8d-1cdb247a8d1f; range_end:; response_count:1; response_revision:14991; }","duration":"281.867259ms","start":"2026-06-04T12:29:55.978322Z","end":"2026-06-04T12:29:56.260189Z","steps":["trace[698324860] 'agreement among raft nodes before linearized reading' (duration: 281.676443ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:56.260497Z","caller":"traceutil/trace.go:171","msg":"trace[322670275] transaction","detail":"{read_only:false; response_revision:14991; number_of_response:1; }","duration":"296.813919ms","start":"2026-06-04T12:29:55.963674Z","end":"2026-06-04T12:29:56.260488Z","steps":["trace[322670275] 'process raft request' (duration: 295.77895ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:56.389025Z","caller":"traceutil/trace.go:171","msg":"trace[168896030] transaction","detail":"{read_only:false; response_revision:14992; number_of_response:1; }","duration":"124.871854ms","start":"2026-06-04T12:29:56.264045Z","end":"2026-06-04T12:29:56.388917Z","steps":["trace[168896030] 'process raft request' (duration: 122.852879ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:56.389878Z","caller":"traceutil/trace.go:171","msg":"trace[652989624] transaction","detail":"{read_only:false; response_revision:14993; number_of_response:1; }","duration":"125.285215ms","start":"2026-06-04T12:29:56.264565Z","end":"2026-06-04T12:29:56.38985Z","steps":["trace[652989624] 'process raft request' (duration: 125.1141ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:31:23.00347Z","caller":"traceutil/trace.go:171","msg":"trace[1425982655] linearizableReadLoop","detail":"{readStateIndex:16356; appliedIndex:16355; }","duration":"107.400544ms","start":"2026-06-04T12:31:22.896034Z","end":"2026-06-04T12:31:23.003435Z","steps":["trace[1425982655] 'read index received' (duration: 107.114637ms)","trace[1425982655] 'applied index is now lower than readState.Index' (duration: 284.287µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:31:23.00363Z","caller":"traceutil/trace.go:171","msg":"trace[416674966] transaction","detail":"{read_only:false; response_revision:15805; number_of_response:1; }","duration":"117.089811ms","start":"2026-06-04T12:31:22.886501Z","end":"2026-06-04T12:31:23.003591Z","steps":["trace[416674966] 'process raft request' (duration: 116.69914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:31:23.003864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.796116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x8qms\" ","response":"range_response_count:1 size:22099"} {"level":"info","ts":"2026-06-04T12:31:23.003928Z","caller":"traceutil/trace.go:171","msg":"trace[823409722] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x8qms; range_end:; response_count:1; response_revision:15805; }","duration":"107.925169ms","start":"2026-06-04T12:31:22.895986Z","end":"2026-06-04T12:31:23.003911Z","steps":["trace[823409722] 'agreement among raft nodes before linearized reading' (duration: 107.601391ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:31:23.209781Z","caller":"traceutil/trace.go:171","msg":"trace[55481627] linearizableReadLoop","detail":"{readStateIndex:16357; appliedIndex:16356; }","duration":"114.203661ms","start":"2026-06-04T12:31:23.095544Z","end":"2026-06-04T12:31:23.209747Z","steps":["trace[55481627] 'read index received' (duration: 113.937724ms)","trace[55481627] 'applied index is now lower than readState.Index' (duration: 265.057µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:31:23.21007Z","caller":"traceutil/trace.go:171","msg":"trace[1036279730] transaction","detail":"{read_only:false; response_revision:15806; number_of_response:1; }","duration":"160.504081ms","start":"2026-06-04T12:31:23.049533Z","end":"2026-06-04T12:31:23.210037Z","steps":["trace[1036279730] 'process raft request' (duration: 160.043749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:31:23.210127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.561311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-04T12:31:23.210169Z","caller":"traceutil/trace.go:171","msg":"trace[1462958106] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15806; }","duration":"114.653584ms","start":"2026-06-04T12:31:23.095506Z","end":"2026-06-04T12:31:23.21016Z","steps":["trace[1462958106] 'agreement among raft nodes before linearized reading' (duration: 114.430418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:31:23.210078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.357936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:31:23.210278Z","caller":"traceutil/trace.go:171","msg":"trace[832215283] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15806; }","duration":"114.626064ms","start":"2026-06-04T12:31:23.095637Z","end":"2026-06-04T12:31:23.210263Z","steps":["trace[832215283] 'agreement among raft nodes before linearized reading' (duration: 114.360066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:31:23.210494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.452439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-04T12:31:23.210527Z","caller":"traceutil/trace.go:171","msg":"trace[1236711224] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15806; }","duration":"114.50899ms","start":"2026-06-04T12:31:23.096011Z","end":"2026-06-04T12:31:23.21052Z","steps":["trace[1236711224] 'agreement among raft nodes before linearized reading' (duration: 114.418357ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:31:23.386909Z","caller":"traceutil/trace.go:171","msg":"trace[867603865] transaction","detail":"{read_only:false; response_revision:15807; number_of_response:1; }","duration":"170.245258ms","start":"2026-06-04T12:31:23.216627Z","end":"2026-06-04T12:31:23.386872Z","steps":["trace[867603865] 'process raft request' (duration: 130.20698ms)","trace[867603865] 'compare' (duration: 39.38279ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:31:23.386945Z","caller":"traceutil/trace.go:171","msg":"trace[2019379783] transaction","detail":"{read_only:false; response_revision:15808; number_of_response:1; }","duration":"166.073494ms","start":"2026-06-04T12:31:23.220839Z","end":"2026-06-04T12:31:23.386912Z","steps":["trace[2019379783] 'process raft request' (duration: 165.954571ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:34:31.724699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14671} {"level":"info","ts":"2026-06-04T12:34:31.806664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14671,"took":"78.393539ms","hash":2968652971,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":31363072,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-04T12:34:31.806744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2968652971,"revision":14671,"compact-revision":12687} {"level":"warn","ts":"2026-06-04T12:38:08.101867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879735745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:38:08.602282Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879735745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:38:09.103216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879735745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:38:09.603809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879735745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:38:10.1046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832269879735745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:38:10.395919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.01138849s","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-06-04T12:38:10.395993Z","caller":"traceutil/trace.go:171","msg":"trace[428626421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18656; }","duration":"3.011488943s","start":"2026-06-04T12:38:07.384488Z","end":"2026-06-04T12:38:10.395977Z","steps":["trace[428626421] 'range keys from in-memory index tree' (duration: 3.011246237s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.396022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.384478Z","time spent":"3.011537245s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-04T12:38:10.396419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.932334905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cxc5j\" ","response":"range_response_count:1 size:6532"} {"level":"info","ts":"2026-06-04T12:38:10.396489Z","caller":"traceutil/trace.go:171","msg":"trace[916442466] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cxc5j; range_end:; response_count:1; response_revision:18656; }","duration":"2.932462708s","start":"2026-06-04T12:38:07.464013Z","end":"2026-06-04T12:38:10.396475Z","steps":["trace[916442466] 'range keys from in-memory index tree' (duration: 2.93216931s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.396515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.46399Z","time spent":"2.93251957s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-cxc5j\" "} {"level":"warn","ts":"2026-06-04T12:38:10.396485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.822507467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-06-04T12:38:10.396591Z","caller":"traceutil/trace.go:171","msg":"trace[375814881] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18656; }","duration":"2.822660661s","start":"2026-06-04T12:38:07.57391Z","end":"2026-06-04T12:38:10.396571Z","steps":["trace[375814881] 'range keys from in-memory index tree' (duration: 2.82231066s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.396644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.573891Z","time spent":"2.822741892s","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-04T12:38:10.398764Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.822427374s","expected-duration":"1s"} {"level":"info","ts":"2026-06-04T12:38:10.399604Z","caller":"traceutil/trace.go:171","msg":"trace[171040840] transaction","detail":"{read_only:false; response_revision:18657; number_of_response:1; }","duration":"2.82336722s","start":"2026-06-04T12:38:07.576212Z","end":"2026-06-04T12:38:10.399579Z","steps":["trace[171040840] 'process raft request' (duration: 2.823210076s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.399768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.576198Z","time spent":"2.823451922s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:38:10.400306Z","caller":"traceutil/trace.go:171","msg":"trace[1140512010] linearizableReadLoop","detail":"{readStateIndex:19308; appliedIndex:19304; }","duration":"2.799099335s","start":"2026-06-04T12:38:07.601189Z","end":"2026-06-04T12:38:10.400288Z","steps":["trace[1140512010] 'read index received' (duration: 2.798288212s)","trace[1140512010] 'applied index is now lower than readState.Index' (duration: 810.203µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:38:10.400517Z","caller":"traceutil/trace.go:171","msg":"trace[1045097665] transaction","detail":"{read_only:false; response_revision:18658; number_of_response:1; }","duration":"2.812755439s","start":"2026-06-04T12:38:07.58775Z","end":"2026-06-04T12:38:10.400506Z","steps":["trace[1045097665] 'process raft request' (duration: 2.812396339s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.400594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.587725Z","time spent":"2.812827521s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:38:10.400733Z","caller":"traceutil/trace.go:171","msg":"trace[2030667199] transaction","detail":"{read_only:false; response_revision:18659; number_of_response:1; }","duration":"2.81100192s","start":"2026-06-04T12:38:07.589721Z","end":"2026-06-04T12:38:10.400723Z","steps":["trace[2030667199] 'process raft request' (duration: 2.810494747s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.40079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.589711Z","time spent":"2.811051252s","remote":"127.0.0.1:44942","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-06-04T12:38:10.400909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.784536115s","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-06-04T12:38:10.400956Z","caller":"traceutil/trace.go:171","msg":"trace[1318318284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18660; }","duration":"2.784628128s","start":"2026-06-04T12:38:07.616317Z","end":"2026-06-04T12:38:10.400945Z","steps":["trace[1318318284] 'agreement among raft nodes before linearized reading' (duration: 2.784483194s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.40099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.616294Z","time spent":"2.78468992s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:38:10.4011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294454574s","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-06-04T12:38:10.401155Z","caller":"traceutil/trace.go:171","msg":"trace[323716851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18660; }","duration":"1.294522876s","start":"2026-06-04T12:38:09.106614Z","end":"2026-06-04T12:38:10.401137Z","steps":["trace[323716851] 'agreement among raft nodes before linearized reading' (duration: 1.294357371s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.549345282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x8qms\" ","response":"range_response_count:1 size:22100"} {"level":"warn","ts":"2026-06-04T12:38:10.401185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.106608Z","time spent":"1.294572267s","remote":"127.0.0.1:44942","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-06-04T12:38:10.401149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.159823291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x8qms\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-06-04T12:38:10.401191Z","caller":"traceutil/trace.go:171","msg":"trace[345203195] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x8qms; range_end:; response_count:1; response_revision:18660; }","duration":"2.549447055s","start":"2026-06-04T12:38:07.851735Z","end":"2026-06-04T12:38:10.401182Z","steps":["trace[345203195] 'agreement among raft nodes before linearized reading' (duration: 2.549338292s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.4012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.708519053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-06-04T12:38:10.401238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.851697Z","time spent":"2.549532967s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-x8qms\" "} {"level":"warn","ts":"2026-06-04T12:38:10.401223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.093565946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-06-04T12:38:10.401249Z","caller":"traceutil/trace.go:171","msg":"trace[948712285] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18660; }","duration":"2.708613316s","start":"2026-06-04T12:38:07.692621Z","end":"2026-06-04T12:38:10.401235Z","steps":["trace[948712285] 'agreement among raft nodes before linearized reading' (duration: 2.708478922s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:38:10.401276Z","caller":"traceutil/trace.go:171","msg":"trace[1288053370] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18660; }","duration":"2.093644567s","start":"2026-06-04T12:38:08.307622Z","end":"2026-06-04T12:38:10.401266Z","steps":["trace[1288053370] 'agreement among raft nodes before linearized reading' (duration: 2.093534584s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.692596Z","time spent":"2.708680628s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-04T12:38:10.401314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.307605Z","time spent":"2.093701109s","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-04T12:38:10.401324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30635142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4nw8m\" ","response":"range_response_count:1 size:20755"} {"level":"info","ts":"2026-06-04T12:38:10.401231Z","caller":"traceutil/trace.go:171","msg":"trace[835595241] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x8qms; range_end:; response_count:1; response_revision:18660; }","duration":"2.159952034s","start":"2026-06-04T12:38:08.241264Z","end":"2026-06-04T12:38:10.401216Z","steps":["trace[835595241] 'agreement among raft nodes before linearized reading' (duration: 2.159693997s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:38:10.401381Z","caller":"traceutil/trace.go:171","msg":"trace[1801734673] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4nw8m; range_end:; response_count:1; response_revision:18660; }","duration":"1.306423382s","start":"2026-06-04T12:38:09.094941Z","end":"2026-06-04T12:38:10.401365Z","steps":["trace[1801734673] 'agreement among raft nodes before linearized reading' (duration: 1.306254387s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292005856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:38:10.401398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.098640165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-sc5fz\" ","response":"range_response_count:1 size:18672"} {"level":"info","ts":"2026-06-04T12:38:10.40143Z","caller":"traceutil/trace.go:171","msg":"trace[1354255466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18660; }","duration":"1.292060449s","start":"2026-06-04T12:38:09.109364Z","end":"2026-06-04T12:38:10.401424Z","steps":["trace[1354255466] 'agreement among raft nodes before linearized reading' (duration: 1.292032568s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:38:10.401278Z","caller":"traceutil/trace.go:171","msg":"trace[1802605175] transaction","detail":"{read_only:false; response_revision:18660; number_of_response:1; }","duration":"2.722738403s","start":"2026-06-04T12:38:07.678533Z","end":"2026-06-04T12:38:10.401271Z","steps":["trace[1802605175] 'process raft request' (duration: 2.721721015s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.109335Z","time spent":"1.292109589s","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-04T12:38:10.401449Z","caller":"traceutil/trace.go:171","msg":"trace[991750270] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-sc5fz; range_end:; response_count:1; response_revision:18660; }","duration":"2.098691466s","start":"2026-06-04T12:38:08.30275Z","end":"2026-06-04T12:38:10.401442Z","steps":["trace[991750270] 'agreement among raft nodes before linearized reading' (duration: 2.098523962s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.40148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306607717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-f6pp8\" ","response":"range_response_count:1 size:18647"} {"level":"warn","ts":"2026-06-04T12:38:10.401498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305870807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kfdhj\" ","response":"range_response_count:1 size:9792"} {"level":"warn","ts":"2026-06-04T12:38:10.401475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.289950765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-06-04T12:38:10.401519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.678515Z","time spent":"2.722946479s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:38:10.401537Z","caller":"traceutil/trace.go:171","msg":"trace[455469892] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kfdhj; range_end:; response_count:1; response_revision:18660; }","duration":"1.305916729s","start":"2026-06-04T12:38:09.095611Z","end":"2026-06-04T12:38:10.401527Z","steps":["trace[455469892] 'agreement among raft nodes before linearized reading' (duration: 1.305846757s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.139939526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-06-04T12:38:10.401577Z","caller":"traceutil/trace.go:171","msg":"trace[1669820536] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-f6pp8; range_end:; response_count:1; response_revision:18660; }","duration":"1.306746921s","start":"2026-06-04T12:38:09.094768Z","end":"2026-06-04T12:38:10.401515Z","steps":["trace[1669820536] 'agreement among raft nodes before linearized reading' (duration: 1.306431393s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.094751Z","time spent":"1.306870394s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18670,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-f6pp8\" "} {"level":"warn","ts":"2026-06-04T12:38:10.401642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.935485245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-04T12:38:10.40148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.302705Z","time spent":"2.098768618s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18695,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-sc5fz\" "} {"level":"info","ts":"2026-06-04T12:38:10.401686Z","caller":"traceutil/trace.go:171","msg":"trace[1215306165] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:18660; }","duration":"1.935568628s","start":"2026-06-04T12:38:08.466108Z","end":"2026-06-04T12:38:10.401676Z","steps":["trace[1215306165] 'agreement among raft nodes before linearized reading' (duration: 1.935430284s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:38:10.401599Z","caller":"traceutil/trace.go:171","msg":"trace[1283412211] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18660; }","duration":"2.140010868s","start":"2026-06-04T12:38:08.261582Z","end":"2026-06-04T12:38:10.401593Z","steps":["trace[1283412211] 'agreement among raft nodes before linearized reading' (duration: 2.139929316s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.46609Z","time spent":"1.935622339s","remote":"127.0.0.1:44958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":76,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:38:10.401748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.261568Z","time spent":"2.140160162s","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-04T12:38:10.401342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.800155403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-06-04T12:38:10.401828Z","caller":"traceutil/trace.go:171","msg":"trace[2016383050] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:18660; }","duration":"2.800657927s","start":"2026-06-04T12:38:07.60116Z","end":"2026-06-04T12:38:10.401818Z","steps":["trace[2016383050] 'agreement among raft nodes before linearized reading' (duration: 2.800135673s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.60115Z","time spent":"2.800698378s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-04T12:38:10.401562Z","caller":"traceutil/trace.go:171","msg":"trace[968603013] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18660; }","duration":"2.29008725s","start":"2026-06-04T12:38:08.111451Z","end":"2026-06-04T12:38:10.401538Z","steps":["trace[968603013] 'agreement among raft nodes before linearized reading' (duration: 2.289560615s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.547129032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-04T12:38:10.402066Z","caller":"traceutil/trace.go:171","msg":"trace[1316495106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18660; }","duration":"2.548074238s","start":"2026-06-04T12:38:07.853974Z","end":"2026-06-04T12:38:10.402049Z","steps":["trace[1316495106] 'agreement among raft nodes before linearized reading' (duration: 2.5470422s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.401826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.096089995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-06-04T12:38:10.402209Z","caller":"traceutil/trace.go:171","msg":"trace[1192899031] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18660; }","duration":"2.096523767s","start":"2026-06-04T12:38:08.305678Z","end":"2026-06-04T12:38:10.402202Z","steps":["trace[1192899031] 'agreement among raft nodes before linearized reading' (duration: 2.095480878s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.402251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.305657Z","time spent":"2.096585378s","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-04T12:38:10.401492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.358667508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:38:10.404649Z","caller":"traceutil/trace.go:171","msg":"trace[1171291214] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:18660; }","duration":"2.361847265s","start":"2026-06-04T12:38:08.042775Z","end":"2026-06-04T12:38:10.404622Z","steps":["trace[1171291214] 'agreement among raft nodes before linearized reading' (duration: 2.358678559s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.404763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.04275Z","time spent":"2.361985389s","remote":"127.0.0.1:45158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:38:10.401388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098447064s","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-06-04T12:38:10.404943Z","caller":"traceutil/trace.go:171","msg":"trace[352661489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18660; }","duration":"1.102032142s","start":"2026-06-04T12:38:09.302903Z","end":"2026-06-04T12:38:10.404935Z","steps":["trace[352661489] 'agreement among raft nodes before linearized reading' (duration: 1.098450524s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.404975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.302889Z","time spent":"1.102078193s","remote":"127.0.0.1:44942","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-06-04T12:38:10.40157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.095607Z","time spent":"1.305954979s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9815,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-kfdhj\" "} {"level":"warn","ts":"2026-06-04T12:38:10.401427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.241239Z","time spent":"2.16017609s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-x8qms\" "} {"level":"warn","ts":"2026-06-04T12:38:10.401858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.940224884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-dkttp\" ","response":"range_response_count:1 size:14278"} {"level":"info","ts":"2026-06-04T12:38:10.405356Z","caller":"traceutil/trace.go:171","msg":"trace[1299132541] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-dkttp; range_end:; response_count:1; response_revision:18660; }","duration":"1.94371901s","start":"2026-06-04T12:38:08.461588Z","end":"2026-06-04T12:38:10.405307Z","steps":["trace[1299132541] 'agreement among raft nodes before linearized reading' (duration: 1.939368241s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.405393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.461568Z","time spent":"1.943814133s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14301,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-dkttp\" "} {"level":"warn","ts":"2026-06-04T12:38:10.40192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.111426Z","time spent":"2.2904848s","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-04T12:38:10.401433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.094937Z","time spent":"1.306486434s","remote":"127.0.0.1:44870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20778,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4nw8m\" "} {"level":"warn","ts":"2026-06-04T12:38:10.402106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.853954Z","time spent":"2.548140979s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-04T12:39:31.73355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17118} {"level":"info","ts":"2026-06-04T12:39:31.834722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17118,"took":"97.765388ms","hash":3014718645,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":29241344,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-04T12:39:31.834798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3014718645,"revision":17118,"compact-revision":14671} {"level":"info","ts":"2026-06-04T12:40:16.909012Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:40:16.914472Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-04T12:40:16.914623Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-04T12:44:31.741033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19098} {"level":"info","ts":"2026-06-04T12:44:31.834734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19098,"took":"90.199832ms","hash":3913880853,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":23769088,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-04T12:44:31.834806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3913880853,"revision":19098,"compact-revision":17118}