{"level":"warn","ts":"2026-07-30T21:07:43.996Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T21:07:43.996089Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-07-30T21:07:43.996155Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T21:07:43.996167Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-30T21:07:43.996204Z","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-07-30T21:07:43.996864Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-07-30T21:07:43.997031Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-07-30T21:07:43.999068Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.807596ms"} {"level":"info","ts":"2026-07-30T21:07:44.003226Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-07-30T21:07:44.003301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T21:07:44.003325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-07-30T21:07:44.003336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T21:07:44.00335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-07-30T21:07:44.003398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-07-30T21:07:44.008117Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T21:07:44.010656Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T21:07:44.012265Z","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-07-30T21:07:44.015231Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T21:07:44.015471Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T21:07:44.015587Z","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-07-30T21:07:44.015666Z","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-07-30T21:07:44.015678Z","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-07-30T21:07:44.016664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-07-30T21:07:44.019477Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-30T21:07:44.020931Z","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-07-30T21:07:44.021053Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-30T21:07:44.021096Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-30T21:07:44.022192Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T21:07:44.022229Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T21:07:44.503793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T21:07:44.503859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T21:07:44.503899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-07-30T21:07:44.503924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-07-30T21:07:44.503935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-30T21:07:44.503962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-07-30T21:07:44.503984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-30T21:07:44.505212Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T21:07:44.505255Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:07:44.505238Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:07:44.505217Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:07:44.505987Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T21:07:44.506058Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T21:07:44.506398Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:07:44.506526Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:07:44.50657Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:07:44.508943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T21:07:44.508986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-07-30T21:07:59.104295Z","caller":"traceutil/trace.go:171","msg":"trace[1080342086] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"131.665494ms","start":"2026-07-30T21:07:58.972594Z","end":"2026-07-30T21:07:59.10426Z","steps":["trace[1080342086] 'process raft request' (duration: 131.47795ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:27.050612Z","caller":"traceutil/trace.go:171","msg":"trace[989934003] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"264.923089ms","start":"2026-07-30T21:08:26.785632Z","end":"2026-07-30T21:08:27.050555Z","steps":["trace[989934003] 'process raft request' (duration: 263.3784ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:27.577323Z","caller":"traceutil/trace.go:171","msg":"trace[581587886] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"340.35695ms","start":"2026-07-30T21:08:27.236928Z","end":"2026-07-30T21:08:27.577285Z","steps":["trace[581587886] 'read index received' (duration: 339.028104ms)","trace[581587886] 'applied index is now lower than readState.Index' (duration: 1.327226ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:08:27.57742Z","caller":"traceutil/trace.go:171","msg":"trace[687804173] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"415.021505ms","start":"2026-07-30T21:08:27.162357Z","end":"2026-07-30T21:08:27.577379Z","steps":["trace[687804173] 'process raft request' (duration: 413.534657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:27.57758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.650894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T21:08:27.57768Z","caller":"traceutil/trace.go:171","msg":"trace[880111402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"340.829828ms","start":"2026-07-30T21:08:27.236833Z","end":"2026-07-30T21:08:27.577663Z","steps":["trace[880111402] 'agreement among raft nodes before linearized reading' (duration: 340.571543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:27.577733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:27.236809Z","time spent":"340.91204ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:08:27.5781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:27.16233Z","time spent":"415.190588ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:09:19.276769Z","caller":"traceutil/trace.go:171","msg":"trace[1000411365] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"140.319904ms","start":"2026-07-30T21:09:19.136421Z","end":"2026-07-30T21:09:19.276741Z","steps":["trace[1000411365] 'process raft request' (duration: 140.10858ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:09:19.446723Z","caller":"traceutil/trace.go:171","msg":"trace[167353966] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"213.090883ms","start":"2026-07-30T21:09:19.23358Z","end":"2026-07-30T21:09:19.446671Z","steps":["trace[167353966] 'process raft request' (duration: 151.006207ms)","trace[167353966] 'compare' (duration: 61.822621ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:09:43.24788Z","caller":"traceutil/trace.go:171","msg":"trace[1986828299] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"208.230449ms","start":"2026-07-30T21:09:43.039606Z","end":"2026-07-30T21:09:43.247836Z","steps":["trace[1986828299] 'process raft request' (duration: 206.15752ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:09:44.184954Z","caller":"traceutil/trace.go:171","msg":"trace[606400533] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"181.711153ms","start":"2026-07-30T21:09:44.003179Z","end":"2026-07-30T21:09:44.18489Z","steps":["trace[606400533] 'process raft request' (duration: 181.502758ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:09:45.781482Z","caller":"traceutil/trace.go:171","msg":"trace[1957028450] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"356.646026ms","start":"2026-07-30T21:09:45.424168Z","end":"2026-07-30T21:09:45.780814Z","steps":["trace[1957028450] 'process raft request' (duration: 267.32112ms)","trace[1957028450] 'compare' (duration: 89.058072ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:09:45.781675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:09:45.424141Z","time spent":"357.440252ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:09:45.796469Z","caller":"traceutil/trace.go:171","msg":"trace[1614819848] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"103.219033ms","start":"2026-07-30T21:09:45.693216Z","end":"2026-07-30T21:09:45.796435Z","steps":["trace[1614819848] 'read index received' (duration: 103.030568ms)","trace[1614819848] 'applied index is now lower than readState.Index' (duration: 186.995µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:09:45.79658Z","caller":"traceutil/trace.go:171","msg":"trace[1500581573] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"226.603881ms","start":"2026-07-30T21:09:45.569955Z","end":"2026-07-30T21:09:45.796559Z","steps":["trace[1500581573] 'process raft request' (duration: 226.312245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:09:45.796636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.400927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:09:45.796682Z","caller":"traceutil/trace.go:171","msg":"trace[975663021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1366; }","duration":"103.517489ms","start":"2026-07-30T21:09:45.693153Z","end":"2026-07-30T21:09:45.79667Z","steps":["trace[975663021] 'agreement among raft nodes before linearized reading' (duration: 103.411827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:10:35.762046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.619155ms","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-07-30T21:10:35.76217Z","caller":"traceutil/trace.go:171","msg":"trace[798298144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2142; }","duration":"147.809149ms","start":"2026-07-30T21:10:35.614335Z","end":"2026-07-30T21:10:35.762145Z","steps":["trace[798298144] 'range keys from in-memory index tree' (duration: 147.348471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:10:36.141089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.241572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:10:36.141193Z","caller":"traceutil/trace.go:171","msg":"trace[650337929] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2143; }","duration":"237.369444ms","start":"2026-07-30T21:10:35.903801Z","end":"2026-07-30T21:10:36.141171Z","steps":["trace[650337929] 'count revisions from in-memory index tree' (duration: 237.089088ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:10:36.141669Z","caller":"traceutil/trace.go:171","msg":"trace[441811888] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"162.329347ms","start":"2026-07-30T21:10:35.979126Z","end":"2026-07-30T21:10:36.141456Z","steps":["trace[441811888] 'process raft request' (duration: 135.8364ms)","trace[441811888] 'compare' (duration: 25.906005ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:11:14.440361Z","caller":"traceutil/trace.go:171","msg":"trace[2056462117] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"154.744683ms","start":"2026-07-30T21:11:14.285577Z","end":"2026-07-30T21:11:14.440322Z","steps":["trace[2056462117] 'process raft request' (duration: 154.158331ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:14.650372Z","caller":"traceutil/trace.go:171","msg":"trace[44127629] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"204.488355ms","start":"2026-07-30T21:11:14.445842Z","end":"2026-07-30T21:11:14.65033Z","steps":["trace[44127629] 'process raft request' (duration: 111.737029ms)","trace[44127629] 'compare' (duration: 92.45901ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:11:14.650462Z","caller":"traceutil/trace.go:171","msg":"trace[34803773] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"205.414162ms","start":"2026-07-30T21:11:14.44501Z","end":"2026-07-30T21:11:14.650424Z","steps":["trace[34803773] 'process raft request' (duration: 205.196627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:14.880243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.340866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:11:14.880378Z","caller":"traceutil/trace.go:171","msg":"trace[901814837] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"150.47412ms","start":"2026-07-30T21:11:14.729875Z","end":"2026-07-30T21:11:14.880349Z","steps":["trace[901814837] 'process raft request' (duration: 32.709907ms)","trace[901814837] 'compare' (duration: 117.231883ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:33.490165Z","caller":"traceutil/trace.go:171","msg":"trace[1372040842] linearizableReadLoop","detail":"{readStateIndex:3119; appliedIndex:3118; }","duration":"131.684603ms","start":"2026-07-30T21:12:33.358453Z","end":"2026-07-30T21:12:33.490138Z","steps":["trace[1372040842] 'read index received' (duration: 127.009644ms)","trace[1372040842] 'applied index is now lower than readState.Index' (duration: 4.673679ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:33.490303Z","caller":"traceutil/trace.go:171","msg":"trace[769619439] transaction","detail":"{read_only:false; response_revision:3014; number_of_response:1; }","duration":"132.250513ms","start":"2026-07-30T21:12:33.358009Z","end":"2026-07-30T21:12:33.490259Z","steps":["trace[769619439] 'process raft request' (duration: 127.498243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:33.490507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.03152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-07-30T21:12:33.490572Z","caller":"traceutil/trace.go:171","msg":"trace[186532809] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3014; }","duration":"132.151253ms","start":"2026-07-30T21:12:33.358402Z","end":"2026-07-30T21:12:33.490553Z","steps":["trace[186532809] 'agreement among raft nodes before linearized reading' (duration: 131.881907ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:17:44.538152Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3094} {"level":"info","ts":"2026-07-30T21:17:44.642948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3094,"took":"103.582471ms","hash":1947044049,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":8527872,"current-db-size-in-use":"8.5 MB"} {"level":"info","ts":"2026-07-30T21:17:44.643025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1947044049,"revision":3094,"compact-revision":-1} {"level":"info","ts":"2026-07-30T21:22:44.543708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5019} {"level":"info","ts":"2026-07-30T21:22:44.58434Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5019,"took":"39.801939ms","hash":346790316,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":11776000,"current-db-size-in-use":"12 MB"} {"level":"info","ts":"2026-07-30T21:22:44.584407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":346790316,"revision":5019,"compact-revision":3094} {"level":"info","ts":"2026-07-30T21:27:44.554024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7299} {"level":"info","ts":"2026-07-30T21:27:44.612117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7299,"took":"56.714502ms","hash":867109119,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":12906496,"current-db-size-in-use":"13 MB"} {"level":"info","ts":"2026-07-30T21:27:44.612208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":867109119,"revision":7299,"compact-revision":5019} {"level":"info","ts":"2026-07-30T21:27:46.363384Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T21:27:46.369095Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-30T21:27:46.369233Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-30T21:31:27.346049Z","caller":"traceutil/trace.go:171","msg":"trace[850978562] transaction","detail":"{read_only:false; response_revision:11522; number_of_response:1; }","duration":"100.969798ms","start":"2026-07-30T21:31:27.245045Z","end":"2026-07-30T21:31:27.346014Z","steps":["trace[850978562] 'process raft request' (duration: 100.740954ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:31:27.542388Z","caller":"traceutil/trace.go:171","msg":"trace[1607587572] linearizableReadLoop","detail":"{readStateIndex:11884; appliedIndex:11883; }","duration":"107.072825ms","start":"2026-07-30T21:31:27.435287Z","end":"2026-07-30T21:31:27.54236Z","steps":["trace[1607587572] 'read index received' (duration: 106.905042ms)","trace[1607587572] 'applied index is now lower than readState.Index' (duration: 166.793µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:31:27.542791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.313461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:31:27.54277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.481383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:31:27.542873Z","caller":"traceutil/trace.go:171","msg":"trace[1070275664] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11523; }","duration":"107.650646ms","start":"2026-07-30T21:31:27.435205Z","end":"2026-07-30T21:31:27.542856Z","steps":["trace[1070275664] 'agreement among raft nodes before linearized reading' (duration: 107.487493ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:31:27.542874Z","caller":"traceutil/trace.go:171","msg":"trace[908970929] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11523; }","duration":"107.423242ms","start":"2026-07-30T21:31:27.435421Z","end":"2026-07-30T21:31:27.542844Z","steps":["trace[908970929] 'agreement among raft nodes before linearized reading' (duration: 107.29935ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:31:27.542975Z","caller":"traceutil/trace.go:171","msg":"trace[1372600958] transaction","detail":"{read_only:false; response_revision:11523; number_of_response:1; }","duration":"135.57754ms","start":"2026-07-30T21:31:27.407377Z","end":"2026-07-30T21:31:27.542955Z","steps":["trace[1372600958] 'process raft request' (duration: 134.680173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:31:27.543084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.815859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:31:27.543109Z","caller":"traceutil/trace.go:171","msg":"trace[1624081321] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11523; }","duration":"107.904731ms","start":"2026-07-30T21:31:27.435197Z","end":"2026-07-30T21:31:27.543102Z","steps":["trace[1624081321] 'agreement among raft nodes before linearized reading' (duration: 107.86191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:31:27.543184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.625767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-07-30T21:31:27.543222Z","caller":"traceutil/trace.go:171","msg":"trace[1829048868] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11523; }","duration":"105.714939ms","start":"2026-07-30T21:31:27.437496Z","end":"2026-07-30T21:31:27.543211Z","steps":["trace[1829048868] 'agreement among raft nodes before linearized reading' (duration: 105.559106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:31:27.543298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.751259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-30T21:31:27.543347Z","caller":"traceutil/trace.go:171","msg":"trace[1381557693] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11523; }","duration":"106.843861ms","start":"2026-07-30T21:31:27.436489Z","end":"2026-07-30T21:31:27.543333Z","steps":["trace[1381557693] 'agreement among raft nodes before linearized reading' (duration: 106.651818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:31:27.543332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.189387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:31:27.543427Z","caller":"traceutil/trace.go:171","msg":"trace[1489302309] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11523; }","duration":"107.284958ms","start":"2026-07-30T21:31:27.436118Z","end":"2026-07-30T21:31:27.543403Z","steps":["trace[1489302309] 'agreement among raft nodes before linearized reading' (duration: 107.169426ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:31:30.525532Z","caller":"traceutil/trace.go:171","msg":"trace[1954992402] transaction","detail":"{read_only:false; response_revision:11543; number_of_response:1; }","duration":"138.505036ms","start":"2026-07-30T21:31:30.386978Z","end":"2026-07-30T21:31:30.525483Z","steps":["trace[1954992402] 'process raft request' (duration: 117.398623ms)","trace[1954992402] 'compare' (duration: 20.825408ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:32:44.561075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9679} {"level":"info","ts":"2026-07-30T21:32:44.622612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9679,"took":"59.13452ms","hash":1784714788,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":14528512,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-30T21:32:44.622698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1784714788,"revision":9679,"compact-revision":7299} {"level":"info","ts":"2026-07-30T21:37:31.588516Z","caller":"traceutil/trace.go:171","msg":"trace[982896124] transaction","detail":"{read_only:false; response_revision:14461; number_of_response:1; }","duration":"140.941202ms","start":"2026-07-30T21:37:31.447541Z","end":"2026-07-30T21:37:31.588482Z","steps":["trace[982896124] 'process raft request' (duration: 140.678166ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:37:31.599384Z","caller":"traceutil/trace.go:171","msg":"trace[146018114] linearizableReadLoop","detail":"{readStateIndex:14910; appliedIndex:14908; }","duration":"100.486289ms","start":"2026-07-30T21:37:31.498862Z","end":"2026-07-30T21:37:31.599348Z","steps":["trace[146018114] 'read index received' (duration: 89.476408ms)","trace[146018114] 'applied index is now lower than readState.Index' (duration: 11.008301ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:37:31.599495Z","caller":"traceutil/trace.go:171","msg":"trace[1960581189] transaction","detail":"{read_only:false; response_revision:14462; number_of_response:1; }","duration":"151.112087ms","start":"2026-07-30T21:37:31.448342Z","end":"2026-07-30T21:37:31.599454Z","steps":["trace[1960581189] 'process raft request' (duration: 150.816331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:37:31.599585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.690592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:37:31.599629Z","caller":"traceutil/trace.go:171","msg":"trace[595714896] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:14462; }","duration":"100.826976ms","start":"2026-07-30T21:37:31.49879Z","end":"2026-07-30T21:37:31.599617Z","steps":["trace[595714896] 'agreement among raft nodes before linearized reading' (duration: 100.689082ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:37:44.569049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12109} {"level":"info","ts":"2026-07-30T21:37:44.631763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12109,"took":"59.846232ms","hash":384496514,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":16883712,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-07-30T21:37:44.631827Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":384496514,"revision":12109,"compact-revision":9679} {"level":"warn","ts":"2026-07-30T21:39:03.454403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.542454ms","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-07-30T21:39:03.454623Z","caller":"traceutil/trace.go:171","msg":"trace[1186292051] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15597; }","duration":"108.818599ms","start":"2026-07-30T21:39:03.345779Z","end":"2026-07-30T21:39:03.454598Z","steps":["trace[1186292051] 'range keys from in-memory index tree' (duration: 108.35799ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:39:03.657678Z","caller":"traceutil/trace.go:171","msg":"trace[1939403311] linearizableReadLoop","detail":"{readStateIndex:16080; appliedIndex:16078; }","duration":"182.891544ms","start":"2026-07-30T21:39:03.474762Z","end":"2026-07-30T21:39:03.657654Z","steps":["trace[1939403311] 'read index received' (duration: 21.109774ms)","trace[1939403311] 'applied index is now lower than readState.Index' (duration: 161.78079ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:39:03.65775Z","caller":"traceutil/trace.go:171","msg":"trace[1043954322] transaction","detail":"{read_only:false; response_revision:15600; number_of_response:1; }","duration":"193.339002ms","start":"2026-07-30T21:39:03.464375Z","end":"2026-07-30T21:39:03.657714Z","steps":["trace[1043954322] 'process raft request' (duration: 185.543463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:39:03.657853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.031407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:39:03.657997Z","caller":"traceutil/trace.go:171","msg":"trace[1428016044] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:15600; }","duration":"183.24017ms","start":"2026-07-30T21:39:03.474732Z","end":"2026-07-30T21:39:03.657973Z","steps":["trace[1428016044] 'agreement among raft nodes before linearized reading' (duration: 183.008966ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:39:04.729218Z","caller":"traceutil/trace.go:171","msg":"trace[2052915329] linearizableReadLoop","detail":"{readStateIndex:16085; appliedIndex:16084; }","duration":"202.826373ms","start":"2026-07-30T21:39:04.526365Z","end":"2026-07-30T21:39:04.729192Z","steps":["trace[2052915329] 'read index received' (duration: 202.614669ms)","trace[2052915329] 'applied index is now lower than readState.Index' (duration: 210.844µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:39:04.729285Z","caller":"traceutil/trace.go:171","msg":"trace[129429450] transaction","detail":"{read_only:false; response_revision:15605; number_of_response:1; }","duration":"216.484842ms","start":"2026-07-30T21:39:04.512778Z","end":"2026-07-30T21:39:04.729263Z","steps":["trace[129429450] 'process raft request' (duration: 216.235538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:39:04.729503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.126029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-j986m\" ","response":"range_response_count:1 size:20565"} {"level":"info","ts":"2026-07-30T21:39:04.729545Z","caller":"traceutil/trace.go:171","msg":"trace[205746014] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-j986m; range_end:; response_count:1; response_revision:15605; }","duration":"203.224841ms","start":"2026-07-30T21:39:04.52631Z","end":"2026-07-30T21:39:04.729535Z","steps":["trace[205746014] 'agreement among raft nodes before linearized reading' (duration: 203.013157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:39:04.729601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.87647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-m26z7\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-07-30T21:39:04.729688Z","caller":"traceutil/trace.go:171","msg":"trace[693568193] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-m26z7; range_end:; response_count:1; response_revision:15605; }","duration":"196.013823ms","start":"2026-07-30T21:39:04.533653Z","end":"2026-07-30T21:39:04.729667Z","steps":["trace[693568193] 'agreement among raft nodes before linearized reading' (duration: 195.764067ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:42:44.577754Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14670} {"level":"info","ts":"2026-07-30T21:42:44.667462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14670,"took":"86.891139ms","hash":4234342282,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":15974400,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-30T21:42:44.667697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4234342282,"revision":14670,"compact-revision":12109} {"level":"info","ts":"2026-07-30T21:47:33.422348Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T21:47:33.428851Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-30T21:47:33.429044Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-30T21:47:44.583114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17066} {"level":"info","ts":"2026-07-30T21:47:44.656102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17066,"took":"70.437554ms","hash":29190278,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":17842176,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-30T21:47:44.656172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":29190278,"revision":17066,"compact-revision":14670} {"level":"info","ts":"2026-07-30T21:51:43.32781Z","caller":"traceutil/trace.go:171","msg":"trace[723070888] transaction","detail":"{read_only:false; response_revision:21228; number_of_response:1; }","duration":"266.764305ms","start":"2026-07-30T21:51:43.061004Z","end":"2026-07-30T21:51:43.327768Z","steps":["trace[723070888] 'process raft request' (duration: 220.368679ms)","trace[723070888] 'compare' (duration: 46.155601ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:51:43.327867Z","caller":"traceutil/trace.go:171","msg":"trace[1062752846] linearizableReadLoop","detail":"{readStateIndex:21876; appliedIndex:21875; }","duration":"266.447578ms","start":"2026-07-30T21:51:43.061377Z","end":"2026-07-30T21:51:43.327825Z","steps":["trace[1062752846] 'read index received' (duration: 220.017522ms)","trace[1062752846] 'applied index is now lower than readState.Index' (duration: 46.427486ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:51:43.454822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.064726ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T21:51:43.454994Z","caller":"traceutil/trace.go:171","msg":"trace[1333135860] linearizableReadLoop","detail":"{readStateIndex:21877; appliedIndex:21876; }","duration":"127.041516ms","start":"2026-07-30T21:51:43.327928Z","end":"2026-07-30T21:51:43.45497Z","steps":["trace[1333135860] 'read index received' (duration: 49.451µs)","trace[1333135860] 'applied index is now lower than readState.Index' (duration: 126.989685ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:51:43.45513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.314204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-30T21:51:43.455203Z","caller":"traceutil/trace.go:171","msg":"trace[1828053495] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21228; }","duration":"202.445127ms","start":"2026-07-30T21:51:43.252737Z","end":"2026-07-30T21:51:43.455182Z","steps":["trace[1828053495] 'agreement among raft nodes before linearized reading' (duration: 202.296974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:51:43.455236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.196285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-m26z7\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-07-30T21:51:43.455328Z","caller":"traceutil/trace.go:171","msg":"trace[2087721850] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-m26z7; range_end:; response_count:1; response_revision:21228; }","duration":"175.369828ms","start":"2026-07-30T21:51:43.279933Z","end":"2026-07-30T21:51:43.455303Z","steps":["trace[2087721850] 'agreement among raft nodes before linearized reading' (duration: 175.105303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:51:43.455833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.438571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:78 size:788820"} {"level":"info","ts":"2026-07-30T21:51:43.455928Z","caller":"traceutil/trace.go:171","msg":"trace[79816728] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:78; response_revision:21228; }","duration":"394.574424ms","start":"2026-07-30T21:51:43.06133Z","end":"2026-07-30T21:51:43.455904Z","steps":["trace[79816728] 'agreement among raft nodes before linearized reading' (duration: 266.643142ms)","trace[79816728] 'range keys from in-memory index tree' (duration: 127.033686ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:51:43.455975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:51:43.061312Z","time spent":"394.650235ms","remote":"127.0.0.1:53252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":78,"response size":788843,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-07-30T21:51:43.72227Z","caller":"traceutil/trace.go:171","msg":"trace[33091063] transaction","detail":"{read_only:false; response_revision:21229; number_of_response:1; }","duration":"233.647511ms","start":"2026-07-30T21:51:43.488491Z","end":"2026-07-30T21:51:43.722138Z","steps":["trace[33091063] 'process raft request' (duration: 233.432407ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:51:43.88662Z","caller":"traceutil/trace.go:171","msg":"trace[81153856] linearizableReadLoop","detail":"{readStateIndex:21879; appliedIndex:21877; }","duration":"332.985168ms","start":"2026-07-30T21:51:43.553606Z","end":"2026-07-30T21:51:43.886592Z","steps":["trace[81153856] 'read index received' (duration: 168.323364ms)","trace[81153856] 'applied index is now lower than readState.Index' (duration: 164.660794ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:51:43.886688Z","caller":"traceutil/trace.go:171","msg":"trace[1740649933] transaction","detail":"{read_only:false; response_revision:21230; number_of_response:1; }","duration":"398.124172ms","start":"2026-07-30T21:51:43.488547Z","end":"2026-07-30T21:51:43.886671Z","steps":["trace[1740649933] 'process raft request' (duration: 397.890728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:51:43.886784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:51:43.488531Z","time spent":"398.186313ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:51:43.886782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.167622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T21:51:43.886823Z","caller":"traceutil/trace.go:171","msg":"trace[1302093809] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:21230; }","duration":"333.250883ms","start":"2026-07-30T21:51:43.553558Z","end":"2026-07-30T21:51:43.886809Z","steps":["trace[1302093809] 'agreement among raft nodes before linearized reading' (duration: 333.169072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:51:43.886856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:51:43.553542Z","time spent":"333.305004ms","remote":"127.0.0.1:41416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:51:43.886927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.963972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T21:51:43.887029Z","caller":"traceutil/trace.go:171","msg":"trace[1837973906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21230; }","duration":"195.076335ms","start":"2026-07-30T21:51:43.691929Z","end":"2026-07-30T21:51:43.887005Z","steps":["trace[1837973906] 'agreement among raft nodes before linearized reading' (duration: 194.926812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:51:43.887089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.058902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-07-30T21:51:43.887215Z","caller":"traceutil/trace.go:171","msg":"trace[812890719] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:21230; }","duration":"276.221264ms","start":"2026-07-30T21:51:43.610956Z","end":"2026-07-30T21:51:43.887177Z","steps":["trace[812890719] 'agreement among raft nodes before linearized reading' (duration: 275.879938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:51:43.887353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.711676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T21:51:43.88751Z","caller":"traceutil/trace.go:171","msg":"trace[156683276] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:21230; }","duration":"195.90848ms","start":"2026-07-30T21:51:43.691552Z","end":"2026-07-30T21:51:43.88746Z","steps":["trace[156683276] 'agreement among raft nodes before linearized reading' (duration: 195.332119ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:52:44.592543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19479} {"level":"info","ts":"2026-07-30T21:52:44.672917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19479,"took":"76.759816ms","hash":298701676,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":17543168,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-30T21:52:44.672985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":298701676,"revision":19479,"compact-revision":17066} {"level":"info","ts":"2026-07-30T21:54:50.946121Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000005ae0.wal"} {"level":"info","ts":"2026-07-30T21:57:44.599226Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21703} {"level":"info","ts":"2026-07-30T21:57:44.680298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21703,"took":"78.050081ms","hash":1597729953,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":16203776,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-30T21:57:44.680367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1597729953,"revision":21703,"compact-revision":19479} {"level":"info","ts":"2026-07-30T22:02:44.605203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23681} {"level":"info","ts":"2026-07-30T22:02:44.68628Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23681,"took":"77.947208ms","hash":1772133350,"current-db-size-bytes":35176448,"current-db-size":"35 MB","current-db-size-in-use-bytes":15712256,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-30T22:02:44.686364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1772133350,"revision":23681,"compact-revision":21703}