{"level":"warn","ts":"2026-08-22T18:49:45.293697Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T18:49:45.293786Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-22T18:49:45.293862Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T18:49:45.293878Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-08-22T18:49:45.293897Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-22T18:49:45.294576Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-08-22T18:49:45.294661Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-22T18:49:45.297208Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.26615ms"} {"level":"info","ts":"2026-08-22T18:49:45.302165Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-08-22T18:49:45.302245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T18:49:45.302273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-08-22T18:49:45.302291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T18:49:45.302341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-08-22T18:49:45.302386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-08-22T18:49:45.307076Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T18:49:45.308527Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T18:49:45.312254Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-22T18:49:45.314912Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T18:49:45.315156Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T18:49:45.315357Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-22T18:49:45.315491Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-22T18:49:45.315529Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-22T18:49:45.317893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-08-22T18:49:45.318156Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-08-22T18:49:45.320628Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-22T18:49:45.320812Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-08-22T18:49:45.320907Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-08-22T18:49:45.321065Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T18:49:45.321119Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T18:49:46.10286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T18:49:46.10293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T18:49:46.102981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-08-22T18:49:46.103001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-08-22T18:49:46.103013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-08-22T18:49:46.10303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-08-22T18:49:46.103044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-08-22T18:49:46.104684Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:49:46.105604Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T18:49:46.1056Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T18:49:46.105653Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T18:49:46.106166Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:49:46.106303Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:49:46.106347Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:49:46.106442Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T18:49:46.106465Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T18:49:46.108799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T18:49:46.109517Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-08-22T18:50:09.731632Z","caller":"traceutil/trace.go:171","msg":"trace[1688073957] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"158.433282ms","start":"2026-08-22T18:50:09.57317Z","end":"2026-08-22T18:50:09.731603Z","steps":["trace[1688073957] 'process raft request' (duration: 158.291179ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:50:45.009212Z","caller":"traceutil/trace.go:171","msg":"trace[457851712] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"130.836227ms","start":"2026-08-22T18:50:44.878349Z","end":"2026-08-22T18:50:45.009185Z","steps":["trace[457851712] 'process raft request' (duration: 130.648892ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:50:45.02512Z","caller":"traceutil/trace.go:171","msg":"trace[821306062] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"143.247904ms","start":"2026-08-22T18:50:44.881819Z","end":"2026-08-22T18:50:45.025067Z","steps":["trace[821306062] 'process raft request' (duration: 142.994847ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:50:45.885552Z","caller":"traceutil/trace.go:171","msg":"trace[1345025008] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"461.612248ms","start":"2026-08-22T18:50:45.423911Z","end":"2026-08-22T18:50:45.885524Z","steps":["trace[1345025008] 'process raft request' (duration: 460.993782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:50:45.886149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:50:45.42386Z","time spent":"461.782663ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:50:46.078909Z","caller":"traceutil/trace.go:171","msg":"trace[1782117263] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"151.418658ms","start":"2026-08-22T18:50:45.927436Z","end":"2026-08-22T18:50:46.078855Z","steps":["trace[1782117263] 'process raft request' (duration: 146.593691ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:50:46.078978Z","caller":"traceutil/trace.go:171","msg":"trace[1762849447] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"137.763699ms","start":"2026-08-22T18:50:45.941178Z","end":"2026-08-22T18:50:46.078942Z","steps":["trace[1762849447] 'process raft request' (duration: 137.549164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:52:12.776519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.674053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-22T18:52:12.776614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.404826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-22T18:52:12.776742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.582606ms","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-08-22T18:52:12.776789Z","caller":"traceutil/trace.go:171","msg":"trace[1082209774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1877; }","duration":"190.978822ms","start":"2026-08-22T18:52:12.585749Z","end":"2026-08-22T18:52:12.776727Z","steps":["trace[1082209774] 'range keys from in-memory index tree' (duration: 190.360775ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:52:12.776846Z","caller":"traceutil/trace.go:171","msg":"trace[2131700307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1877; }","duration":"242.75581ms","start":"2026-08-22T18:52:12.534067Z","end":"2026-08-22T18:52:12.776823Z","steps":["trace[2131700307] 'range keys from in-memory index tree' (duration: 242.309819ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:52:12.776819Z","caller":"traceutil/trace.go:171","msg":"trace[706556518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1877; }","duration":"185.634912ms","start":"2026-08-22T18:52:12.591124Z","end":"2026-08-22T18:52:12.776759Z","steps":["trace[706556518] 'range keys from in-memory index tree' (duration: 185.123428ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:52:12.776932Z","caller":"traceutil/trace.go:171","msg":"trace[1014422843] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"171.308524ms","start":"2026-08-22T18:52:12.605578Z","end":"2026-08-22T18:52:12.776886Z","steps":["trace[1014422843] 'process raft request' (duration: 161.320567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:53:02.779767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.522604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-22T18:53:02.779864Z","caller":"traceutil/trace.go:171","msg":"trace[1997989129] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2205; }","duration":"179.674988ms","start":"2026-08-22T18:53:02.600167Z","end":"2026-08-22T18:53:02.779842Z","steps":["trace[1997989129] 'range keys from in-memory index tree' (duration: 179.293417ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:54:26.992476Z","caller":"traceutil/trace.go:171","msg":"trace[919970984] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"136.742393ms","start":"2026-08-22T18:54:26.855699Z","end":"2026-08-22T18:54:26.992441Z","steps":["trace[919970984] 'process raft request' (duration: 136.514067ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:56:57.958436Z","caller":"traceutil/trace.go:171","msg":"trace[446861557] transaction","detail":"{read_only:false; response_revision:3851; number_of_response:1; }","duration":"123.675559ms","start":"2026-08-22T18:56:57.834734Z","end":"2026-08-22T18:56:57.95841Z","steps":["trace[446861557] 'process raft request' (duration: 123.546235ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:58:18.354619Z","caller":"traceutil/trace.go:171","msg":"trace[1615476010] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"116.83117ms","start":"2026-08-22T18:58:18.237759Z","end":"2026-08-22T18:58:18.35459Z","steps":["trace[1615476010] 'process raft request' (duration: 116.714747ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:58:23.83242Z","caller":"traceutil/trace.go:171","msg":"trace[1589164678] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5099; }","duration":"245.183267ms","start":"2026-08-22T18:58:23.587215Z","end":"2026-08-22T18:58:23.832399Z","steps":["trace[1589164678] 'read index received' (duration: 244.95973ms)","trace[1589164678] 'applied index is now lower than readState.Index' (duration: 222.677µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:58:23.832546Z","caller":"traceutil/trace.go:171","msg":"trace[242250493] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"252.916512ms","start":"2026-08-22T18:58:23.579588Z","end":"2026-08-22T18:58:23.832505Z","steps":["trace[242250493] 'process raft request' (duration: 252.649335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:58:23.832618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.757279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bmctx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T18:58:23.83265Z","caller":"traceutil/trace.go:171","msg":"trace[961850954] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bmctx; range_end:; response_count:1; response_revision:4907; }","duration":"152.844792ms","start":"2026-08-22T18:58:23.679796Z","end":"2026-08-22T18:58:23.832641Z","steps":["trace[961850954] 'agreement among raft nodes before linearized reading' (duration: 152.724478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:58:23.832724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.472975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7465"} {"level":"info","ts":"2026-08-22T18:58:23.832829Z","caller":"traceutil/trace.go:171","msg":"trace[891384414] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4907; }","duration":"245.593638ms","start":"2026-08-22T18:58:23.587209Z","end":"2026-08-22T18:58:23.832803Z","steps":["trace[891384414] 'agreement among raft nodes before linearized reading' (duration: 245.29958ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:58:27.355614Z","caller":"traceutil/trace.go:171","msg":"trace[629864712] linearizableReadLoop","detail":"{readStateIndex:5242; appliedIndex:5241; }","duration":"168.878285ms","start":"2026-08-22T18:58:27.186703Z","end":"2026-08-22T18:58:27.355581Z","steps":["trace[629864712] 'read index received' (duration: 168.71086ms)","trace[629864712] 'applied index is now lower than readState.Index' (duration: 165.665µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:58:27.355699Z","caller":"traceutil/trace.go:171","msg":"trace[349483474] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"211.762261ms","start":"2026-08-22T18:58:27.143901Z","end":"2026-08-22T18:58:27.355664Z","steps":["trace[349483474] 'process raft request' (duration: 211.441522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:58:27.355848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.643318ms","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-08-22T18:58:27.355896Z","caller":"traceutil/trace.go:171","msg":"trace[687054974] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:5046; }","duration":"168.752841ms","start":"2026-08-22T18:58:27.18713Z","end":"2026-08-22T18:58:27.355883Z","steps":["trace[687054974] 'agreement among raft nodes before linearized reading' (duration: 168.637808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:58:27.355915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.151622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T18:58:27.356004Z","caller":"traceutil/trace.go:171","msg":"trace[100577089] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:5046; }","duration":"169.285586ms","start":"2026-08-22T18:58:27.186697Z","end":"2026-08-22T18:58:27.355982Z","steps":["trace[100577089] 'agreement among raft nodes before linearized reading' (duration: 169.055839ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:58:27.486203Z","caller":"traceutil/trace.go:171","msg":"trace[253296090] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"122.217594ms","start":"2026-08-22T18:58:27.363947Z","end":"2026-08-22T18:58:27.486165Z","steps":["trace[253296090] 'process raft request' (duration: 122.037569ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:58:27.759017Z","caller":"traceutil/trace.go:171","msg":"trace[1858998588] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"154.567988ms","start":"2026-08-22T18:58:27.604425Z","end":"2026-08-22T18:58:27.758993Z","steps":["trace[1858998588] 'process raft request' (duration: 146.971539ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:58:40.111888Z","caller":"traceutil/trace.go:171","msg":"trace[1193034147] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"104.015488ms","start":"2026-08-22T18:58:40.007843Z","end":"2026-08-22T18:58:40.111858Z","steps":["trace[1193034147] 'process raft request' (duration: 103.827963ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:58:40.700294Z","caller":"traceutil/trace.go:171","msg":"trace[302291648] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"231.791156ms","start":"2026-08-22T18:58:40.468481Z","end":"2026-08-22T18:58:40.700272Z","steps":["trace[302291648] 'process raft request' (duration: 231.532849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:58:48.005686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.297464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2102"} {"level":"info","ts":"2026-08-22T18:58:48.005767Z","caller":"traceutil/trace.go:171","msg":"trace[461114252] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5501; }","duration":"131.422818ms","start":"2026-08-22T18:58:47.874327Z","end":"2026-08-22T18:58:48.00575Z","steps":["trace[461114252] 'range keys from in-memory index tree' (duration: 131.015336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:58:48.261298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.872639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:58:48.261288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.844036ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:58:48.261396Z","caller":"traceutil/trace.go:171","msg":"trace[1683711579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5502; }","duration":"141.038603ms","start":"2026-08-22T18:58:48.120337Z","end":"2026-08-22T18:58:48.261376Z","steps":["trace[1683711579] 'range keys from in-memory index tree' (duration: 140.798097ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:58:48.261409Z","caller":"traceutil/trace.go:171","msg":"trace[327360905] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5502; }","duration":"141.98047ms","start":"2026-08-22T18:58:48.119409Z","end":"2026-08-22T18:58:48.26139Z","steps":["trace[327360905] 'range keys from in-memory index tree' (duration: 141.826976ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:58:48.478945Z","caller":"traceutil/trace.go:171","msg":"trace[1948975174] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"153.252803ms","start":"2026-08-22T18:58:48.325662Z","end":"2026-08-22T18:58:48.478915Z","steps":["trace[1948975174] 'process raft request' (duration: 64.115455ms)","trace[1948975174] 'compare' (duration: 89.006903ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:58:48.488078Z","caller":"traceutil/trace.go:171","msg":"trace[2035376127] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"102.170589ms","start":"2026-08-22T18:58:48.385879Z","end":"2026-08-22T18:58:48.48805Z","steps":["trace[2035376127] 'process raft request' (duration: 102.006935ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:59:46.134742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2963} {"level":"info","ts":"2026-08-22T18:59:46.204883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2963,"took":"68.639699ms","hash":3806352054,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":33939456,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T18:59:46.204979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3806352054,"revision":2963,"compact-revision":-1} {"level":"info","ts":"2026-08-22T19:04:46.141326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6035} {"level":"info","ts":"2026-08-22T19:04:46.246895Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6035,"took":"103.517359ms","hash":1447023922,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":23228416,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-22T19:04:46.246979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1447023922,"revision":6035,"compact-revision":2963} {"level":"info","ts":"2026-08-22T19:04:48.957655Z","caller":"traceutil/trace.go:171","msg":"trace[977527534] linearizableReadLoop","detail":"{readStateIndex:8574; appliedIndex:8573; }","duration":"234.779588ms","start":"2026-08-22T19:04:48.722843Z","end":"2026-08-22T19:04:48.957622Z","steps":["trace[977527534] 'read index received' (duration: 234.4974ms)","trace[977527534] 'applied index is now lower than readState.Index' (duration: 280.838µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:04:48.957739Z","caller":"traceutil/trace.go:171","msg":"trace[1755595573] transaction","detail":"{read_only:false; response_revision:8277; number_of_response:1; }","duration":"245.83962ms","start":"2026-08-22T19:04:48.711854Z","end":"2026-08-22T19:04:48.957694Z","steps":["trace[1755595573] 'process raft request' (duration: 245.554912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:04:48.95788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.003494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T19:04:48.957919Z","caller":"traceutil/trace.go:171","msg":"trace[1325823255] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:8277; }","duration":"235.069236ms","start":"2026-08-22T19:04:48.722837Z","end":"2026-08-22T19:04:48.957906Z","steps":["trace[1325823255] 'agreement among raft nodes before linearized reading' (duration: 234.957063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:04:48.958124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.100397ms","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-08-22T19:04:48.958245Z","caller":"traceutil/trace.go:171","msg":"trace[751096934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8277; }","duration":"229.253972ms","start":"2026-08-22T19:04:48.728957Z","end":"2026-08-22T19:04:48.958211Z","steps":["trace[751096934] 'agreement among raft nodes before linearized reading' (duration: 228.994024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:04:48.958295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.428295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T19:04:48.958324Z","caller":"traceutil/trace.go:171","msg":"trace[2133366906] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8277; }","duration":"147.511157ms","start":"2026-08-22T19:04:48.810803Z","end":"2026-08-22T19:04:48.958314Z","steps":["trace[2133366906] 'agreement among raft nodes before linearized reading' (duration: 147.440965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:04:48.958413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.437466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-22T19:04:48.958455Z","caller":"traceutil/trace.go:171","msg":"trace[424530651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8277; }","duration":"224.544049ms","start":"2026-08-22T19:04:48.7339Z","end":"2026-08-22T19:04:48.958444Z","steps":["trace[424530651] 'agreement among raft nodes before linearized reading' (duration: 224.347214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:04:48.958578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.358075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-22T19:04:48.958705Z","caller":"traceutil/trace.go:171","msg":"trace[537303186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8277; }","duration":"224.57136ms","start":"2026-08-22T19:04:48.734103Z","end":"2026-08-22T19:04:48.958674Z","steps":["trace[537303186] 'agreement among raft nodes before linearized reading' (duration: 224.121887ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:04:49.07992Z","caller":"traceutil/trace.go:171","msg":"trace[1546252597] transaction","detail":"{read_only:false; response_revision:8280; number_of_response:1; }","duration":"116.308739ms","start":"2026-08-22T19:04:48.963588Z","end":"2026-08-22T19:04:49.079897Z","steps":["trace[1546252597] 'process raft request' (duration: 116.274188ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:04:49.07992Z","caller":"traceutil/trace.go:171","msg":"trace[2073234986] transaction","detail":"{read_only:false; response_revision:8278; number_of_response:1; }","duration":"117.366189ms","start":"2026-08-22T19:04:48.962513Z","end":"2026-08-22T19:04:49.079879Z","steps":["trace[2073234986] 'process raft request' (duration: 105.767492ms)","trace[2073234986] 'compare' (duration: 11.394751ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:04:49.080043Z","caller":"traceutil/trace.go:171","msg":"trace[1136280724] transaction","detail":"{read_only:false; response_revision:8279; number_of_response:1; }","duration":"116.897225ms","start":"2026-08-22T19:04:48.963111Z","end":"2026-08-22T19:04:49.080008Z","steps":["trace[1136280724] 'process raft request' (duration: 116.71632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:05:01.056456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.652367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T19:05:01.056544Z","caller":"traceutil/trace.go:171","msg":"trace[273850743] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8342; }","duration":"100.810722ms","start":"2026-08-22T19:05:00.955712Z","end":"2026-08-22T19:05:01.056523Z","steps":["trace[273850743] 'count revisions from in-memory index tree' (duration: 100.423161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:05:01.821327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.235113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T19:05:01.821459Z","caller":"traceutil/trace.go:171","msg":"trace[1653485875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8349; }","duration":"123.381277ms","start":"2026-08-22T19:05:01.698021Z","end":"2026-08-22T19:05:01.821402Z","steps":["trace[1653485875] 'range keys from in-memory index tree' (duration: 123.072279ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:05:31.366014Z","caller":"traceutil/trace.go:171","msg":"trace[2017917994] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"162.47835ms","start":"2026-08-22T19:05:31.203508Z","end":"2026-08-22T19:05:31.365987Z","steps":["trace[2017917994] 'process raft request' (duration: 161.992165ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:05:36.540568Z","caller":"traceutil/trace.go:171","msg":"trace[1066673111] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"108.293493ms","start":"2026-08-22T19:05:36.432254Z","end":"2026-08-22T19:05:36.540547Z","steps":["trace[1066673111] 'process raft request' (duration: 108.162899ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:05:42.508058Z","caller":"traceutil/trace.go:171","msg":"trace[1292757495] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"126.703941ms","start":"2026-08-22T19:05:42.38132Z","end":"2026-08-22T19:05:42.508024Z","steps":["trace[1292757495] 'process raft request' (duration: 126.413473ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:05:42.771248Z","caller":"traceutil/trace.go:171","msg":"trace[1766721652] linearizableReadLoop","detail":"{readStateIndex:8957; appliedIndex:8956; }","duration":"201.373796ms","start":"2026-08-22T19:05:42.569847Z","end":"2026-08-22T19:05:42.771221Z","steps":["trace[1766721652] 'read index received' (duration: 109.015883ms)","trace[1766721652] 'applied index is now lower than readState.Index' (duration: 92.356613ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T19:05:42.771519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.672253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-22T19:05:42.771553Z","caller":"traceutil/trace.go:171","msg":"trace[1384599414] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8645; }","duration":"257.744625ms","start":"2026-08-22T19:05:42.5138Z","end":"2026-08-22T19:05:42.771545Z","steps":["trace[1384599414] 'agreement among raft nodes before linearized reading' (duration: 257.519549ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:05:42.771767Z","caller":"traceutil/trace.go:171","msg":"trace[1250627775] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"250.988505ms","start":"2026-08-22T19:05:42.520764Z","end":"2026-08-22T19:05:42.771753Z","steps":["trace[1250627775] 'process raft request' (duration: 158.165419ms)","trace[1250627775] 'compare' (duration: 91.910021ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:05:42.917767Z","caller":"traceutil/trace.go:171","msg":"trace[1805185891] linearizableReadLoop","detail":"{readStateIndex:8958; appliedIndex:8957; }","duration":"127.71432ms","start":"2026-08-22T19:05:42.790021Z","end":"2026-08-22T19:05:42.917735Z","steps":["trace[1805185891] 'read index received' (duration: 126.027153ms)","trace[1805185891] 'applied index is now lower than readState.Index' (duration: 1.685967ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:05:42.918522Z","caller":"traceutil/trace.go:171","msg":"trace[924598241] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"130.268421ms","start":"2026-08-22T19:05:42.78823Z","end":"2026-08-22T19:05:42.918498Z","steps":["trace[924598241] 'process raft request' (duration: 127.847204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:05:42.918847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.80123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-22T19:05:42.918891Z","caller":"traceutil/trace.go:171","msg":"trace[1534210594] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:8646; }","duration":"128.869633ms","start":"2026-08-22T19:05:42.79001Z","end":"2026-08-22T19:05:42.918879Z","steps":["trace[1534210594] 'agreement among raft nodes before linearized reading' (duration: 128.703987ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:06:32.990775Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024cf.wal"} {"level":"info","ts":"2026-08-22T19:07:34.410213Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T19:07:34.41659Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T19:07:34.41669Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T19:09:46.148914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8266} {"level":"info","ts":"2026-08-22T19:09:46.22727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8266,"took":"75.676573ms","hash":876467436,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":26460160,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T19:09:46.227364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":876467436,"revision":8266,"compact-revision":6035} {"level":"info","ts":"2026-08-22T19:10:47.612617Z","caller":"traceutil/trace.go:171","msg":"trace[1156373183] linearizableReadLoop","detail":"{readStateIndex:11679; appliedIndex:11678; }","duration":"161.759854ms","start":"2026-08-22T19:10:47.450831Z","end":"2026-08-22T19:10:47.61259Z","steps":["trace[1156373183] 'read index received' (duration: 161.512337ms)","trace[1156373183] 'applied index is now lower than readState.Index' (duration: 246.297µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:10:47.612734Z","caller":"traceutil/trace.go:171","msg":"trace[1368634379] transaction","detail":"{read_only:false; response_revision:11287; number_of_response:1; }","duration":"171.629154ms","start":"2026-08-22T19:10:47.441067Z","end":"2026-08-22T19:10:47.612696Z","steps":["trace[1368634379] 'process raft request' (duration: 171.370407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:10:47.613027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.165645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-08-22T19:10:47.613098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.170503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129648"} {"level":"info","ts":"2026-08-22T19:10:47.613113Z","caller":"traceutil/trace.go:171","msg":"trace[766062149] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:11287; }","duration":"162.290909ms","start":"2026-08-22T19:10:47.450802Z","end":"2026-08-22T19:10:47.613093Z","steps":["trace[766062149] 'agreement among raft nodes before linearized reading' (duration: 161.97516ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:10:47.613144Z","caller":"traceutil/trace.go:171","msg":"trace[1383538973] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:11287; }","duration":"155.261616ms","start":"2026-08-22T19:10:47.457869Z","end":"2026-08-22T19:10:47.61313Z","steps":["trace[1383538973] 'agreement among raft nodes before linearized reading' (duration: 154.953997ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:14:46.155003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10732} {"level":"info","ts":"2026-08-22T19:14:46.234381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10732,"took":"76.47035ms","hash":4097473202,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":27734016,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T19:14:46.234435Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4097473202,"revision":10732,"compact-revision":8266} {"level":"info","ts":"2026-08-22T19:17:06.284295Z","caller":"traceutil/trace.go:171","msg":"trace[1657820823] transaction","detail":"{read_only:false; response_revision:13844; number_of_response:1; }","duration":"101.497212ms","start":"2026-08-22T19:17:06.18277Z","end":"2026-08-22T19:17:06.284267Z","steps":["trace[1657820823] 'process raft request' (duration: 101.364588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:17:07.298564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.321683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T19:17:07.298649Z","caller":"traceutil/trace.go:171","msg":"trace[727915079] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:13849; }","duration":"237.470957ms","start":"2026-08-22T19:17:07.061158Z","end":"2026-08-22T19:17:07.298629Z","steps":["trace[727915079] 'count revisions from in-memory index tree' (duration: 237.230491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:17:07.298653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.558543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-22T19:17:07.298741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.695916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-22T19:17:07.298819Z","caller":"traceutil/trace.go:171","msg":"trace[437982678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13849; }","duration":"385.82764ms","start":"2026-08-22T19:17:06.912971Z","end":"2026-08-22T19:17:07.298799Z","steps":["trace[437982678] 'range keys from in-memory index tree' (duration: 385.46987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:17:07.298888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T19:17:06.912955Z","time spent":"385.923262ms","remote":"127.0.0.1:57032","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-08-22T19:17:07.300507Z","caller":"traceutil/trace.go:171","msg":"trace[937894656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13849; }","duration":"385.737967ms","start":"2026-08-22T19:17:06.913017Z","end":"2026-08-22T19:17:07.298754Z","steps":["trace[937894656] 'range keys from in-memory index tree' (duration: 385.386077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:17:07.300599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T19:17:06.912991Z","time spent":"387.585818ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T19:17:16.678295Z","caller":"traceutil/trace.go:171","msg":"trace[1083929395] transaction","detail":"{read_only:false; response_revision:14016; number_of_response:1; }","duration":"173.006096ms","start":"2026-08-22T19:17:16.505262Z","end":"2026-08-22T19:17:16.678268Z","steps":["trace[1083929395] 'process raft request' (duration: 172.823861ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:17:16.820841Z","caller":"traceutil/trace.go:171","msg":"trace[107712850] transaction","detail":"{read_only:false; response_revision:14017; number_of_response:1; }","duration":"172.832752ms","start":"2026-08-22T19:17:16.647977Z","end":"2026-08-22T19:17:16.82081Z","steps":["trace[107712850] 'process raft request' (duration: 168.071251ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:17:16.820995Z","caller":"traceutil/trace.go:171","msg":"trace[897049276] transaction","detail":"{read_only:false; response_revision:14018; number_of_response:1; }","duration":"171.173116ms","start":"2026-08-22T19:17:16.649796Z","end":"2026-08-22T19:17:16.820969Z","steps":["trace[897049276] 'process raft request' (duration: 170.923609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:18:52.189018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.948802ms","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":"info","ts":"2026-08-22T19:18:52.189129Z","caller":"traceutil/trace.go:171","msg":"trace[577159567] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:15080; }","duration":"232.077136ms","start":"2026-08-22T19:18:51.957028Z","end":"2026-08-22T19:18:52.189105Z","steps":["trace[577159567] 'count revisions from in-memory index tree' (duration: 231.840399ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:18:52.340767Z","caller":"traceutil/trace.go:171","msg":"trace[773671573] transaction","detail":"{read_only:false; response_revision:15081; number_of_response:1; }","duration":"145.75643ms","start":"2026-08-22T19:18:52.194984Z","end":"2026-08-22T19:18:52.34074Z","steps":["trace[773671573] 'process raft request' (duration: 145.613127ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:18:52.341797Z","caller":"traceutil/trace.go:171","msg":"trace[1470121545] linearizableReadLoop","detail":"{readStateIndex:15603; appliedIndex:15601; }","duration":"143.014692ms","start":"2026-08-22T19:18:52.198745Z","end":"2026-08-22T19:18:52.341759Z","steps":["trace[1470121545] 'read index received' (duration: 141.954434ms)","trace[1470121545] 'applied index is now lower than readState.Index' (duration: 1.058768ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:18:52.342257Z","caller":"traceutil/trace.go:171","msg":"trace[1651999827] transaction","detail":"{read_only:false; response_revision:15082; number_of_response:1; }","duration":"147.144076ms","start":"2026-08-22T19:18:52.195078Z","end":"2026-08-22T19:18:52.342222Z","steps":["trace[1651999827] 'process raft request' (duration: 146.436959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:18:52.343503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.234042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769800"} {"level":"warn","ts":"2026-08-22T19:18:52.343595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.832167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769800"} {"level":"info","ts":"2026-08-22T19:18:52.343658Z","caller":"traceutil/trace.go:171","msg":"trace[311420909] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15083; }","duration":"144.920869ms","start":"2026-08-22T19:18:52.198725Z","end":"2026-08-22T19:18:52.343645Z","steps":["trace[311420909] 'agreement among raft nodes before linearized reading' (duration: 144.142799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:18:52.343905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.211645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-08-22T19:18:52.343961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.60295ms","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-08-22T19:18:52.344004Z","caller":"traceutil/trace.go:171","msg":"trace[726004655] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15083; }","duration":"101.677091ms","start":"2026-08-22T19:18:52.242315Z","end":"2026-08-22T19:18:52.343992Z","steps":["trace[726004655] 'agreement among raft nodes before linearized reading' (duration: 101.61031ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:18:52.344008Z","caller":"traceutil/trace.go:171","msg":"trace[699822456] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15083; }","duration":"100.380068ms","start":"2026-08-22T19:18:52.243606Z","end":"2026-08-22T19:18:52.343986Z","steps":["trace[699822456] 'agreement among raft nodes before linearized reading' (duration: 100.033059ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:18:52.343601Z","caller":"traceutil/trace.go:171","msg":"trace[598419173] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15083; }","duration":"144.399406ms","start":"2026-08-22T19:18:52.19918Z","end":"2026-08-22T19:18:52.343579Z","steps":["trace[598419173] 'agreement among raft nodes before linearized reading' (duration: 143.322619ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:19:46.172743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12831} {"level":"info","ts":"2026-08-22T19:19:46.246131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12831,"took":"71.824769ms","hash":28126856,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":31649792,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T19:19:46.246246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":28126856,"revision":12831,"compact-revision":10732} {"level":"warn","ts":"2026-08-22T19:24:34.806821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.248771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-h6cj6\" ","response":"range_response_count:1 size:15746"} {"level":"info","ts":"2026-08-22T19:24:34.806928Z","caller":"traceutil/trace.go:171","msg":"trace[752449738] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-h6cj6; range_end:; response_count:1; response_revision:17675; }","duration":"131.391755ms","start":"2026-08-22T19:24:34.67551Z","end":"2026-08-22T19:24:34.806902Z","steps":["trace[752449738] 'range keys from in-memory index tree' (duration: 131.039986ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:24:34.814217Z","caller":"traceutil/trace.go:171","msg":"trace[1274037124] transaction","detail":"{read_only:false; response_revision:17676; number_of_response:1; }","duration":"137.029962ms","start":"2026-08-22T19:24:34.677176Z","end":"2026-08-22T19:24:34.814205Z","steps":["trace[1274037124] 'process raft request' (duration: 136.920349ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:24:41.277532Z","caller":"traceutil/trace.go:171","msg":"trace[782770] linearizableReadLoop","detail":"{readStateIndex:18341; appliedIndex:18340; }","duration":"143.876543ms","start":"2026-08-22T19:24:41.133636Z","end":"2026-08-22T19:24:41.277513Z","steps":["trace[782770] 'read index received' (duration: 143.76982ms)","trace[782770] 'applied index is now lower than readState.Index' (duration: 105.993µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:24:41.277615Z","caller":"traceutil/trace.go:171","msg":"trace[1994171716] transaction","detail":"{read_only:false; response_revision:17731; number_of_response:1; }","duration":"146.490296ms","start":"2026-08-22T19:24:41.13109Z","end":"2026-08-22T19:24:41.277581Z","steps":["trace[1994171716] 'process raft request' (duration: 146.27806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:24:41.277732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.085658ms","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-08-22T19:24:41.277809Z","caller":"traceutil/trace.go:171","msg":"trace[1182194446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17731; }","duration":"144.213462ms","start":"2026-08-22T19:24:41.13358Z","end":"2026-08-22T19:24:41.277793Z","steps":["trace[1182194446] 'agreement among raft nodes before linearized reading' (duration: 144.012456ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:24:41.820847Z","caller":"traceutil/trace.go:171","msg":"trace[779863480] transaction","detail":"{read_only:false; response_revision:17734; number_of_response:1; }","duration":"194.431529ms","start":"2026-08-22T19:24:41.626388Z","end":"2026-08-22T19:24:41.820819Z","steps":["trace[779863480] 'process raft request' (duration: 108.757345ms)","trace[779863480] 'compare' (duration: 85.508489ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:24:41.821067Z","caller":"traceutil/trace.go:171","msg":"trace[1445095036] transaction","detail":"{read_only:false; response_revision:17735; number_of_response:1; }","duration":"110.734231ms","start":"2026-08-22T19:24:41.710322Z","end":"2026-08-22T19:24:41.821057Z","steps":["trace[1445095036] 'process raft request' (duration: 110.452203ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:24:42.23624Z","caller":"traceutil/trace.go:171","msg":"trace[1397699818] linearizableReadLoop","detail":"{readStateIndex:18346; appliedIndex:18345; }","duration":"116.640225ms","start":"2026-08-22T19:24:42.119576Z","end":"2026-08-22T19:24:42.236216Z","steps":["trace[1397699818] 'read index received' (duration: 116.369867ms)","trace[1397699818] 'applied index is now lower than readState.Index' (duration: 269.068µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:24:42.236672Z","caller":"traceutil/trace.go:171","msg":"trace[1243835353] transaction","detail":"{read_only:false; response_revision:17736; number_of_response:1; }","duration":"400.213564ms","start":"2026-08-22T19:24:41.836443Z","end":"2026-08-22T19:24:42.236657Z","steps":["trace[1243835353] 'process raft request' (duration: 399.519145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:24:42.23704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T19:24:41.836424Z","time spent":"400.305207ms","remote":"127.0.0.1:56930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20871,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T19:24:42.237703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.095476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T19:24:42.237815Z","caller":"traceutil/trace.go:171","msg":"trace[1195998822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17736; }","duration":"118.26272ms","start":"2026-08-22T19:24:42.119527Z","end":"2026-08-22T19:24:42.23779Z","steps":["trace[1195998822] 'agreement among raft nodes before linearized reading' (duration: 118.084585ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:24:46.179893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15425} {"level":"info","ts":"2026-08-22T19:24:46.285746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15425,"took":"102.287596ms","hash":1313090991,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":32878592,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T19:24:46.285816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1313090991,"revision":15425,"compact-revision":12831} {"level":"info","ts":"2026-08-22T19:27:58.000202Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T19:27:58.00452Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T19:27:58.00464Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T19:29:42.793621Z","caller":"traceutil/trace.go:171","msg":"trace[727689581] transaction","detail":"{read_only:false; response_revision:20075; number_of_response:1; }","duration":"114.069269ms","start":"2026-08-22T19:29:42.679522Z","end":"2026-08-22T19:29:42.793591Z","steps":["trace[727689581] 'process raft request' (duration: 113.791232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:29:42.954881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.376407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T19:29:42.955082Z","caller":"traceutil/trace.go:171","msg":"trace[1489835431] linearizableReadLoop","detail":"{readStateIndex:20763; appliedIndex:20762; }","duration":"113.556806ms","start":"2026-08-22T19:29:42.841507Z","end":"2026-08-22T19:29:42.955064Z","steps":["trace[1489835431] 'read index received' (duration: 88.872µs)","trace[1489835431] 'applied index is now lower than readState.Index' (duration: 113.466213ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:29:42.955117Z","caller":"traceutil/trace.go:171","msg":"trace[777275379] transaction","detail":"{read_only:false; response_revision:20076; number_of_response:1; }","duration":"156.550023ms","start":"2026-08-22T19:29:42.798538Z","end":"2026-08-22T19:29:42.955088Z","steps":["trace[777275379] 'process raft request' (duration: 31.696203ms)","trace[777275379] 'compare' (duration: 124.196171ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T19:29:42.955341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.829743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T19:29:42.955419Z","caller":"traceutil/trace.go:171","msg":"trace[1469205260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20076; }","duration":"113.940706ms","start":"2026-08-22T19:29:42.841467Z","end":"2026-08-22T19:29:42.955408Z","steps":["trace[1469205260] 'agreement among raft nodes before linearized reading' (duration: 113.687239ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:29:43.102294Z","caller":"traceutil/trace.go:171","msg":"trace[1343620176] transaction","detail":"{read_only:false; response_revision:20077; number_of_response:1; }","duration":"141.692549ms","start":"2026-08-22T19:29:42.96057Z","end":"2026-08-22T19:29:43.102263Z","steps":["trace[1343620176] 'process raft request' (duration: 127.458373ms)","trace[1343620176] 'compare' (duration: 14.098642ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:29:43.189758Z","caller":"traceutil/trace.go:171","msg":"trace[823254641] transaction","detail":"{read_only:false; response_revision:20078; number_of_response:1; }","duration":"182.533088ms","start":"2026-08-22T19:29:43.007195Z","end":"2026-08-22T19:29:43.189728Z","steps":["trace[823254641] 'process raft request' (duration: 182.352953ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:29:43.50354Z","caller":"traceutil/trace.go:171","msg":"trace[78877039] transaction","detail":"{read_only:false; response_revision:20079; number_of_response:1; }","duration":"105.388787ms","start":"2026-08-22T19:29:43.398112Z","end":"2026-08-22T19:29:43.503501Z","steps":["trace[78877039] 'process raft request' (duration: 105.14957ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:29:43.645389Z","caller":"traceutil/trace.go:171","msg":"trace[860282359] transaction","detail":"{read_only:false; response_revision:20080; number_of_response:1; }","duration":"246.606854ms","start":"2026-08-22T19:29:43.398745Z","end":"2026-08-22T19:29:43.645352Z","steps":["trace[860282359] 'process raft request' (duration: 221.684339ms)","trace[860282359] 'compare' (duration: 24.76054ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:29:43.971755Z","caller":"traceutil/trace.go:171","msg":"trace[227086658] transaction","detail":"{read_only:false; response_revision:20082; number_of_response:1; }","duration":"135.163858ms","start":"2026-08-22T19:29:43.836532Z","end":"2026-08-22T19:29:43.971696Z","steps":["trace[227086658] 'process raft request' (duration: 134.957722ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:29:44.109449Z","caller":"traceutil/trace.go:171","msg":"trace[1279592406] transaction","detail":"{read_only:false; response_revision:20083; number_of_response:1; }","duration":"134.312423ms","start":"2026-08-22T19:29:43.975115Z","end":"2026-08-22T19:29:44.109427Z","steps":["trace[1279592406] 'process raft request' (duration: 134.19228ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:29:44.110137Z","caller":"traceutil/trace.go:171","msg":"trace[1070494785] transaction","detail":"{read_only:false; response_revision:20084; number_of_response:1; }","duration":"133.775659ms","start":"2026-08-22T19:29:43.976338Z","end":"2026-08-22T19:29:44.110114Z","steps":["trace[1070494785] 'process raft request' (duration: 133.596254ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:29:46.187088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17767} {"level":"info","ts":"2026-08-22T19:29:46.294304Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17767,"took":"103.299329ms","hash":2921498848,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":33042432,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T19:29:46.294364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2921498848,"revision":17767,"compact-revision":15425} {"level":"info","ts":"2026-08-22T19:33:26.138827Z","caller":"traceutil/trace.go:171","msg":"trace[2023120430] transaction","detail":"{read_only:false; response_revision:22336; number_of_response:1; }","duration":"128.966455ms","start":"2026-08-22T19:33:26.009835Z","end":"2026-08-22T19:33:26.138801Z","steps":["trace[2023120430] 'process raft request' (duration: 128.79162ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:33:26.13903Z","caller":"traceutil/trace.go:171","msg":"trace[1790089666] linearizableReadLoop","detail":"{readStateIndex:23097; appliedIndex:23097; }","duration":"127.804513ms","start":"2026-08-22T19:33:26.011203Z","end":"2026-08-22T19:33:26.139007Z","steps":["trace[1790089666] 'read index received' (duration: 127.795593ms)","trace[1790089666] 'applied index is now lower than readState.Index' (duration: 7.34µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T19:33:26.139193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.970037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T19:33:26.139217Z","caller":"traceutil/trace.go:171","msg":"trace[1499831478] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:22336; }","duration":"128.03724ms","start":"2026-08-22T19:33:26.011173Z","end":"2026-08-22T19:33:26.13921Z","steps":["trace[1499831478] 'agreement among raft nodes before linearized reading' (duration: 127.930647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:33:26.152671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.390867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T19:33:26.152732Z","caller":"traceutil/trace.go:171","msg":"trace[1872018575] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:22337; }","duration":"141.480559ms","start":"2026-08-22T19:33:26.011239Z","end":"2026-08-22T19:33:26.15272Z","steps":["trace[1872018575] 'agreement among raft nodes before linearized reading' (duration: 141.356326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:33:26.153292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.275433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T19:33:26.153308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.787978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T19:33:26.153327Z","caller":"traceutil/trace.go:171","msg":"trace[1570160411] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:22337; }","duration":"121.819248ms","start":"2026-08-22T19:33:26.031502Z","end":"2026-08-22T19:33:26.153321Z","steps":["trace[1570160411] 'agreement among raft nodes before linearized reading' (duration: 121.789838ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:33:26.153326Z","caller":"traceutil/trace.go:171","msg":"trace[981808158] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:22337; }","duration":"121.318174ms","start":"2026-08-22T19:33:26.031997Z","end":"2026-08-22T19:33:26.153315Z","steps":["trace[981808158] 'agreement among raft nodes before linearized reading' (duration: 121.264323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:33:26.153445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.079736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-08-22T19:33:26.153463Z","caller":"traceutil/trace.go:171","msg":"trace[562806345] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:22337; }","duration":"122.139667ms","start":"2026-08-22T19:33:26.031317Z","end":"2026-08-22T19:33:26.153456Z","steps":["trace[562806345] 'agreement among raft nodes before linearized reading' (duration: 122.081386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:33:26.153553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.831411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T19:33:26.153568Z","caller":"traceutil/trace.go:171","msg":"trace[1554526542] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:22337; }","duration":"140.859711ms","start":"2026-08-22T19:33:26.012703Z","end":"2026-08-22T19:33:26.153562Z","steps":["trace[1554526542] 'agreement among raft nodes before linearized reading' (duration: 140.83571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:33:26.153877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.865632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T19:33:26.153894Z","caller":"traceutil/trace.go:171","msg":"trace[1977341923] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:22337; }","duration":"100.915984ms","start":"2026-08-22T19:33:26.052973Z","end":"2026-08-22T19:33:26.153889Z","steps":["trace[1977341923] 'agreement among raft nodes before linearized reading' (duration: 100.889393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:33:26.153968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.991976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T19:33:26.15398Z","caller":"traceutil/trace.go:171","msg":"trace[1847732230] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:22337; }","duration":"101.044517ms","start":"2026-08-22T19:33:26.052932Z","end":"2026-08-22T19:33:26.153977Z","steps":["trace[1847732230] 'agreement among raft nodes before linearized reading' (duration: 101.022197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:33:26.154047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.088828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T19:33:26.154062Z","caller":"traceutil/trace.go:171","msg":"trace[266946292] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:22337; }","duration":"101.1424ms","start":"2026-08-22T19:33:26.052915Z","end":"2026-08-22T19:33:26.154058Z","steps":["trace[266946292] 'agreement among raft nodes before linearized reading' (duration: 101.117269ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:33:33.021094Z","caller":"traceutil/trace.go:171","msg":"trace[825661912] linearizableReadLoop","detail":"{readStateIndex:23256; appliedIndex:23255; }","duration":"139.980986ms","start":"2026-08-22T19:33:32.881088Z","end":"2026-08-22T19:33:33.021069Z","steps":["trace[825661912] 'read index received' (duration: 139.830982ms)","trace[825661912] 'applied index is now lower than readState.Index' (duration: 148.804µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T19:33:33.021265Z","caller":"traceutil/trace.go:171","msg":"trace[1485956275] transaction","detail":"{read_only:false; response_revision:22494; number_of_response:1; }","duration":"153.17039ms","start":"2026-08-22T19:33:32.868052Z","end":"2026-08-22T19:33:33.021222Z","steps":["trace[1485956275] 'process raft request' (duration: 152.849251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:33:33.021342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.227894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T19:33:33.02138Z","caller":"traceutil/trace.go:171","msg":"trace[745306514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22494; }","duration":"140.307947ms","start":"2026-08-22T19:33:32.881061Z","end":"2026-08-22T19:33:33.021369Z","steps":["trace[745306514] 'agreement among raft nodes before linearized reading' (duration: 140.117792ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:33:33.045289Z","caller":"traceutil/trace.go:171","msg":"trace[1012235318] transaction","detail":"{read_only:false; response_revision:22495; number_of_response:1; }","duration":"109.14299ms","start":"2026-08-22T19:33:32.936125Z","end":"2026-08-22T19:33:33.045268Z","steps":["trace[1012235318] 'process raft request' (duration: 108.625566ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:34:46.193814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20094} {"level":"info","ts":"2026-08-22T19:34:46.305924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20094,"took":"106.155627ms","hash":3046268285,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":43216896,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-22T19:34:46.306013Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3046268285,"revision":20094,"compact-revision":17767} {"level":"info","ts":"2026-08-22T19:35:33.288999Z","caller":"traceutil/trace.go:171","msg":"trace[1825758273] transaction","detail":"{read_only:false; response_revision:23702; number_of_response:1; }","duration":"110.913279ms","start":"2026-08-22T19:35:33.178063Z","end":"2026-08-22T19:35:33.288977Z","steps":["trace[1825758273] 'process raft request' (duration: 110.082766ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:35:46.970758Z","caller":"traceutil/trace.go:171","msg":"trace[962123619] transaction","detail":"{read_only:false; response_revision:23803; number_of_response:1; }","duration":"279.904973ms","start":"2026-08-22T19:35:46.690727Z","end":"2026-08-22T19:35:46.970632Z","steps":["trace[962123619] 'process raft request' (duration: 279.357889ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:35:47.011967Z","caller":"traceutil/trace.go:171","msg":"trace[138195046] transaction","detail":"{read_only:false; response_revision:23804; number_of_response:1; }","duration":"320.198431ms","start":"2026-08-22T19:35:46.691743Z","end":"2026-08-22T19:35:47.011942Z","steps":["trace[138195046] 'process raft request' (duration: 320.008056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T19:35:47.012212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T19:35:46.691731Z","time spent":"320.386136ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T19:35:47.01241Z","caller":"traceutil/trace.go:171","msg":"trace[1715453459] transaction","detail":"{read_only:false; response_revision:23805; number_of_response:1; }","duration":"153.098968ms","start":"2026-08-22T19:35:46.859253Z","end":"2026-08-22T19:35:47.012352Z","steps":["trace[1715453459] 'process raft request' (duration: 152.644346ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:35:47.012529Z","caller":"traceutil/trace.go:171","msg":"trace[627302484] linearizableReadLoop","detail":"{readStateIndex:24600; appliedIndex:24598; }","duration":"282.433152ms","start":"2026-08-22T19:35:46.73008Z","end":"2026-08-22T19:35:47.012513Z","steps":["trace[627302484] 'read index received' (duration: 240.523371ms)","trace[627302484] 'applied index is now lower than readState.Index' (duration: 41.908201ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T19:35:47.012832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.744781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-22T19:35:47.012949Z","caller":"traceutil/trace.go:171","msg":"trace[1525712610] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:23806; }","duration":"282.885425ms","start":"2026-08-22T19:35:46.730047Z","end":"2026-08-22T19:35:47.012933Z","steps":["trace[1525712610] 'agreement among raft nodes before linearized reading' (duration: 282.677369ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:35:47.013094Z","caller":"traceutil/trace.go:171","msg":"trace[144598917] transaction","detail":"{read_only:false; response_revision:23806; number_of_response:1; }","duration":"152.618144ms","start":"2026-08-22T19:35:46.860462Z","end":"2026-08-22T19:35:47.01308Z","steps":["trace[144598917] 'process raft request' (duration: 151.74039ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T19:39:46.201927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23302} {"level":"info","ts":"2026-08-22T19:39:46.336603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23302,"took":"131.372487ms","hash":1942196625,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":31248384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T19:39:46.336657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1942196625,"revision":23302,"compact-revision":20094}