{"level":"warn","ts":"2026-02-27T21:02:12.016812Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T21:02:12.016937Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41: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.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41: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-02-27T21:02:12.017066Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T21:02:12.017087Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-27T21:02:12.017419Z","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-02-27T21:02:12.018231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-27T21:02:12.018318Z","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.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41: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-02-27T21:02:12.02295Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.289361ms"} {"level":"info","ts":"2026-02-27T21:02:12.03108Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-27T21:02:12.03116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T21:02:12.031185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-27T21:02:12.031204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T21:02:12.031253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-27T21:02:12.031338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-27T21:02:12.037853Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T21:02:12.039496Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T21:02:12.040924Z","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-02-27T21:02:12.043628Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T21:02:12.043762Z","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-02-27T21:02:12.043883Z","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-02-27T21:02:12.043897Z","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-02-27T21:02:12.043876Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T21:02:12.045243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-27T21:02:12.046634Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-27T21:02:12.049643Z","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-02-27T21:02:12.049776Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-27T21:02:12.04997Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-27T21:02:12.050072Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T21:02:12.050124Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T21:02:13.032426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T21:02:13.032516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T21:02:13.032563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-27T21:02:13.032585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-27T21:02:13.032596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-27T21:02:13.032611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-27T21:02:13.032625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-27T21:02:13.034074Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T21:02:13.034139Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:02:13.034302Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T21:02:13.034397Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T21:02:13.036051Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:02:13.036186Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:02:13.036232Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:02:13.037762Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T21:02:13.037825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-27T21:02:13.048085Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T21:02:13.04812Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-27T21:02:28.766132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.959345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4372"} {"level":"info","ts":"2026-02-27T21:02:28.766245Z","caller":"traceutil/trace.go:171","msg":"trace[93822544] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:411; }","duration":"132.157641ms","start":"2026-02-27T21:02:28.634069Z","end":"2026-02-27T21:02:28.766226Z","steps":["trace[93822544] 'range keys from in-memory index tree' (duration: 131.76571ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:28.980323Z","caller":"traceutil/trace.go:171","msg":"trace[424596941] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"203.695741ms","start":"2026-02-27T21:02:28.776597Z","end":"2026-02-27T21:02:28.980293Z","steps":["trace[424596941] 'process raft request' (duration: 203.523816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:29.203288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.846815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-27T21:02:29.203437Z","caller":"traceutil/trace.go:171","msg":"trace[1972727769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"120.088523ms","start":"2026-02-27T21:02:29.083314Z","end":"2026-02-27T21:02:29.203403Z","steps":["trace[1972727769] 'range keys from in-memory index tree' (duration: 119.707961ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:51.159332Z","caller":"traceutil/trace.go:171","msg":"trace[1847963424] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"112.615787ms","start":"2026-02-27T21:02:51.046693Z","end":"2026-02-27T21:02:51.159308Z","steps":["trace[1847963424] 'process raft request' (duration: 112.448212ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:12.344615Z","caller":"traceutil/trace.go:171","msg":"trace[813338438] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"115.486996ms","start":"2026-02-27T21:03:12.229102Z","end":"2026-02-27T21:03:12.344589Z","steps":["trace[813338438] 'process raft request' (duration: 115.293481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:50.490173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.032397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:04:50.490291Z","caller":"traceutil/trace.go:171","msg":"trace[1073965085] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"127.845399ms","start":"2026-02-27T21:04:50.362426Z","end":"2026-02-27T21:04:50.490272Z","steps":["trace[1073965085] 'read index received' (duration: 39.092µs)","trace[1073965085] 'applied index is now lower than readState.Index' (duration: 127.804927ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:04:50.490354Z","caller":"traceutil/trace.go:171","msg":"trace[1350762400] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"332.063621ms","start":"2026-02-27T21:04:50.158249Z","end":"2026-02-27T21:04:50.490312Z","steps":["trace[1350762400] 'process raft request' (duration: 134.920315ms)","trace[1350762400] 'compare' (duration: 195.951614ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:50.490444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.013784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:04:50.490488Z","caller":"traceutil/trace.go:171","msg":"trace[1442621232] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1882; }","duration":"128.105006ms","start":"2026-02-27T21:04:50.362371Z","end":"2026-02-27T21:04:50.490476Z","steps":["trace[1442621232] 'agreement among raft nodes before linearized reading' (duration: 128.003313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:50.490563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:50.158219Z","time spent":"332.226576ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:05:45.855684Z","caller":"traceutil/trace.go:171","msg":"trace[1898489518] transaction","detail":"{read_only:false; response_revision:2325; number_of_response:1; }","duration":"132.566694ms","start":"2026-02-27T21:05:45.723084Z","end":"2026-02-27T21:05:45.855651Z","steps":["trace[1898489518] 'process raft request' (duration: 132.431421ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:05:45.878609Z","caller":"traceutil/trace.go:171","msg":"trace[1638094513] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"155.053923ms","start":"2026-02-27T21:05:45.723526Z","end":"2026-02-27T21:05:45.87858Z","steps":["trace[1638094513] 'process raft request' (duration: 152.76672ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:06:18.522621Z","caller":"traceutil/trace.go:171","msg":"trace[1972137586] transaction","detail":"{read_only:false; response_revision:2508; number_of_response:1; }","duration":"105.738241ms","start":"2026-02-27T21:06:18.416849Z","end":"2026-02-27T21:06:18.522587Z","steps":["trace[1972137586] 'process raft request' (duration: 105.573197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:06:19.058446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.330679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:06:19.058707Z","caller":"traceutil/trace.go:171","msg":"trace[691774507] linearizableReadLoop","detail":"{readStateIndex:2601; appliedIndex:2599; }","duration":"101.28019ms","start":"2026-02-27T21:06:18.957406Z","end":"2026-02-27T21:06:19.058686Z","steps":["trace[691774507] 'read index received' (duration: 83.843898ms)","trace[691774507] 'applied index is now lower than readState.Index' (duration: 17.434551ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:06:19.058784Z","caller":"traceutil/trace.go:171","msg":"trace[957254713] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"330.211562ms","start":"2026-02-27T21:06:18.728539Z","end":"2026-02-27T21:06:19.05875Z","steps":["trace[957254713] 'process raft request' (duration: 58.441001ms)","trace[957254713] 'compare' (duration: 270.96749ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:06:19.058894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:06:18.728518Z","time spent":"330.308155ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:06:19.058925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.519636ms","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-02-27T21:06:19.058961Z","caller":"traceutil/trace.go:171","msg":"trace[1938211839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2513; }","duration":"101.605519ms","start":"2026-02-27T21:06:18.957345Z","end":"2026-02-27T21:06:19.05895Z","steps":["trace[1938211839] 'agreement among raft nodes before linearized reading' (duration: 101.402863ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:06:19.05877Z","caller":"traceutil/trace.go:171","msg":"trace[1435149429] transaction","detail":"{read_only:false; response_revision:2513; number_of_response:1; }","duration":"227.831172ms","start":"2026-02-27T21:06:18.830917Z","end":"2026-02-27T21:06:19.058748Z","steps":["trace[1435149429] 'process raft request' (duration: 227.669108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:20.192834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.001422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-02-27T21:10:20.192969Z","caller":"traceutil/trace.go:171","msg":"trace[606368537] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4600; }","duration":"207.187997ms","start":"2026-02-27T21:10:19.985756Z","end":"2026-02-27T21:10:20.192944Z","steps":["trace[606368537] 'range keys from in-memory index tree' (duration: 206.669094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:20.192926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.718577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:10:20.193091Z","caller":"traceutil/trace.go:171","msg":"trace[433359639] linearizableReadLoop","detail":"{readStateIndex:4770; appliedIndex:4769; }","duration":"175.72153ms","start":"2026-02-27T21:10:20.017346Z","end":"2026-02-27T21:10:20.193067Z","steps":["trace[433359639] 'read index received' (duration: 36.728656ms)","trace[433359639] 'applied index is now lower than readState.Index' (duration: 138.991144ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:10:20.193138Z","caller":"traceutil/trace.go:171","msg":"trace[1452891018] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"199.970627ms","start":"2026-02-27T21:10:19.99314Z","end":"2026-02-27T21:10:20.193111Z","steps":["trace[1452891018] 'process raft request' (duration: 60.954452ms)","trace[1452891018] 'compare' (duration: 138.581474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:10:20.193265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.894974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:10:20.193324Z","caller":"traceutil/trace.go:171","msg":"trace[1506189107] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:4601; }","duration":"176.024028ms","start":"2026-02-27T21:10:20.017278Z","end":"2026-02-27T21:10:20.193302Z","steps":["trace[1506189107] 'agreement among raft nodes before linearized reading' (duration: 175.915115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:20.193356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.737722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:776962"} {"level":"info","ts":"2026-02-27T21:10:20.193553Z","caller":"traceutil/trace.go:171","msg":"trace[1442564636] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4600; }","duration":"204.966269ms","start":"2026-02-27T21:10:19.988558Z","end":"2026-02-27T21:10:20.193524Z","steps":["trace[1442564636] 'range keys from in-memory index tree' (duration: 204.050214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:20.193636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.712168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-27T21:10:20.193744Z","caller":"traceutil/trace.go:171","msg":"trace[559772634] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4601; }","duration":"162.856021ms","start":"2026-02-27T21:10:20.03086Z","end":"2026-02-27T21:10:20.193716Z","steps":["trace[559772634] 'agreement among raft nodes before linearized reading' (duration: 162.380109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:20.193934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.677351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-27T21:10:20.194008Z","caller":"traceutil/trace.go:171","msg":"trace[2089120368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4601; }","duration":"158.783144ms","start":"2026-02-27T21:10:20.035207Z","end":"2026-02-27T21:10:20.19399Z","steps":["trace[2089120368] 'agreement among raft nodes before linearized reading' (duration: 158.574479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:20.193915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.986415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T21:10:20.194282Z","caller":"traceutil/trace.go:171","msg":"trace[1725353331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4601; }","duration":"163.351474ms","start":"2026-02-27T21:10:20.030902Z","end":"2026-02-27T21:10:20.194254Z","steps":["trace[1725353331] 'agreement among raft nodes before linearized reading' (duration: 162.912613ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:38.821237Z","caller":"traceutil/trace.go:171","msg":"trace[1596731679] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"216.878643ms","start":"2026-02-27T21:10:38.60433Z","end":"2026-02-27T21:10:38.821208Z","steps":["trace[1596731679] 'process raft request' (duration: 216.602184ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:38.821261Z","caller":"traceutil/trace.go:171","msg":"trace[803054104] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"215.917207ms","start":"2026-02-27T21:10:38.605313Z","end":"2026-02-27T21:10:38.82123Z","steps":["trace[803054104] 'process raft request' (duration: 215.807224ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:38.821207Z","caller":"traceutil/trace.go:171","msg":"trace[436909572] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5149; }","duration":"214.85955ms","start":"2026-02-27T21:10:38.606312Z","end":"2026-02-27T21:10:38.821172Z","steps":["trace[436909572] 'read index received' (duration: 44.08252ms)","trace[436909572] 'applied index is now lower than readState.Index' (duration: 170.77575ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:10:38.821465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.135316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-02-27T21:10:38.821508Z","caller":"traceutil/trace.go:171","msg":"trace[2072808065] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4973; }","duration":"215.201618ms","start":"2026-02-27T21:10:38.606295Z","end":"2026-02-27T21:10:38.821497Z","steps":["trace[2072808065] 'agreement among raft nodes before linearized reading' (duration: 214.989572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:38.821644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.865474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana.189836b5891b3b59\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-02-27T21:10:38.821687Z","caller":"traceutil/trace.go:171","msg":"trace[1719603649] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana.189836b5891b3b59; range_end:; response_count:1; response_revision:4973; }","duration":"167.930195ms","start":"2026-02-27T21:10:38.653744Z","end":"2026-02-27T21:10:38.821674Z","steps":["trace[1719603649] 'agreement among raft nodes before linearized reading' (duration: 167.803011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:38.821803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.39018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:10:38.821836Z","caller":"traceutil/trace.go:171","msg":"trace[982102125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4973; }","duration":"108.420141ms","start":"2026-02-27T21:10:38.713404Z","end":"2026-02-27T21:10:38.821824Z","steps":["trace[982102125] 'agreement among raft nodes before linearized reading' (duration: 108.371209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:38.821964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.161934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d503bf8a-ff61-42e6-9494-99296e44b39a\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-02-27T21:10:38.821994Z","caller":"traceutil/trace.go:171","msg":"trace[226021699] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d503bf8a-ff61-42e6-9494-99296e44b39a; range_end:; response_count:1; response_revision:4973; }","duration":"127.232066ms","start":"2026-02-27T21:10:38.694753Z","end":"2026-02-27T21:10:38.821985Z","steps":["trace[226021699] 'agreement among raft nodes before linearized reading' (duration: 127.133953ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:39.023598Z","caller":"traceutil/trace.go:171","msg":"trace[281248846] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5152; }","duration":"196.984809ms","start":"2026-02-27T21:10:38.82659Z","end":"2026-02-27T21:10:39.023575Z","steps":["trace[281248846] 'read index received' (duration: 196.908107ms)","trace[281248846] 'applied index is now lower than readState.Index' (duration: 75.772µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:10:39.023627Z","caller":"traceutil/trace.go:171","msg":"trace[651684568] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"198.804036ms","start":"2026-02-27T21:10:38.824798Z","end":"2026-02-27T21:10:39.023602Z","steps":["trace[651684568] 'process raft request' (duration: 198.638582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:39.023982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.358399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80096"} {"level":"info","ts":"2026-02-27T21:10:39.024303Z","caller":"traceutil/trace.go:171","msg":"trace[1130923115] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:4974; }","duration":"197.728959ms","start":"2026-02-27T21:10:38.826555Z","end":"2026-02-27T21:10:39.024284Z","steps":["trace[1130923115] 'agreement among raft nodes before linearized reading' (duration: 197.134123ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:39.184143Z","caller":"traceutil/trace.go:171","msg":"trace[1170924521] linearizableReadLoop","detail":"{readStateIndex:5155; appliedIndex:5153; }","duration":"160.187712ms","start":"2026-02-27T21:10:39.023924Z","end":"2026-02-27T21:10:39.184112Z","steps":["trace[1170924521] 'read index received' (duration: 159.810102ms)","trace[1170924521] 'applied index is now lower than readState.Index' (duration: 376.3µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:10:39.184202Z","caller":"traceutil/trace.go:171","msg":"trace[521240524] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"175.717329ms","start":"2026-02-27T21:10:39.00846Z","end":"2026-02-27T21:10:39.184177Z","steps":["trace[521240524] 'process raft request' (duration: 175.580166ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:39.184193Z","caller":"traceutil/trace.go:171","msg":"trace[1067876839] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"353.413841ms","start":"2026-02-27T21:10:38.830729Z","end":"2026-02-27T21:10:39.184143Z","steps":["trace[1067876839] 'process raft request' (duration: 353.101413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:39.184412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:10:38.830715Z","time spent":"353.582455ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:10:39.184452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.042032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-27T21:10:39.184501Z","caller":"traceutil/trace.go:171","msg":"trace[945300557] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4976; }","duration":"175.140243ms","start":"2026-02-27T21:10:39.009345Z","end":"2026-02-27T21:10:39.184485Z","steps":["trace[945300557] 'agreement among raft nodes before linearized reading' (duration: 174.928468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:39.184508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.02323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-02-27T21:10:39.184553Z","caller":"traceutil/trace.go:171","msg":"trace[1538047865] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4976; }","duration":"356.105882ms","start":"2026-02-27T21:10:38.828436Z","end":"2026-02-27T21:10:39.184542Z","steps":["trace[1538047865] 'agreement among raft nodes before linearized reading' (duration: 355.918587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:39.184598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:10:38.828425Z","time spent":"356.164283ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1015,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"warn","ts":"2026-02-27T21:10:39.184715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.236666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus.189836b58950e28e\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-02-27T21:10:39.184821Z","caller":"traceutil/trace.go:171","msg":"trace[1557019152] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus.189836b58950e28e; range_end:; response_count:1; response_revision:4976; }","duration":"156.38363ms","start":"2026-02-27T21:10:39.028414Z","end":"2026-02-27T21:10:39.184797Z","steps":["trace[1557019152] 'agreement among raft nodes before linearized reading' (duration: 156.112823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:39.184897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.933441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:10:39.185085Z","caller":"traceutil/trace.go:171","msg":"trace[654443346] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:4976; }","duration":"212.208419ms","start":"2026-02-27T21:10:38.972785Z","end":"2026-02-27T21:10:39.184994Z","steps":["trace[654443346] 'agreement among raft nodes before linearized reading' (duration: 211.647954ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:12:13.065435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2781} {"level":"info","ts":"2026-02-27T21:12:13.129802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2781,"took":"61.966327ms","hash":3078662251,"current-db-size-bytes":41193472,"current-db-size":"41 MB","current-db-size-in-use-bytes":30801920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-02-27T21:12:13.129895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3078662251,"revision":2781,"compact-revision":-1} {"level":"info","ts":"2026-02-27T21:16:36.413049Z","caller":"traceutil/trace.go:171","msg":"trace[1660766958] linearizableReadLoop","detail":"{readStateIndex:7849; appliedIndex:7848; }","duration":"114.853437ms","start":"2026-02-27T21:16:36.298161Z","end":"2026-02-27T21:16:36.413015Z","steps":["trace[1660766958] 'read index received' (duration: 114.713674ms)","trace[1660766958] 'applied index is now lower than readState.Index' (duration: 138.083µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:16:36.413332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.855721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-27T21:16:36.413373Z","caller":"traceutil/trace.go:171","msg":"trace[1678313101] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7583; }","duration":"104.935362ms","start":"2026-02-27T21:16:36.308424Z","end":"2026-02-27T21:16:36.413359Z","steps":["trace[1678313101] 'agreement among raft nodes before linearized reading' (duration: 104.763678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:16:36.413431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.223497ms","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-02-27T21:16:36.413519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.53032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T21:16:36.413589Z","caller":"traceutil/trace.go:171","msg":"trace[816528711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7583; }","duration":"115.449853ms","start":"2026-02-27T21:16:36.298102Z","end":"2026-02-27T21:16:36.413552Z","steps":["trace[816528711] 'agreement among raft nodes before linearized reading' (duration: 115.040113ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:16:36.413622Z","caller":"traceutil/trace.go:171","msg":"trace[1153466380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7583; }","duration":"102.645462ms","start":"2026-02-27T21:16:36.310949Z","end":"2026-02-27T21:16:36.413594Z","steps":["trace[1153466380] 'agreement among raft nodes before linearized reading' (duration: 102.420957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:16:36.413679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.739695ms","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-02-27T21:16:36.413731Z","caller":"traceutil/trace.go:171","msg":"trace[768934352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7583; }","duration":"102.811357ms","start":"2026-02-27T21:16:36.310907Z","end":"2026-02-27T21:16:36.413718Z","steps":["trace[768934352] 'agreement among raft nodes before linearized reading' (duration: 102.676863ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:16:36.576883Z","caller":"traceutil/trace.go:171","msg":"trace[399896866] transaction","detail":"{read_only:false; response_revision:7584; number_of_response:1; }","duration":"162.65811ms","start":"2026-02-27T21:16:36.414183Z","end":"2026-02-27T21:16:36.576841Z","steps":["trace[399896866] 'process raft request' (duration: 117.096577ms)","trace[399896866] 'compare' (duration: 45.345717ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:16:36.647554Z","caller":"traceutil/trace.go:171","msg":"trace[856176210] transaction","detail":"{read_only:false; response_revision:7585; number_of_response:1; }","duration":"229.308054ms","start":"2026-02-27T21:16:36.418212Z","end":"2026-02-27T21:16:36.64752Z","steps":["trace[856176210] 'process raft request' (duration: 229.027215ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:16:36.647768Z","caller":"traceutil/trace.go:171","msg":"trace[908840451] transaction","detail":"{read_only:false; response_revision:7586; number_of_response:1; }","duration":"229.504159ms","start":"2026-02-27T21:16:36.418237Z","end":"2026-02-27T21:16:36.647741Z","steps":["trace[908840451] 'process raft request' (duration: 229.223071ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:16:36.647888Z","caller":"traceutil/trace.go:171","msg":"trace[1062216963] transaction","detail":"{read_only:false; response_revision:7587; number_of_response:1; }","duration":"229.280244ms","start":"2026-02-27T21:16:36.418572Z","end":"2026-02-27T21:16:36.647852Z","steps":["trace[1062216963] 'process raft request' (duration: 229.110959ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:16:36.648234Z","caller":"traceutil/trace.go:171","msg":"trace[416085938] transaction","detail":"{read_only:false; response_revision:7588; number_of_response:1; }","duration":"229.376756ms","start":"2026-02-27T21:16:36.418842Z","end":"2026-02-27T21:16:36.648219Z","steps":["trace[416085938] 'process raft request' (duration: 228.965265ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:17:13.07227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5769} {"level":"info","ts":"2026-02-27T21:17:13.162534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5769,"took":"87.92098ms","hash":1265311691,"current-db-size-bytes":43864064,"current-db-size":"44 MB","current-db-size-in-use-bytes":23048192,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-27T21:17:13.162606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1265311691,"revision":5769,"compact-revision":2781} {"level":"warn","ts":"2026-02-27T21:17:16.809194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.958764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:17:16.809343Z","caller":"traceutil/trace.go:171","msg":"trace[1099361057] linearizableReadLoop","detail":"{readStateIndex:8153; appliedIndex:8152; }","duration":"250.974231ms","start":"2026-02-27T21:17:16.558348Z","end":"2026-02-27T21:17:16.809322Z","steps":["trace[1099361057] 'read index received' (duration: 55.70009ms)","trace[1099361057] 'applied index is now lower than readState.Index' (duration: 195.272581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:17:16.809586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.236458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-27T21:17:16.809641Z","caller":"traceutil/trace.go:171","msg":"trace[762876901] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:7872; }","duration":"251.289249ms","start":"2026-02-27T21:17:16.558336Z","end":"2026-02-27T21:17:16.809625Z","steps":["trace[762876901] 'agreement among raft nodes before linearized reading' (duration: 251.084094ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:17:16.809867Z","caller":"traceutil/trace.go:171","msg":"trace[407673988] transaction","detail":"{read_only:false; response_revision:7872; number_of_response:1; }","duration":"252.543142ms","start":"2026-02-27T21:17:16.557305Z","end":"2026-02-27T21:17:16.809848Z","steps":["trace[407673988] 'process raft request' (duration: 56.814618ms)","trace[407673988] 'compare' (duration: 194.416069ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:17:17.431612Z","caller":"traceutil/trace.go:171","msg":"trace[1611561128] transaction","detail":"{read_only:false; response_revision:7883; number_of_response:1; }","duration":"135.78045ms","start":"2026-02-27T21:17:17.295801Z","end":"2026-02-27T21:17:17.431581Z","steps":["trace[1611561128] 'process raft request' (duration: 135.702998ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:17:17.431628Z","caller":"traceutil/trace.go:171","msg":"trace[88300832] transaction","detail":"{read_only:false; response_revision:7882; number_of_response:1; }","duration":"140.892537ms","start":"2026-02-27T21:17:17.290713Z","end":"2026-02-27T21:17:17.431605Z","steps":["trace[88300832] 'process raft request' (duration: 84.258843ms)","trace[88300832] 'compare' (duration: 56.188202ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:20:56.301372Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"17dd22a2661aae79","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-27T21:20:56.307049Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-27T21:20:56.307141Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-27T21:22:13.079185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7848} {"level":"info","ts":"2026-02-27T21:22:13.152687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7848,"took":"69.512364ms","hash":2969033817,"current-db-size-bytes":43864064,"current-db-size":"44 MB","current-db-size-in-use-bytes":28241920,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-27T21:22:13.152761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2969033817,"revision":7848,"compact-revision":5769} {"level":"info","ts":"2026-02-27T21:27:13.085761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10365} {"level":"info","ts":"2026-02-27T21:27:13.17088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10365,"took":"81.635916ms","hash":403779289,"current-db-size-bytes":43864064,"current-db-size":"44 MB","current-db-size-in-use-bytes":24383488,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-27T21:27:13.170952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":403779289,"revision":10365,"compact-revision":7848} {"level":"info","ts":"2026-02-27T21:28:29.084209Z","caller":"traceutil/trace.go:171","msg":"trace[890083013] linearizableReadLoop","detail":"{readStateIndex:13145; appliedIndex:13144; }","duration":"153.758203ms","start":"2026-02-27T21:28:28.930417Z","end":"2026-02-27T21:28:29.084176Z","steps":["trace[890083013] 'read index received' (duration: 153.491045ms)","trace[890083013] 'applied index is now lower than readState.Index' (duration: 265.358µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:28:29.084536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.068901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w9w6k\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-27T21:28:29.084583Z","caller":"traceutil/trace.go:171","msg":"trace[1809946293] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w9w6k; range_end:; response_count:1; response_revision:12700; }","duration":"154.208745ms","start":"2026-02-27T21:28:28.930363Z","end":"2026-02-27T21:28:29.084572Z","steps":["trace[1809946293] 'agreement among raft nodes before linearized reading' (duration: 153.955609ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:28:29.084532Z","caller":"traceutil/trace.go:171","msg":"trace[1760877454] transaction","detail":"{read_only:false; response_revision:12700; number_of_response:1; }","duration":"184.20656ms","start":"2026-02-27T21:28:28.900243Z","end":"2026-02-27T21:28:29.08445Z","steps":["trace[1760877454] 'process raft request' (duration: 183.676635ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:28:42.626058Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000033fe.wal"} {"level":"info","ts":"2026-02-27T21:28:50.181255Z","caller":"traceutil/trace.go:171","msg":"trace[1657166667] transaction","detail":"{read_only:false; response_revision:12994; number_of_response:1; }","duration":"134.056454ms","start":"2026-02-27T21:28:50.047162Z","end":"2026-02-27T21:28:50.181218Z","steps":["trace[1657166667] 'process raft request' (duration: 133.877179ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:30:15.580994Z","caller":"traceutil/trace.go:171","msg":"trace[826896518] transaction","detail":"{read_only:false; response_revision:13755; number_of_response:1; }","duration":"379.30022ms","start":"2026-02-27T21:30:15.201668Z","end":"2026-02-27T21:30:15.580968Z","steps":["trace[826896518] 'process raft request' (duration: 379.163627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:30:15.581208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:30:15.20166Z","time spent":"379.426633ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:30:15.581898Z","caller":"traceutil/trace.go:171","msg":"trace[115582932] linearizableReadLoop","detail":"{readStateIndex:14235; appliedIndex:14235; }","duration":"293.806098ms","start":"2026-02-27T21:30:15.288076Z","end":"2026-02-27T21:30:15.581882Z","steps":["trace[115582932] 'read index received' (duration: 293.800168ms)","trace[115582932] 'applied index is now lower than readState.Index' (duration: 4.21µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:30:15.582173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.706366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:30:15.582219Z","caller":"traceutil/trace.go:171","msg":"trace[1944367396] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:13755; }","duration":"293.800848ms","start":"2026-02-27T21:30:15.288406Z","end":"2026-02-27T21:30:15.582207Z","steps":["trace[1944367396] 'agreement among raft nodes before linearized reading' (duration: 293.589382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:30:15.582365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.26432ms","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-02-27T21:30:15.582455Z","caller":"traceutil/trace.go:171","msg":"trace[1673210291] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13755; }","duration":"294.398094ms","start":"2026-02-27T21:30:15.288035Z","end":"2026-02-27T21:30:15.582434Z","steps":["trace[1673210291] 'agreement among raft nodes before linearized reading' (duration: 294.273751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:30:15.582728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.687704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-02-27T21:30:15.582777Z","caller":"traceutil/trace.go:171","msg":"trace[297243398] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13755; }","duration":"287.779737ms","start":"2026-02-27T21:30:15.294985Z","end":"2026-02-27T21:30:15.582764Z","steps":["trace[297243398] 'agreement among raft nodes before linearized reading' (duration: 287.619062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:30:15.582984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.938388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-02-27T21:30:15.58303Z","caller":"traceutil/trace.go:171","msg":"trace[1439854384] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:13755; }","duration":"292.001339ms","start":"2026-02-27T21:30:15.291017Z","end":"2026-02-27T21:30:15.583019Z","steps":["trace[1439854384] 'agreement among raft nodes before linearized reading' (duration: 291.838345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:30:15.583253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.718162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-02-27T21:30:15.583287Z","caller":"traceutil/trace.go:171","msg":"trace[1407669737] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13755; }","duration":"291.773963ms","start":"2026-02-27T21:30:15.291504Z","end":"2026-02-27T21:30:15.583278Z","steps":["trace[1407669737] 'agreement among raft nodes before linearized reading' (duration: 291.678171ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:32:13.094137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12250} {"level":"info","ts":"2026-02-27T21:32:13.1744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12250,"took":"76.576594ms","hash":3919855458,"current-db-size-bytes":43864064,"current-db-size":"44 MB","current-db-size-in-use-bytes":27906048,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-27T21:32:13.174464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3919855458,"revision":12250,"compact-revision":10365} {"level":"info","ts":"2026-02-27T21:37:13.103153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14537} {"level":"info","ts":"2026-02-27T21:37:13.206125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14537,"took":"97.593988ms","hash":1962863868,"current-db-size-bytes":43864064,"current-db-size":"44 MB","current-db-size-in-use-bytes":23281664,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-27T21:37:13.206213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1962863868,"revision":14537,"compact-revision":12250}