{"level":"warn","ts":"2026-05-05T13:48:12.222263Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T13:48:12.22234Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.65:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.65:2380","--initial-cluster=instance=https://199.19.213.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.65: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-05-05T13:48:12.222417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T13:48:12.222427Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-05-05T13:48:12.222445Z","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-05-05T13:48:12.223058Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"]} {"level":"info","ts":"2026-05-05T13:48:12.223128Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.65: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-05-05T13:48:12.227543Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.207233ms"} {"level":"info","ts":"2026-05-05T13:48:12.234346Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a071a454a8148542","cluster-id":"aa233e43aeaf7fe5"} {"level":"info","ts":"2026-05-05T13:48:12.234413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T13:48:12.234432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 0"} {"level":"info","ts":"2026-05-05T13:48:12.234441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a071a454a8148542 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T13:48:12.234449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 1"} {"level":"info","ts":"2026-05-05T13:48:12.234497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"warn","ts":"2026-05-05T13:48:12.241234Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T13:48:12.242683Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T13:48:12.243778Z","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-05-05T13:48:12.246045Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a071a454a8148542","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T13:48:12.246304Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a071a454a8148542","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T13:48:12.246433Z","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-05-05T13:48:12.246622Z","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-05-05T13:48:12.246651Z","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-05-05T13:48:12.24767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"info","ts":"2026-05-05T13:48:12.24912Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","added-peer-id":"a071a454a8148542","added-peer-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-05-05T13:48:12.25173Z","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-05-05T13:48:12.251924Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-05-05T13:48:12.251996Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-05-05T13:48:12.252186Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a071a454a8148542","initial-advertise-peer-urls":["https://199.19.213.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T13:48:12.252268Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T13:48:12.535096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T13:48:12.535215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T13:48:12.535244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgPreVoteResp from a071a454a8148542 at term 1"} {"level":"info","ts":"2026-05-05T13:48:12.535266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became candidate at term 2"} {"level":"info","ts":"2026-05-05T13:48:12.535279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgVoteResp from a071a454a8148542 at term 2"} {"level":"info","ts":"2026-05-05T13:48:12.535297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became leader at term 2"} {"level":"info","ts":"2026-05-05T13:48:12.535351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a071a454a8148542 elected leader a071a454a8148542 at term 2"} {"level":"info","ts":"2026-05-05T13:48:12.536177Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:48:12.537279Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a071a454a8148542","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.65:2379]}","request-path":"/0/members/a071a454a8148542/attributes","cluster-id":"aa233e43aeaf7fe5","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T13:48:12.537417Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:48:12.537509Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:48:12.537746Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:48:12.53791Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:48:12.537964Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:48:12.53828Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T13:48:12.538358Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T13:48:12.539571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.65:2379"} {"level":"info","ts":"2026-05-05T13:48:12.540648Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-05T13:48:29.925996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.25616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:48:29.926333Z","caller":"traceutil/trace.go:171","msg":"trace[113653887] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"335.684555ms","start":"2026-05-05T13:48:29.590627Z","end":"2026-05-05T13:48:29.926312Z","steps":["trace[113653887] 'read index received' (duration: 194.332044ms)","trace[113653887] 'applied index is now lower than readState.Index' (duration: 141.350851ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:48:29.926518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.609035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-05-05T13:48:29.926503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.844239ms","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-05-05T13:48:29.926374Z","caller":"traceutil/trace.go:171","msg":"trace[7443101] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"336.933729ms","start":"2026-05-05T13:48:29.589406Z","end":"2026-05-05T13:48:29.926339Z","steps":["trace[7443101] 'process raft request' (duration: 195.589408ms)","trace[7443101] 'compare' (duration: 140.090887ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:48:29.926573Z","caller":"traceutil/trace.go:171","msg":"trace[33682566] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:370; }","duration":"334.777659ms","start":"2026-05-05T13:48:29.591781Z","end":"2026-05-05T13:48:29.926559Z","steps":["trace[33682566] 'agreement among raft nodes before linearized reading' (duration: 334.677167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:29.926595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.240989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:48:29.926614Z","caller":"traceutil/trace.go:171","msg":"trace[738126991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"335.973771ms","start":"2026-05-05T13:48:29.5906Z","end":"2026-05-05T13:48:29.926574Z","steps":["trace[738126991] 'agreement among raft nodes before linearized reading' (duration: 335.819248ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:29.926656Z","caller":"traceutil/trace.go:171","msg":"trace[654100484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"275.321151ms","start":"2026-05-05T13:48:29.651311Z","end":"2026-05-05T13:48:29.926633Z","steps":["trace[654100484] 'agreement among raft nodes before linearized reading' (duration: 275.238109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:29.926625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:29.591768Z","time spent":"334.84735ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4080,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-05-05T13:48:29.926682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:29.590591Z","time spent":"336.076884ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T13:48:29.9267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:29.589377Z","time spent":"337.234317ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:48:30.136888Z","caller":"traceutil/trace.go:171","msg":"trace[2101968396] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"182.130596ms","start":"2026-05-05T13:48:29.954733Z","end":"2026-05-05T13:48:30.136864Z","steps":["trace[2101968396] 'process raft request' (duration: 181.891372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:49.647277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.866802ms","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-05-05T13:48:49.647403Z","caller":"traceutil/trace.go:171","msg":"trace[391184953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"327.031926ms","start":"2026-05-05T13:48:49.32035Z","end":"2026-05-05T13:48:49.647382Z","steps":["trace[391184953] 'range keys from in-memory index tree' (duration: 326.769231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:49.647294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.958802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-05-05T13:48:49.647443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:49.320333Z","time spent":"327.100307ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T13:48:49.647489Z","caller":"traceutil/trace.go:171","msg":"trace[795455664] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"304.171261ms","start":"2026-05-05T13:48:49.3433Z","end":"2026-05-05T13:48:49.647471Z","steps":["trace[795455664] 'process raft request' (duration: 103.966564ms)","trace[795455664] 'compare' (duration: 199.85415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:48:49.647546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:49.343273Z","time spent":"304.249113ms","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T13:49:05.328448Z","caller":"traceutil/trace.go:171","msg":"trace[1776885082] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"216.24717ms","start":"2026-05-05T13:49:05.112173Z","end":"2026-05-05T13:49:05.32842Z","steps":["trace[1776885082] 'process raft request' (duration: 201.464427ms)","trace[1776885082] 'compare' (duration: 14.385326ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:49:30.650356Z","caller":"traceutil/trace.go:171","msg":"trace[403721228] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"152.708483ms","start":"2026-05-05T13:49:30.497621Z","end":"2026-05-05T13:49:30.65033Z","steps":["trace[403721228] 'process raft request' (duration: 152.512549ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:05.484011Z","caller":"traceutil/trace.go:171","msg":"trace[214763916] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"114.330324ms","start":"2026-05-05T13:51:05.369657Z","end":"2026-05-05T13:51:05.483987Z","steps":["trace[214763916] 'process raft request' (duration: 114.192471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:53.214948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.059676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:51:53.215264Z","caller":"traceutil/trace.go:171","msg":"trace[1655794110] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2539; }","duration":"136.432613ms","start":"2026-05-05T13:51:53.07881Z","end":"2026-05-05T13:51:53.215243Z","steps":["trace[1655794110] 'count revisions from in-memory index tree' (duration: 135.937743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:52:27.536536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.704832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:52:27.536699Z","caller":"traceutil/trace.go:171","msg":"trace[2048135436] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"209.397481ms","start":"2026-05-05T13:52:27.327267Z","end":"2026-05-05T13:52:27.536664Z","steps":["trace[2048135436] 'process raft request' (duration: 72.490175ms)","trace[2048135436] 'compare' (duration: 136.58436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:52:27.754261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.611383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:52:27.754467Z","caller":"traceutil/trace.go:171","msg":"trace[1343151601] linearizableReadLoop","detail":"{readStateIndex:2855; appliedIndex:2853; }","duration":"104.210401ms","start":"2026-05-05T13:52:27.650234Z","end":"2026-05-05T13:52:27.754445Z","steps":["trace[1343151601] 'read index received' (duration: 54.279807ms)","trace[1343151601] 'applied index is now lower than readState.Index' (duration: 49.929354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:52:27.754582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.341423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:52:27.75446Z","caller":"traceutil/trace.go:171","msg":"trace[153435435] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"213.111272ms","start":"2026-05-05T13:52:27.54132Z","end":"2026-05-05T13:52:27.754431Z","steps":["trace[153435435] 'process raft request' (duration: 100.233904ms)","trace[153435435] 'compare' (duration: 112.4632ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:52:27.754618Z","caller":"traceutil/trace.go:171","msg":"trace[1616241965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2750; }","duration":"104.409974ms","start":"2026-05-05T13:52:27.650197Z","end":"2026-05-05T13:52:27.754607Z","steps":["trace[1616241965] 'agreement among raft nodes before linearized reading' (duration: 104.320422ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:52:27.754699Z","caller":"traceutil/trace.go:171","msg":"trace[1950940758] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"213.019ms","start":"2026-05-05T13:52:27.541573Z","end":"2026-05-05T13:52:27.754592Z","steps":["trace[1950940758] 'process raft request' (duration: 212.797586ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:56:20.272588Z","caller":"traceutil/trace.go:171","msg":"trace[1450851154] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"148.554526ms","start":"2026-05-05T13:56:20.123997Z","end":"2026-05-05T13:56:20.272552Z","steps":["trace[1450851154] 'process raft request' (duration: 94.150384ms)","trace[1450851154] 'compare' (duration: 54.181147ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:56:24.67971Z","caller":"traceutil/trace.go:171","msg":"trace[1284054972] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5118; }","duration":"240.287724ms","start":"2026-05-05T13:56:24.439394Z","end":"2026-05-05T13:56:24.679681Z","steps":["trace[1284054972] 'read index received' (duration: 240.101711ms)","trace[1284054972] 'applied index is now lower than readState.Index' (duration: 184.853µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:56:24.679768Z","caller":"traceutil/trace.go:171","msg":"trace[588552911] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"249.840334ms","start":"2026-05-05T13:56:24.429898Z","end":"2026-05-05T13:56:24.679739Z","steps":["trace[588552911] 'process raft request' (duration: 249.561529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:24.680197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.749343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T13:56:24.680346Z","caller":"traceutil/trace.go:171","msg":"trace[2087006208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4934; }","duration":"240.947656ms","start":"2026-05-05T13:56:24.439361Z","end":"2026-05-05T13:56:24.680309Z","steps":["trace[2087006208] 'agreement among raft nodes before linearized reading' (duration: 240.496568ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:56:24.741219Z","caller":"traceutil/trace.go:171","msg":"trace[1414498063] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"143.866377ms","start":"2026-05-05T13:56:24.597309Z","end":"2026-05-05T13:56:24.741175Z","steps":["trace[1414498063] 'process raft request' (duration: 127.379494ms)","trace[1414498063] 'compare' (duration: 16.209888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:56:24.741594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.655503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:778238"} {"level":"info","ts":"2026-05-05T13:56:24.741644Z","caller":"traceutil/trace.go:171","msg":"trace[366193057] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4936; }","duration":"248.753484ms","start":"2026-05-05T13:56:24.492877Z","end":"2026-05-05T13:56:24.741631Z","steps":["trace[366193057] 'agreement among raft nodes before linearized reading' (duration: 248.318536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:24.74165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.545022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9feeafb4-d983-42c0-b912-1409d1314e41\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-05-05T13:56:24.741788Z","caller":"traceutil/trace.go:171","msg":"trace[810022461] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9feeafb4-d983-42c0-b912-1409d1314e41; range_end:; response_count:1; response_revision:4936; }","duration":"152.768346ms","start":"2026-05-05T13:56:24.588991Z","end":"2026-05-05T13:56:24.74176Z","steps":["trace[810022461] 'agreement among raft nodes before linearized reading' (duration: 152.325168ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:56:25.045612Z","caller":"traceutil/trace.go:171","msg":"trace[845693171] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"107.12552ms","start":"2026-05-05T13:56:24.938461Z","end":"2026-05-05T13:56:25.045586Z","steps":["trace[845693171] 'process raft request' (duration: 81.522435ms)","trace[845693171] 'compare' (duration: 25.454832ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:56:25.05178Z","caller":"traceutil/trace.go:171","msg":"trace[464911761] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"112.440102ms","start":"2026-05-05T13:56:24.939306Z","end":"2026-05-05T13:56:25.051746Z","steps":["trace[464911761] 'process raft request' (duration: 112.35285ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:56:31.714766Z","caller":"traceutil/trace.go:171","msg":"trace[1605729474] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"102.23777ms","start":"2026-05-05T13:56:31.612489Z","end":"2026-05-05T13:56:31.714727Z","steps":["trace[1605729474] 'process raft request' (duration: 102.028706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:34.786879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.578945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:56:34.786961Z","caller":"traceutil/trace.go:171","msg":"trace[1218540795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5139; }","duration":"135.706947ms","start":"2026-05-05T13:56:34.651236Z","end":"2026-05-05T13:56:34.786943Z","steps":["trace[1218540795] 'range keys from in-memory index tree' (duration: 135.513473ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:56:43.025165Z","caller":"traceutil/trace.go:171","msg":"trace[1219812698] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"138.350798ms","start":"2026-05-05T13:56:42.886781Z","end":"2026-05-05T13:56:43.025132Z","steps":["trace[1219812698] 'process raft request' (duration: 105.550655ms)","trace[1219812698] 'compare' (duration: 32.699571ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:56:44.540595Z","caller":"traceutil/trace.go:171","msg":"trace[1959018591] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"118.750565ms","start":"2026-05-05T13:56:44.421821Z","end":"2026-05-05T13:56:44.540572Z","steps":["trace[1959018591] 'process raft request' (duration: 118.605482ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:56:52.859221Z","caller":"traceutil/trace.go:171","msg":"trace[1942689036] linearizableReadLoop","detail":"{readStateIndex:5658; appliedIndex:5657; }","duration":"103.547257ms","start":"2026-05-05T13:56:52.755655Z","end":"2026-05-05T13:56:52.859202Z","steps":["trace[1942689036] 'read index received' (duration: 103.291842ms)","trace[1942689036] 'applied index is now lower than readState.Index' (duration: 254.765µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:56:52.859291Z","caller":"traceutil/trace.go:171","msg":"trace[1944494485] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"104.565476ms","start":"2026-05-05T13:56:52.754692Z","end":"2026-05-05T13:56:52.859258Z","steps":["trace[1944494485] 'process raft request' (duration: 104.316461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:52.859395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.71971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-281e8f2e-bbb0-45e7-8103-29f45719c44b\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-05T13:56:52.859434Z","caller":"traceutil/trace.go:171","msg":"trace[861951016] range","detail":"{range_begin:/registry/persistentvolumes/pvc-281e8f2e-bbb0-45e7-8103-29f45719c44b; range_end:; response_count:1; response_revision:5460; }","duration":"103.796831ms","start":"2026-05-05T13:56:52.755626Z","end":"2026-05-05T13:56:52.859423Z","steps":["trace[861951016] 'agreement among raft nodes before linearized reading' (duration: 103.662828ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:58:12.802673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2996} {"level":"info","ts":"2026-05-05T13:58:12.881237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2996,"took":"76.845638ms","hash":2290273704,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":33980416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T13:58:12.881303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2290273704,"revision":2996,"compact-revision":-1} {"level":"warn","ts":"2026-05-05T14:03:03.545546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.633714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gwq96\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-05-05T14:03:03.545664Z","caller":"traceutil/trace.go:171","msg":"trace[506038303] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gwq96; range_end:; response_count:1; response_revision:8224; }","duration":"308.802378ms","start":"2026-05-05T14:03:03.236836Z","end":"2026-05-05T14:03:03.545638Z","steps":["trace[506038303] 'range keys from in-memory index tree' (duration: 308.461391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:03.546077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:03:03.236809Z","time spent":"308.910039ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-gwq96\" "} {"level":"warn","ts":"2026-05-05T14:03:03.546645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.974962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:03:03.546809Z","caller":"traceutil/trace.go:171","msg":"trace[1175313677] linearizableReadLoop","detail":"{readStateIndex:8514; appliedIndex:8513; }","duration":"281.95069ms","start":"2026-05-05T14:03:03.264844Z","end":"2026-05-05T14:03:03.546795Z","steps":["trace[1175313677] 'read index received' (duration: 156.55104ms)","trace[1175313677] 'applied index is now lower than readState.Index' (duration: 125.39804ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:03:03.546835Z","caller":"traceutil/trace.go:171","msg":"trace[1702614730] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"307.002173ms","start":"2026-05-05T14:03:03.239771Z","end":"2026-05-05T14:03:03.546774Z","steps":["trace[1702614730] 'process raft request' (duration: 181.587802ms)","trace[1702614730] 'compare' (duration: 123.899882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:03:03.546965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.103472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T14:03:03.547033Z","caller":"traceutil/trace.go:171","msg":"trace[2062828528] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8225; }","duration":"282.249725ms","start":"2026-05-05T14:03:03.264766Z","end":"2026-05-05T14:03:03.547015Z","steps":["trace[2062828528] 'agreement among raft nodes before linearized reading' (duration: 282.093212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:03.54709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:03:03.239738Z","time spent":"307.276318ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:03:03.547546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.476023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:03:03.54763Z","caller":"traceutil/trace.go:171","msg":"trace[217929108] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8225; }","duration":"215.640216ms","start":"2026-05-05T14:03:03.331978Z","end":"2026-05-05T14:03:03.547618Z","steps":["trace[217929108] 'agreement among raft nodes before linearized reading' (duration: 215.469243ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:03.975425Z","caller":"traceutil/trace.go:171","msg":"trace[1314229135] linearizableReadLoop","detail":"{readStateIndex:8515; appliedIndex:8514; }","duration":"422.238531ms","start":"2026-05-05T14:03:03.553157Z","end":"2026-05-05T14:03:03.975396Z","steps":["trace[1314229135] 'read index received' (duration: 358.848053ms)","trace[1314229135] 'applied index is now lower than readState.Index' (duration: 63.389228ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:03:03.975797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.995455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:03:03.97588Z","caller":"traceutil/trace.go:171","msg":"trace[23353007] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:8226; }","duration":"259.132398ms","start":"2026-05-05T14:03:03.716734Z","end":"2026-05-05T14:03:03.975867Z","steps":["trace[23353007] 'agreement among raft nodes before linearized reading' (duration: 258.996826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:03.975903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.71267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19406"} {"level":"info","ts":"2026-05-05T14:03:03.975944Z","caller":"traceutil/trace.go:171","msg":"trace[229349868] transaction","detail":"{read_only:false; response_revision:8226; number_of_response:1; }","duration":"423.507665ms","start":"2026-05-05T14:03:03.55219Z","end":"2026-05-05T14:03:03.975698Z","steps":["trace[229349868] 'process raft request' (duration: 359.906552ms)","trace[229349868] 'compare' (duration: 62.89441ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:03:03.976013Z","caller":"traceutil/trace.go:171","msg":"trace[1802861585] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8226; }","duration":"422.861542ms","start":"2026-05-05T14:03:03.553131Z","end":"2026-05-05T14:03:03.975993Z","steps":["trace[1802861585] 'agreement among raft nodes before linearized reading' (duration: 422.551667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:03.976085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:03:03.552165Z","time spent":"423.844741ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:03:03.976094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:03:03.55312Z","time spent":"422.956475ms","remote":"127.0.0.1:38872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19430,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-05T14:03:05.445994Z","caller":"traceutil/trace.go:171","msg":"trace[1641674766] linearizableReadLoop","detail":"{readStateIndex:8522; appliedIndex:8521; }","duration":"292.242393ms","start":"2026-05-05T14:03:05.153725Z","end":"2026-05-05T14:03:05.445967Z","steps":["trace[1641674766] 'read index received' (duration: 292.022289ms)","trace[1641674766] 'applied index is now lower than readState.Index' (duration: 219.114µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:03:05.446092Z","caller":"traceutil/trace.go:171","msg":"trace[194348550] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"297.737398ms","start":"2026-05-05T14:03:05.148314Z","end":"2026-05-05T14:03:05.446052Z","steps":["trace[194348550] 'process raft request' (duration: 297.483613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:05.446266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.875748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T14:03:05.446324Z","caller":"traceutil/trace.go:171","msg":"trace[552884661] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:8233; }","duration":"109.027811ms","start":"2026-05-05T14:03:05.337274Z","end":"2026-05-05T14:03:05.446302Z","steps":["trace[552884661] 'agreement among raft nodes before linearized reading' (duration: 108.854448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:05.44639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.626131ms","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-05-05T14:03:05.446496Z","caller":"traceutil/trace.go:171","msg":"trace[594840843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8233; }","duration":"292.779654ms","start":"2026-05-05T14:03:05.153693Z","end":"2026-05-05T14:03:05.446473Z","steps":["trace[594840843] 'agreement among raft nodes before linearized reading' (duration: 292.447427ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:05.556478Z","caller":"traceutil/trace.go:171","msg":"trace[1751540703] transaction","detail":"{read_only:false; response_revision:8234; number_of_response:1; }","duration":"104.356802ms","start":"2026-05-05T14:03:05.452065Z","end":"2026-05-05T14:03:05.556422Z","steps":["trace[1751540703] 'process raft request' (duration: 49.491646ms)","trace[1751540703] 'compare' (duration: 54.576131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:03:06.185344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.765993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:03:06.185495Z","caller":"traceutil/trace.go:171","msg":"trace[1814445586] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"159.962523ms","start":"2026-05-05T14:03:06.025475Z","end":"2026-05-05T14:03:06.185438Z","steps":["trace[1814445586] 'process raft request' (duration: 39.015687ms)","trace[1814445586] 'compare' (duration: 120.581309ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:03:12.808447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6111} {"level":"info","ts":"2026-05-05T14:03:12.905151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6111,"took":"94.461426ms","hash":3447948646,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":22982656,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T14:03:12.90522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3447948646,"revision":6111,"compact-revision":2996} {"level":"info","ts":"2026-05-05T14:03:32.702833Z","caller":"traceutil/trace.go:171","msg":"trace[1187993014] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"266.12209ms","start":"2026-05-05T14:03:32.436674Z","end":"2026-05-05T14:03:32.702796Z","steps":["trace[1187993014] 'process raft request' (duration: 266.005678ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:32.872474Z","caller":"traceutil/trace.go:171","msg":"trace[304436853] linearizableReadLoop","detail":"{readStateIndex:8732; appliedIndex:8730; }","duration":"221.532697ms","start":"2026-05-05T14:03:32.650873Z","end":"2026-05-05T14:03:32.872405Z","steps":["trace[304436853] 'read index received' (duration: 51.739069ms)","trace[304436853] 'applied index is now lower than readState.Index' (duration: 169.792778ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:03:32.872588Z","caller":"traceutil/trace.go:171","msg":"trace[613254870] transaction","detail":"{read_only:false; response_revision:8434; number_of_response:1; }","duration":"435.656194ms","start":"2026-05-05T14:03:32.436911Z","end":"2026-05-05T14:03:32.872567Z","steps":["trace[613254870] 'process raft request' (duration: 425.508123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:32.872737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:03:32.436894Z","time spent":"435.772076ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:03:32.87263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.743731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:03:32.872852Z","caller":"traceutil/trace.go:171","msg":"trace[1857661042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8434; }","duration":"222.006787ms","start":"2026-05-05T14:03:32.650836Z","end":"2026-05-05T14:03:32.872843Z","steps":["trace[1857661042] 'agreement among raft nodes before linearized reading' (duration: 221.737591ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:35.16071Z","caller":"traceutil/trace.go:171","msg":"trace[1614349710] linearizableReadLoop","detail":"{readStateIndex:8745; appliedIndex:8744; }","duration":"102.840243ms","start":"2026-05-05T14:03:35.057846Z","end":"2026-05-05T14:03:35.160686Z","steps":["trace[1614349710] 'read index received' (duration: 102.541018ms)","trace[1614349710] 'applied index is now lower than readState.Index' (duration: 298.505µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:03:35.160792Z","caller":"traceutil/trace.go:171","msg":"trace[1369270956] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"118.960338ms","start":"2026-05-05T14:03:35.041786Z","end":"2026-05-05T14:03:35.160746Z","steps":["trace[1369270956] 'process raft request' (duration: 118.700653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:35.16124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.367293ms","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-05-05T14:03:35.161346Z","caller":"traceutil/trace.go:171","msg":"trace[1204974806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8447; }","duration":"103.541197ms","start":"2026-05-05T14:03:35.057779Z","end":"2026-05-05T14:03:35.16132Z","steps":["trace[1204974806] 'agreement among raft nodes before linearized reading' (duration: 103.015887ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:46.158581Z","caller":"traceutil/trace.go:171","msg":"trace[335677849] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"186.441404ms","start":"2026-05-05T14:03:45.972114Z","end":"2026-05-05T14:03:46.158555Z","steps":["trace[335677849] 'process raft request' (duration: 186.289321ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:46.94964Z","caller":"traceutil/trace.go:171","msg":"trace[1500262835] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8853; }","duration":"198.664484ms","start":"2026-05-05T14:03:46.750952Z","end":"2026-05-05T14:03:46.949617Z","steps":["trace[1500262835] 'read index received' (duration: 198.505051ms)","trace[1500262835] 'applied index is now lower than readState.Index' (duration: 158.323µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:03:46.949824Z","caller":"traceutil/trace.go:171","msg":"trace[1493222812] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"200.231625ms","start":"2026-05-05T14:03:46.749561Z","end":"2026-05-05T14:03:46.949793Z","steps":["trace[1493222812] 'process raft request' (duration: 199.903548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:46.949868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.89986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-smlnq\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-05-05T14:03:46.949901Z","caller":"traceutil/trace.go:171","msg":"trace[975292471] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-smlnq; range_end:; response_count:1; response_revision:8550; }","duration":"198.986891ms","start":"2026-05-05T14:03:46.750905Z","end":"2026-05-05T14:03:46.949892Z","steps":["trace[975292471] 'agreement among raft nodes before linearized reading' (duration: 198.822678ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:46.991065Z","caller":"traceutil/trace.go:171","msg":"trace[867122561] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"225.40117ms","start":"2026-05-05T14:03:46.765639Z","end":"2026-05-05T14:03:46.99104Z","steps":["trace[867122561] 'process raft request' (duration: 218.420098ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:46.991344Z","caller":"traceutil/trace.go:171","msg":"trace[1275318850] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"109.126693ms","start":"2026-05-05T14:03:46.882199Z","end":"2026-05-05T14:03:46.991326Z","steps":["trace[1275318850] 'process raft request' (duration: 108.992111ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:46.991357Z","caller":"traceutil/trace.go:171","msg":"trace[771256248] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"188.262508ms","start":"2026-05-05T14:03:46.803055Z","end":"2026-05-05T14:03:46.991318Z","steps":["trace[771256248] 'process raft request' (duration: 187.908701ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:47.5249Z","caller":"traceutil/trace.go:171","msg":"trace[213748840] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"115.682326ms","start":"2026-05-05T14:03:47.409188Z","end":"2026-05-05T14:03:47.524871Z","steps":["trace[213748840] 'process raft request' (duration: 72.951089ms)","trace[213748840] 'compare' (duration: 42.502613ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:03:47.848048Z","caller":"traceutil/trace.go:171","msg":"trace[1724627258] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"110.447157ms","start":"2026-05-05T14:03:47.737557Z","end":"2026-05-05T14:03:47.848004Z","steps":["trace[1724627258] 'process raft request' (duration: 110.239944ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:04:34.852527Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002479.wal"} {"level":"info","ts":"2026-05-05T14:05:51.422475Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a071a454a8148542","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T14:05:51.42892Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T14:05:51.429013Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T14:08:12.814673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8280} {"level":"info","ts":"2026-05-05T14:08:12.892526Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8280,"took":"75.077008ms","hash":2158197188,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":26304512,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T14:08:12.892604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2158197188,"revision":8280,"compact-revision":6111} {"level":"info","ts":"2026-05-05T14:13:12.821102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10928} {"level":"info","ts":"2026-05-05T14:13:12.904554Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10928,"took":"80.53648ms","hash":4273298378,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":28012544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T14:13:12.90461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4273298378,"revision":10928,"compact-revision":8280} {"level":"info","ts":"2026-05-05T14:18:12.829364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12970} {"level":"info","ts":"2026-05-05T14:18:12.909454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12970,"took":"76.972485ms","hash":3557663956,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":23924736,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-05T14:18:12.909522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3557663956,"revision":12970,"compact-revision":10928}